smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 5000 (0x1388) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 26 (0x1A) smb_vwv[10]= 92 (0x5C) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_bcc=55 [2013/07/02 15:04:26.474553, 3] ../source3/smbd/process.c:1398(switch_message) switch message SMBtrans (pid 18848) conn 0x7f8f97742e00 [2013/07/02 15:04:26.474580, 4] ../source3/smbd/uid.c:384(change_to_user) Skipping user change - already user [2013/07/02 15:04:26.474609, 3] ../source3/smbd/ipc.c:589(handle_trans) trans <\PIPE\LANMAN> data=0 params=26 setup=0 [2013/07/02 15:04:26.474638, 5] ../source3/smbd/ipc.c:622(handle_trans) calling named_pipe [2013/07/02 15:04:26.474661, 3] ../source3/smbd/ipc.c:540(named_pipe) named pipe command on name [2013/07/02 15:04:26.474686, 3] ../source3/smbd/lanman.c:5853(api_reply) Got API command 104 of form (tdscnt=0,tpscnt=26,mdrcnt=4374,mprcnt=8) [2013/07/02 15:04:26.474711, 3] ../source3/smbd/lanman.c:5857(api_reply) Doing NetServerEnum2 [2013/07/02 15:04:26.474737, 4] ../source3/smbd/lanman.c:1495(api_RNetServerEnum2) server request level: B16BBDz 80000000 domains_req:Yes local_only:No [2013/07/02 15:04:26.474764, 4] ../source3/smbd/lanman.c:1508(api_RNetServerEnum2) domain [CORP] [2013/07/02 15:04:26.474797, 4] ../source3/smbd/lanman.c:1232(get_session_info) Can't open /usr/local/samba/var/cache/browse.dat - No such file or directory [2013/07/02 15:04:26.474833, 3] ../source3/smbd/lanman.c:1585(api_RNetServerEnum2) NetServerEnum2 domain = CORP uLevel=1 counted=0 total=0 [2013/07/02 15:04:26.474860, 5] ../source3/smbd/ipc.c:62(copy_trans_params_and_data) copy_trans_params_and_data: params[0..8] data[0..0] (align 0) [2013/07/02 15:04:26.474885, 5] ../source3/lib/util.c:168(show_msg) [2013/07/02 15:04:26.474899, 5] ../source3/lib/util.c:178(show_msg) size=64 smb_com=0x25 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51207 smb_tid=5556 smb_pid=976 smb_uid=53392 smb_mid=320 smt_wct=10 smb_vwv[ 0]= 8 (0x8) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 8 (0x8) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 64 (0x40) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=9 [2013/07/02 15:04:38.010426, 6] ../source3/smbd/process.c:1793(process_smb) [2013/07/02 15:04:38.010446, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) got message type 0x0 of len 0x23 setting sec ctx (65534, 3000013) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.010495, 3] ../source3/smbd/process.c:1795(process_smb) Transaction 6 of length 39 (0 toread) [2013/07/02 15:04:38.010511, 5] ../libcli/security/security_token.c:63(security_token_debug) Security token SIDs (3): [2013/07/02 15:04:38.010524, 5] ../source3/lib/util.c:168(show_msg) SID[ 0]: S-1-5-7 [2013/07/02 15:04:38.010543, 5] ../source3/lib/util.c:178(show_msg) SID[ 1]: S-1-1-0 size=35 SID[ 2]: S-1-5-2 smb_com=0x71 Privileges (0x 0): smb_rcls=0 smb_reh=0 Rights (0x 0): smb_err=0 smb_flg=24 [2013/07/02 15:04:38.010594, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) smb_flg2=51207 UNIX token of user 65534 smb_tid=5556 Primary group is 3000013 and contains 2 supplementary groups smb_pid=65279 smb_uid=53392 Group[ 0]: 3000013 smb_mid=384 Group[ 1]: 3000014 smt_wct=0 smb_bcc=0 [2013/07/02 15:04:38.010660, 5] ../source3/smbd/uid.c:363(change_to_user_internal) [2013/07/02 15:04:38.010670, 3] ../source3/smbd/process.c:1398(switch_message) Impersonated user: uid=(65534,65534), gid=(0,3000013) switch message SMBtdis (pid 18848) conn 0x7f8f97742e00 [2013/07/02 15:04:38.010693, 4] ../source3/smbd/vfs.c:838(vfs_ChDir) [2013/07/02 15:04:38.010700, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) vfs_ChDir to /tmp setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.010726, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.010751, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 [2013/07/02 15:04:38.010764, 4] ../source3/smbd/vfs.c:849(vfs_ChDir) Primary group is 0 and contains 0 supplementary groups vfs_ChDir got /tmp [2013/07/02 15:04:38.010793, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) [2013/07/02 15:04:38.010795, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.010819, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.010830, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 1 for /usr/local/samba/var/lock/smbXsrv_tcon_global.tdb [2013/07/02 15:04:38.010843, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.010883, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.010939, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) [2013/07/02 15:04:38.010948, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 1 for /usr/local/samba/var/lock/smbXsrv_tcon_global.tdb release lock order 1 for /usr/local/samba/var/lock/smbXsrv_tcon_global.tdb [2013/07/02 15:04:38.010991, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.011018, 5] ../libcli/security/security_token.c:53(security_token_debug) [2013/07/02 15:04:38.011016, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) Security token: (NULL) release lock order 1 for /usr/local/samba/var/lock/smbXsrv_tcon_global.tdb [2013/07/02 15:04:38.011042, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 [2013/07/02 15:04:38.011055, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) Primary group is 0 and contains 0 supplementary groups setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.011080, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) [2013/07/02 15:04:38.011082, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.011106, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) [2013/07/02 15:04:38.011107, 3] ../source3/smbd/service.c:1122(close_cnum) UNIX token of user 0 trams-server (ipv4:192.168.0.164:57926) closed connection to service IPC$ Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.011146, 4] ../source3/smbd/vfs.c:838(vfs_ChDir) vfs_ChDir to / [2013/07/02 15:04:38.011156, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.011187, 3] ../source3/smbd/service.c:1122(close_cnum) [2013/07/02 15:04:38.011190, 4] ../source3/smbd/vfs.c:849(vfs_ChDir) vfs_ChDir got / trams-server (ipv4:192.168.0.164:57925) closed connection to service IPC$ [2013/07/02 15:04:38.011222, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) [2013/07/02 15:04:38.011223, 4] ../source3/smbd/vfs.c:838(vfs_ChDir) vfs_ChDir to / setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.011249, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.011261, 4] ../source3/smbd/vfs.c:849(vfs_ChDir) vfs_ChDir got / [2013/07/02 15:04:38.011272, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 [2013/07/02 15:04:38.011291, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) Primary group is 0 and contains 0 supplementary groups setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.011310, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) [2013/07/02 15:04:38.011317, 5] ../libcli/security/security_token.c:53(security_token_debug) change_to_root_user: now uid=(0,0) gid=(0,0) Security token: (NULL) [2013/07/02 15:04:38.011341, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.011377, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.011569, 5] ../source3/lib/util.c:168(show_msg) [2013/07/02 15:04:38.011592, 5] ../source3/lib/util.c:178(show_msg) size=35 smb_com=0x71 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51207 smb_tid=5556 smb_pid=65279 smb_uid=53392 smb_mid=384 smt_wct=0 smb_bcc=0 [2013/07/02 15:04:38.011761, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.011798, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.011823, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.011860, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.011888, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb [2013/07/02 15:04:38.011950, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb [2013/07/02 15:04:38.012050, 6] ../source3/smbd/process.c:1793(process_smb) got message type 0x0 of len 0x27 [2013/07/02 15:04:38.012117, 3] ../source3/smbd/process.c:1795(process_smb) [2013/07/02 15:04:38.012123, 5] ../source4/lib/messaging/messaging.c:554(imessaging_cleanup) Transaction 7 of length 43 (0 toread) imessaging: cleaning up /usr/local/samba/private/smbd.tmp/msg/msg.18847.1 [2013/07/02 15:04:38.012148, 5] ../source3/lib/util.c:168(show_msg) [2013/07/02 15:04:38.012164, 5] ../source3/lib/util.c:178(show_msg) size=39 smb_com=0x74 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=53392 smb_mid=448 smt_wct=2 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_bcc=0 [2013/07/02 15:04:38.012318, 3] ../source3/smbd/process.c:1398(switch_message) switch message SMBulogoffX (pid 18848) conn 0x0 [2013/07/02 15:04:38.012363, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.012400, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.012435, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.012492, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.012548, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb [2013/07/02 15:04:38.012570, 2] ../source3/smbd/smb2_server.c:3250(smbd_smb2_request_incoming) smbd_smb2_request_incoming: client read error NT_STATUS_CONNECTION_DISCONNECTED [2013/07/02 15:04:38.012610, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb [2013/07/02 15:04:38.012637, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.012665, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.012689, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.012718, 5] ../source4/lib/messaging/messaging.c:554(imessaging_cleanup) [2013/07/02 15:04:38.012727, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) imessaging: cleaning up /usr/local/samba/private/smbd.tmp/msg/msg.18848.1 change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.012757, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.012782, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.012806, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.012842, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.012875, 5] ../source3/lib/messages.c:340(messaging_deregister) Deregistering messaging pointer for type 1536 - private_data=0x7f8f9798d570 [2013/07/02 15:04:38.012998, 3] ../source3/smbd/server_exit.c:212(exit_server_common) Server exit (NT_STATUS_CONNECTION_DISCONNECTED) [2013/07/02 15:04:38.013076, 3] ../source3/smbd/reply.c:2280(reply_ulogoffX) ulogoffX vuid=53392 [2013/07/02 15:04:38.013119, 5] ../source3/lib/util.c:168(show_msg) [2013/07/02 15:04:38.013134, 5] ../source3/lib/util.c:178(show_msg) size=39 smb_com=0x74 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=53392 smb_mid=448 smt_wct=2 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_bcc=0 [2013/07/02 15:04:38.013928, 5] ../source3/lib/util_sock.c:137(read_fd_with_timeout) read_fd_with_timeout: blocking read. EOF from client. [2013/07/02 15:04:38.013965, 5] ../source3/smbd/process.c:480(receive_smb_talloc) receive_smb_raw_talloc failed for client ipv4:192.168.0.164:57926 read error = NT_STATUS_END_OF_FILE. [2013/07/02 15:04:38.014012, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.014038, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.014062, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.014101, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.014134, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:38.014159, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:38.014183, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:38.014218, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:38.014250, 5] ../source3/lib/messages.c:340(messaging_deregister) Deregistering messaging pointer for type 1536 - private_data=0x7f8f97c42640 [2013/07/02 15:04:38.014354, 3] ../source3/smbd/server_exit.c:212(exit_server_common) Server exit (failed to receive smb request) [2013/07/02 15:04:38.016939, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:04:38.017070, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:04:38.017161, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:04:38.017204, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:04:52.407240, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:04:52.407387, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:04:52.407476, 5] ../lib/util/util_net.c:848(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 5 TCP_KEEPIDLE = 3600 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 663750 SO_RCVBUF = 262030 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2013/07/02 15:04:52.407678, 5] ../lib/util/util_net.c:848(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 5 TCP_KEEPIDLE = 3600 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 663750 SO_RCVBUF = 262030 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2013/07/02 15:04:52.407995, 6] ../source3/param/loadparm.c:2654(lp_file_list_changed) lp_file_list_changed() file /usr/local/samba/etc/smb.conf -> /usr/local/samba/etc/smb.conf last mod_time: Sat Jun 29 19:24:34 2013 [2013/07/02 15:04:52.408131, 3] ../source3/lib/access.c:338(allow_access) Allowed connection from 192.168.0.2 (192.168.0.2) [2013/07/02 15:04:52.408272, 3] ../source3/smbd/oplock.c:868(init_oplocks) init_oplocks: initializing messages. [2013/07/02 15:04:52.408302, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 774 - private_data=0x7f8f97b72c70 [2013/07/02 15:04:52.408330, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 776 - private_data=0x7f8f97b72c70 [2013/07/02 15:04:52.408355, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 778 - private_data=0x7f8f97b72c70 [2013/07/02 15:04:52.408380, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 770 - private_data=0x7f8f97b72c70 [2013/07/02 15:04:52.408405, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 787 - private_data=0x7f8f97b72c70 [2013/07/02 15:04:52.408430, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 779 - private_data=0x7f8f97b72c70 [2013/07/02 15:04:52.408458, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 15 - private_data=(nil) [2013/07/02 15:04:52.408483, 5] ../source3/lib/messages.c:308(messaging_register) Overriding messaging pointer for type 15 - private_data=(nil) [2013/07/02 15:04:52.408513, 5] ../source3/lib/messages.c:340(messaging_deregister) Deregistering messaging pointer for type 16 - private_data=(nil) [2013/07/02 15:04:52.408540, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 16 - private_data=0x7f8f97b72c70 [2013/07/02 15:04:52.408568, 5] ../source3/lib/messages.c:340(messaging_deregister) Deregistering messaging pointer for type 33 - private_data=0x7f8f97193ac0 [2013/07/02 15:04:52.408593, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 33 - private_data=0x7f8f97b72c70 [2013/07/02 15:04:52.408619, 5] ../source3/lib/messages.c:340(messaging_deregister) Deregistering messaging pointer for type 1 - private_data=(nil) [2013/07/02 15:04:52.408643, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 1 - private_data=(nil) [2013/07/02 15:04:52.408786, 6] ../source3/smbd/process.c:1793(process_smb) got message type 0x0 of len 0xbe [2013/07/02 15:04:52.408824, 3] ../source3/smbd/process.c:1795(process_smb) Transaction 0 of length 194 (0 toread) [2013/07/02 15:04:52.408854, 5] ../source3/lib/util.c:168(show_msg) [2013/07/02 15:04:52.408870, 5] ../source3/lib/util.c:178(show_msg) size=190 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51267 smb_tid=0 smb_pid=65534 smb_uid=0 smb_mid=1 smt_wct=0 smb_bcc=155 [2013/07/02 15:04:52.409019, 3] ../source3/smbd/process.c:1398(switch_message) switch message SMBnegprot (pid 18858) conn 0x0 [2013/07/02 15:04:52.409056, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:52.409086, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:52.409116, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:52.409165, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:52.410042, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [PC NETWORK PROGRAM 1.0] [2013/07/02 15:04:52.410092, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [MICROSOFT NETWORKS 1.03] [2013/07/02 15:04:52.410125, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [MICROSOFT NETWORKS 3.0] [2013/07/02 15:04:52.410153, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [LANMAN1.0] [2013/07/02 15:04:52.410180, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [LM1.2X002] [2013/07/02 15:04:52.410209, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [DOS LANMAN2.1] [2013/07/02 15:04:52.410237, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [LANMAN2.1] [2013/07/02 15:04:52.410263, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [Samba] [2013/07/02 15:04:52.410289, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [NT LANMAN 1.0] [2013/07/02 15:04:52.410316, 3] ../source3/smbd/negprot.c:562(reply_negprot) Requested protocol [NT LM 0.12] [2013/07/02 15:04:52.410367, 6] ../source3/param/loadparm.c:2654(lp_file_list_changed) lp_file_list_changed() file /usr/local/samba/etc/smb.conf -> /usr/local/samba/etc/smb.conf last mod_time: Sat Jun 29 19:24:34 2013 [2013/07/02 15:04:52.410430, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:04:52.410468, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:04:52.410520, 6] ../source3/param/loadparm.c:2654(lp_file_list_changed) lp_file_list_changed() file /usr/local/samba/etc/smb.conf -> /usr/local/samba/etc/smb.conf last mod_time: Sat Jun 29 19:24:34 2013 [2013/07/02 15:04:52.410646, 5] ../source3/lib/messages.c:293(messaging_register) Registering messaging pointer for type 1536 - private_data=0x7f8f97c42a10 [2013/07/02 15:04:52.410738, 5] ../source3/auth/auth.c:476(make_auth_context_subsystem) Making default auth method list for server role = 'active directory domain controller' [2013/07/02 15:04:52.410784, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend sam [2013/07/02 15:04:52.410816, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'sam' [2013/07/02 15:04:52.410841, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend sam_ignoredomain [2013/07/02 15:04:52.410870, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'sam_ignoredomain' [2013/07/02 15:04:52.410896, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend unix [2013/07/02 15:04:52.410935, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'unix' [2013/07/02 15:04:52.410962, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend winbind [2013/07/02 15:04:52.410988, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'winbind' [2013/07/02 15:04:52.411012, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend wbc [2013/07/02 15:04:52.411036, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'wbc' [2013/07/02 15:04:52.411061, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend trustdomain [2013/07/02 15:04:52.411087, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'trustdomain' [2013/07/02 15:04:52.411110, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend ntdomain [2013/07/02 15:04:52.411137, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'ntdomain' [2013/07/02 15:04:52.411164, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend guest [2013/07/02 15:04:52.411191, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'guest' [2013/07/02 15:04:52.411217, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend samba4 [2013/07/02 15:04:52.411244, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'samba4' [2013/07/02 15:04:52.411268, 5] ../source3/auth/auth.c:351(load_auth_module) load_auth_module: Attempting to find an auth method to match samba4 [2013/07/02 15:04:52.412517, 3] ../auth/gensec/gensec_start.c:870(gensec_register) GENSEC backend 'gssapi_spnego' registered [2013/07/02 15:04:52.412563, 3] ../auth/gensec/gensec_start.c:870(gensec_register) GENSEC backend 'gssapi_krb5' registered [2013/07/02 15:04:52.412591, 3] ../auth/gensec/gensec_start.c:870(gensec_register) GENSEC backend 'gssapi_krb5_sasl' registered [2013/07/02 15:04:52.412619, 3] ../auth/gensec/gensec_start.c:870(gensec_register) GENSEC backend 'schannel' registered [2013/07/02 15:04:52.412651, 3] ../auth/gensec/gensec_start.c:870(gensec_register) GENSEC backend 'spnego' registered [2013/07/02 15:04:52.412677, 3] ../auth/gensec/gensec_start.c:870(gensec_register) GENSEC backend 'ntlmssp' registered [2013/07/02 15:04:52.412707, 3] ../auth/gensec/gensec_start.c:870(gensec_register) GENSEC backend 'krb5' registered [2013/07/02 15:04:52.412733, 3] ../auth/gensec/gensec_start.c:870(gensec_register) GENSEC backend 'fake_gssapi_krb5' registered [2013/07/02 15:04:52.412762, 5] ../source3/auth/auth.c:376(load_auth_module) load_auth_module: auth method samba4 has a valid init [2013/07/02 15:04:52.413517, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f9753b590 [2013/07/02 15:04:52.413567, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f97546f10 [2013/07/02 15:04:52.413604, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f9753b590 "ltdb_callback" [2013/07/02 15:04:52.413668, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f97546f10 "ltdb_timeout" [2013/07/02 15:04:52.413707, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f9753b590 "ltdb_callback" [2013/07/02 15:04:52.413836, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f979719a0 [2013/07/02 15:04:52.413874, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f975fb610 [2013/07/02 15:04:52.413910, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f979719a0 "ltdb_callback" [2013/07/02 15:04:52.413948, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f975fb610 "ltdb_timeout" [2013/07/02 15:04:52.413982, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f979719a0 "ltdb_callback" [2013/07/02 15:04:52.414024, 3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect) ldb_wrap open of secrets.ldb [2013/07/02 15:04:52.414103, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f98338050 [2013/07/02 15:04:52.414143, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f97e66c10 [2013/07/02 15:04:52.414178, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f98338050 "ltdb_callback" [2013/07/02 15:04:52.414289, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f97e66c10 "ltdb_timeout" [2013/07/02 15:04:52.414330, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f98338050 "ltdb_callback" [2013/07/02 15:04:52.415285, 3] ../source4/auth/ntlm/auth.c:673(auth_register) AUTH backend 'sam' registered [2013/07/02 15:04:52.415303, 3] ../source4/auth/ntlm/auth.c:673(auth_register) AUTH backend 'sam_ignoredomain' registered [2013/07/02 15:04:52.415316, 3] ../source4/auth/ntlm/auth.c:673(auth_register) AUTH backend 'anonymous' registered [2013/07/02 15:04:52.415328, 3] ../source4/auth/ntlm/auth.c:673(auth_register) AUTH backend 'winbind' registered [2013/07/02 15:04:52.415339, 3] ../source4/auth/ntlm/auth.c:673(auth_register) AUTH backend 'winbind_wbclient' registered [2013/07/02 15:04:52.415350, 3] ../source4/auth/ntlm/auth.c:673(auth_register) AUTH backend 'name_to_ntstatus' registered [2013/07/02 15:04:52.415361, 3] ../source4/auth/ntlm/auth.c:673(auth_register) AUTH backend 'unix' registered [2013/07/02 15:04:52.417105, 5] ../auth/gensec/gensec_start.c:649(gensec_start_mech) Starting GENSEC mechanism spnego [2013/07/02 15:04:52.417155, 5] ../auth/gensec/gensec_start.c:649(gensec_start_mech) Starting GENSEC submechanism gssapi_krb5 [2013/07/02 15:04:52.417656, 5] ../source4/lib/messaging/messaging.c:554(imessaging_cleanup) imessaging: cleaning up /usr/local/samba/private/smbd.tmp/msg/msg.18858.1 [2013/07/02 15:04:52.417778, 3] ../source3/smbd/negprot.c:384(reply_nt1) using SPNEGO [2013/07/02 15:04:52.417804, 3] ../source3/smbd/negprot.c:668(reply_negprot) Selected protocol NT LANMAN 1.0 [2013/07/02 15:04:52.417816, 5] ../source3/smbd/negprot.c:675(reply_negprot) negprot index=8 [2013/07/02 15:04:52.417836, 5] ../source3/lib/util.c:168(show_msg) [2013/07/02 15:04:52.417843, 5] ../source3/lib/util.c:178(show_msg) size=181 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51267 smb_tid=0 smb_pid=65534 smb_uid=0 smb_mid=1 smt_wct=17 smb_vwv[ 0]= 8 (0x8) smb_vwv[ 1]=12803 (0x3203) smb_vwv[ 2]= 256 (0x100) smb_vwv[ 3]= 1024 (0x400) smb_vwv[ 4]= 65 (0x41) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 256 (0x100) smb_vwv[ 7]=43520 (0xAA00) smb_vwv[ 8]= 73 (0x49) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]=17024 (0x4280) smb_vwv[12]=64757 (0xFCF5) smb_vwv[13]=24425 (0x5F69) smb_vwv[14]=52855 (0xCE77) smb_vwv[15]=11265 (0x2C01) smb_vwv[16]= 1 (0x1) smb_bcc=112 [2013/07/02 15:04:52.454113, 6] ../source3/smbd/process.c:1793(process_smb) got message type 0x0 of len 0xaec [2013/07/02 15:04:52.454175, 3] ../source3/smbd/process.c:1795(process_smb) Transaction 1 of length 2800 (0 toread) [2013/07/02 15:04:52.454203, 5] ../source3/lib/util.c:168(show_msg) [2013/07/02 15:04:52.454218, 5] ../source3/lib/util.c:178(show_msg) size=2796 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51203 smb_tid=0 smb_pid=10735 smb_uid=0 smb_mid=2 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=12288 (0x3000) smb_vwv[ 3]= 50 (0x32) smb_vwv[ 4]= 1 (0x1) smb_vwv[ 5]=18858 (0x49AA) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 2661 (0xA65) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]=62461 (0xF3FD) smb_vwv[11]=32896 (0x8080) smb_bcc=2737 [2013/07/02 15:04:52.454447, 3] ../source3/smbd/process.c:1398(switch_message) switch message SMBsesssetupX (pid 18858) conn 0x0 [2013/07/02 15:04:52.454475, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:52.454500, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:52.454524, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:52.454564, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2013/07/02 15:04:52.454596, 3] ../source3/smbd/sesssetup.c:601(reply_sesssetup_and_X) wct=12 flg2=0xc803 [2013/07/02 15:04:52.454637, 3] ../source3/smbd/sesssetup.c:138(reply_sesssetup_and_X_spnego) Doing spnego session setup [2013/07/02 15:04:52.454678, 3] ../source3/smbd/sesssetup.c:179(reply_sesssetup_and_X_spnego) NativeOS=[Unix] NativeLanMan=[Samba 4.1.0pre1-GIT-af41eb6] PrimaryDomain=[CORP] [2013/07/02 15:04:52.454721, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb [2013/07/02 15:04:52.454996, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb [2013/07/02 15:04:52.455036, 5] ../source3/auth/auth.c:476(make_auth_context_subsystem) Making default auth method list for server role = 'active directory domain controller' [2013/07/02 15:04:52.455067, 5] ../source3/auth/auth.c:351(load_auth_module) load_auth_module: Attempting to find an auth method to match samba4 [2013/07/02 15:04:52.455094, 5] ../source3/auth/auth.c:376(load_auth_module) load_auth_module: auth method samba4 has a valid init [2013/07/02 15:04:52.455447, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f983ba480 [2013/07/02 15:04:52.455494, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f9846fce0 [2013/07/02 15:04:52.455530, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f983ba480 "ltdb_callback" [2013/07/02 15:04:52.455587, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f9846fce0 "ltdb_timeout" [2013/07/02 15:04:52.455625, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f983ba480 "ltdb_callback" [2013/07/02 15:04:52.455700, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f9767ecb0 [2013/07/02 15:04:52.455738, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f976a2200 [2013/07/02 15:04:52.455773, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f9767ecb0 "ltdb_callback" [2013/07/02 15:04:52.455811, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f976a2200 "ltdb_timeout" [2013/07/02 15:04:52.455846, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f9767ecb0 "ltdb_callback" [2013/07/02 15:04:52.455882, 3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect) ldb_wrap open of secrets.ldb [2013/07/02 15:04:52.455930, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f975277a0 [2013/07/02 15:04:52.455966, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f98001470 [2013/07/02 15:04:52.456000, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f975277a0 "ltdb_callback" [2013/07/02 15:04:52.456109, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f98001470 "ltdb_timeout" [2013/07/02 15:04:52.456150, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f975277a0 "ltdb_callback" [2013/07/02 15:04:52.459837, 5] ../auth/gensec/gensec_start.c:649(gensec_start_mech) Starting GENSEC mechanism spnego [2013/07/02 15:04:52.459891, 4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2013/07/02 15:04:52.459923, 4] ../source3/smbd/uid.c:485(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2013/07/02 15:04:52.459948, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2013/07/02 15:04:52.459973, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2013/07/02 15:04:52.459997, 5] ../source3/auth/token_util.c:528(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2013/07/02 15:04:52.460160, 5] ../auth/gensec/gensec_start.c:649(gensec_start_mech) Starting GENSEC submechanism gssapi_krb5 [2013/07/02 15:04:52.462932, 5] ../source4/auth/gensec/gensec_gssapi.c:523(gensec_gssapi_update) gensec_gssapi: credentials were delegated [2013/07/02 15:04:52.462982, 5] ../source4/auth/gensec/gensec_gssapi.c:544(gensec_gssapi_update) GSSAPI Connection will have no cryptographic protection [2013/07/02 15:04:52.463051, 4] ../source3/smbd/sec_ctx.c:424(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2013/07/02 15:04:52.469812, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f977d2c40 [2013/07/02 15:04:52.469866, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f97dda8a0 [2013/07/02 15:04:52.469905, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f977d2c40 "ltdb_callback" [2013/07/02 15:04:52.469954, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f97dda8a0 "ltdb_timeout" [2013/07/02 15:04:52.469991, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f977d2c40 "ltdb_callback" [2013/07/02 15:04:52.470026, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: no modules required by the db [2013/07/02 15:04:52.470052, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: No modules specified for this database [2013/07/02 15:04:52.470088, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f979f42d0 [2013/07/02 15:04:52.470123, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f97a17110 [2013/07/02 15:04:52.470157, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f979f42d0 "ltdb_callback" [2013/07/02 15:04:52.470194, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f97a17110 "ltdb_timeout" [2013/07/02 15:04:52.470228, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f979f42d0 "ltdb_callback" [2013/07/02 15:04:52.470263, 3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect) ldb_wrap open of privilege.ldb [2013/07/02 15:04:52.470358, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f981b3640 [2013/07/02 15:04:52.470398, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f975cf770 [2013/07/02 15:04:52.470436, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f981b3640 "ltdb_callback" [2013/07/02 15:04:52.470491, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f975cf770 "ltdb_timeout" [2013/07/02 15:04:52.470528, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f981b3640 "ltdb_callback" [2013/07/02 15:04:52.470564, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\05\00\00\00\00\00\05\15\00\00\00\FA\A8gZ\992\8B\98\BBK\1Ct\E8\03\00\00 -> 0 [2013/07/02 15:04:52.470618, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f976a9ee0 [2013/07/02 15:04:52.470654, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f9765d840 [2013/07/02 15:04:52.470688, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f976a9ee0 "ltdb_callback" [2013/07/02 15:04:52.470739, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f9765d840 "ltdb_timeout" [2013/07/02 15:04:52.470775, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f976a9ee0 "ltdb_callback" [2013/07/02 15:04:52.470809, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\05\00\00\00\00\00\05\15\00\00\00\FA\A8gZ\992\8B\98\BBK\1Ct\04\02\00\00 -> 0 [2013/07/02 15:04:52.470859, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f984586e0 [2013/07/02 15:04:52.470894, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f9765d840 [2013/07/02 15:04:52.470940, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f984586e0 "ltdb_callback" [2013/07/02 15:04:52.470991, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f9765d840 "ltdb_timeout" [2013/07/02 15:04:52.471027, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f984586e0 "ltdb_callback" [2013/07/02 15:04:52.471060, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\05\00\00\00\00\00\05\15\00\00\00\FA\A8gZ\992\8B\98\BBK\1Ct<\02\00\00 -> 0 [2013/07/02 15:04:52.471106, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f9765d840 [2013/07/02 15:04:52.471142, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f97369370 [2013/07/02 15:04:52.471175, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f9765d840 "ltdb_callback" [2013/07/02 15:04:52.471224, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f97369370 "ltdb_timeout" [2013/07/02 15:04:52.471260, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f9765d840 "ltdb_callback" [2013/07/02 15:04:52.471294, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\01\00\00\00\00\00\05\09\00\00\00 -> 0 [2013/07/02 15:04:52.471338, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f97369370 [2013/07/02 15:04:52.471374, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f9765d840 [2013/07/02 15:04:52.471407, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f97369370 "ltdb_callback" [2013/07/02 15:04:52.471458, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f9765d840 "ltdb_timeout" [2013/07/02 15:04:52.471494, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f97369370 "ltdb_callback" [2013/07/02 15:04:52.471528, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\01\00\00\00\00\00\01\00\00\00\00 -> 0 [2013/07/02 15:04:52.471574, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f9765d840 [2013/07/02 15:04:52.471609, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f97369370 [2013/07/02 15:04:52.471643, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f9765d840 "ltdb_callback" [2013/07/02 15:04:52.471691, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f97369370 "ltdb_timeout" [2013/07/02 15:04:52.471727, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f9765d840 "ltdb_callback" [2013/07/02 15:04:52.471760, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\01\00\00\00\00\00\05\02\00\00\00 -> 0 [2013/07/02 15:04:52.471802, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f97369370 [2013/07/02 15:04:52.471838, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f9765d840 [2013/07/02 15:04:52.471871, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f97369370 "ltdb_callback" [2013/07/02 15:04:52.471919, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f9765d840 "ltdb_timeout" [2013/07/02 15:04:52.471955, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f97369370 "ltdb_callback" [2013/07/02 15:04:52.471988, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\01\00\00\00\00\00\05\0B\00\00\00 -> 0 [2013/07/02 15:04:52.472033, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f976a9ee0 [2013/07/02 15:04:52.472068, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f9765d840 [2013/07/02 15:04:52.472102, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f976a9ee0 "ltdb_callback" [2013/07/02 15:04:52.472150, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f9765d840 "ltdb_timeout" [2013/07/02 15:04:52.472186, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f976a9ee0 "ltdb_callback" [2013/07/02 15:04:52.472219, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\02\00\00\00\00\00\05\20\00\00\000\02\00\00 -> 0 [2013/07/02 15:04:52.472265, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f981b3640 [2013/07/02 15:04:52.472300, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f9765d840 [2013/07/02 15:04:52.472333, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f981b3640 "ltdb_callback" [2013/07/02 15:04:52.472412, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f9765d840 "ltdb_timeout" [2013/07/02 15:04:52.472451, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f981b3640 "ltdb_callback" [2013/07/02 15:04:52.472484, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\02\00\00\00\00\00\05\20\00\00\00\2A\02\00\00 -> 1 [2013/07/02 15:04:52.472559, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_callback": 0x7f8f981b3640 [2013/07/02 15:04:52.472598, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Added timed event "ltdb_timeout": 0x7f8f984586e0 [2013/07/02 15:04:52.472632, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Running timer event 0x7f8f981b3640 "ltdb_callback" [2013/07/02 15:04:52.472683, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Destroying timer event 0x7f8f984586e0 "ltdb_timeout" [2013/07/02 15:04:52.472719, 5] ../lib/ldb-samba/ldb_wrap.c:69(ldb_wrap_debug) ldb: Ending timer event 0x7f8f981b3640 "ltdb_callback" [2013/07/02 15:04:52.472755, 6] ../lib/util/util_ldb.c:60(gendb_search_v) gendb_search_v: NULL objectSid=\01\02\00\00\00\00\00\05\20\00\00\00\21\02\00\00 -> 0 [2013/07/02 15:04:52.472954, 5] ../source4/libcli/wbclient/wbclient.c:72(wbc_sids_to_xids_send) wbc_sids_to_xids called [2013/07/02 15:04:52.474323, 5] ../source4/libcli/wbclient/wbclient.c:118(wbc_sids_to_xids_recv) wbc_sids_to_xids_recv called [2013/07/02 15:04:52.474381, 5] ../source4/auth/unix_token.c:118(security_token_to_unix_token) Successfully converted security token to a unix token:Security token SIDs (10): SID[ 0]: S-1-5-21-1516742906-2559259289-1948011451-1000 SID[ 1]: S-1-5-21-1516742906-2559259289-1948011451-516 SID[ 2]: S-1-5-21-1516742906-2559259289-1948011451-572 SID[ 3]: S-1-5-9 SID[ 4]: S-1-1-0 SID[ 5]: S-1-5-2 SID[ 6]: S-1-5-11 SID[ 7]: S-1-5-32-560 SID[ 8]: S-1-5-32-554 SID[ 9]: S-1-5-32-545 Privileges (0x 800000): Privilege[ 0]: SeChangeNotifyPrivilege Rights (0x 400): Right[ 0]: SeRemoteInteractiveLogonRight [2013/07/02 15:04:52.475179, 7] ../source3/param/loadparm.c:5164(lp_servicenumber) lp_servicenumber: couldn't find CORP\RUNWAY$ [2013/07/02 15:04:52.475221, 5] ../source3/lib/username.c:181(Get_Pwnam_alloc) Finding user CORP\RUNWAY$ [2013/07/02 15:04:52.475250, 5] ../source3/lib/username.c:120(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is corp\runway$ [2013/07/02 15:04:52.482381, 5] ../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals did find user [CORP\RUNWAY$]! [2013/07/02 15:04:52.482439, 3] ../source3/smbd/password.c:144(register_homes_share) Adding homes service for user 'CORP\RUNWAY$' using home directory: '/home/CORP/RUNWAY$' [2013/07/02 15:04:52.482539, 8] ../source3/param/loadparm.c:1616(add_a_service) add_a_service: Creating snum = 6 for RUNWAY$ [2013/07/02 15:04:52.482583, 3] ../source3/param/loadparm.c:1722(lp_add_home) adding home's share [RUNWAY$] for user 'CORP\RUNWAY$' at '/home/CORP' [2013/07/02 15:04:52.482644, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb [2013/07/02 15:04:52.482769, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb [2013/07/02 15:05:09.299981, 2] ../source3/smbd/server.c:437(remove_child_pid) Could not find child 18866 -- ignoring [2013/07/02 15:05:27.563484, 5] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) check lock order 2 for /usr/local/samba/var/lock/serverid.tdb [2013/07/02 15:05:27.563529, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /usr/local/samba/var/lock/serverid.tdb