[2011/07/20 15:27:14.788337, 10] lib/util_sock.c:731(read_smb_length_return_keepalive) got smb length of 76 [2011/07/20 15:27:14.788590, 6] smbd/process.c:1486(process_smb) got message type 0x0 of len 0x4c [2011/07/20 15:27:14.788677, 3] smbd/process.c:1489(process_smb) Transaction 21 of length 80 (0 toread) [2011/07/20 15:27:14.788772, 5] lib/util.c:617(show_msg) [2011/07/20 15:27:14.788828, 5] lib/util.c:627(show_msg) size=76 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51207 smb_tid=2 smb_pid=2948 smb_uid=100 smb_mid=1344 smt_wct=15 smb_vwv[ 0]= 8 (0x8) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]= 40 (0x28) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 8 (0x8) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=11 [2011/07/20 15:27:14.789702, 10] ../lib/util/util.c:278(_dump_data) [0000] 00 00 00 01 01 00 00 00 00 00 00 ........ ... [2011/07/20 15:27:14.789965, 3] smbd/process.c:1298(switch_message) switch message SMBtrans2 (pid 33685582) conn 0x2039e3d8 [2011/07/20 15:27:14.790083, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (18676, 19711) - sec_ctx_stack_ndx = 0 [2011/07/20 15:27:14.790194, 5] auth/token_util.c:531(debug_nt_user_token) NT user token of user S-1-5-21-501412222-2961087410-1742230919-38352 contains 6 SIDs SID[ 0]: S-1-5-21-501412222-2961087410-1742230919-38352 SID[ 1]: S-1-22-2-19711 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SID[ 5]: S-1-22-1-18676 SE_PRIV 0x0 0x0 0x0 0x0 [2011/07/20 15:27:14.790655, 5] auth/token_util.c:551(debug_unix_user_token) UNIX token of user 18676 Primary group is 19711 and contains 1 supplementary groups Group[ 0]: 19711 [2011/07/20 15:27:14.790849, 5] smbd/uid.c:354(change_to_user) change_to_user uid=(0,18676) gid=(0,19711) [2011/07/20 15:27:14.790974, 3] smbd/trans2.c:5100(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257 [2011/07/20 15:27:14.791071, 5] smbd/filename.c:169(unix_convert) unix_convert called on file "" [2011/07/20 15:27:14.793072, 5] smbd/filename.c:203(unix_convert) conversion finished "" -> . [2011/07/20 15:27:14.793251, 10] locking/locking.c:926(fetch_share_mode_unlocked) fetch_share_mode_unlocked: no share_mode record around (file not open) [2011/07/20 15:27:14.793333, 3] smbd/trans2.c:5226(call_trans2qfilepathinfo) call_trans2qfilepathinfo . (fnum = -1) level=257 call=5 total_data=0 [2011/07/20 15:27:14.793457, 5] smbd/trans2.c:4198(smbd_do_qfilepathinfo) smbd_do_qfilepathinfo: . (fnum = -1) level=257 max_data=40 [2011/07/20 15:27:14.793569, 8] smbd/dosmode.c:613(dos_mode) dos_mode: . [2011/07/20 15:27:14.793656, 8] smbd/dosmode.c:207(dos_mode_from_sbuf) dos_mode_from_sbuf returning d [2011/07/20 15:27:14.793741, 8] smbd/dosmode.c:666(dos_mode) dos_mode returning d [2011/07/20 15:27:14.793829, 10] smbd/trans2.c:4429(smbd_do_qfilepathinfo) smbd_do_qfilepathinfo: SMB_QUERY_FILE_BASIC_INFO [2011/07/20 15:27:14.793909, 5] smbd/trans2.c:4442(smbd_do_qfilepathinfo) SMB_QFBI - create: Wed Jul 20 10:44:31 2011 access: Wed Jul 20 15:23:04 2011 write: Wed Jul 20 10:44:31 2011 change: Wed Jul 20 10:44:31 2011 mode: 10 [2011/07/20 15:27:14.794113, 9] smbd/trans2.c:927(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 36, useable_space = 65473 [2011/07/20 15:27:14.794217, 9] smbd/trans2.c:929(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 36, paramsize = 2, datasize = 36 [2011/07/20 15:27:14.794326, 5] lib/util.c:617(show_msg) [2011/07/20 15:27:14.794384, 5] lib/util.c:627(show_msg) size=96 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49155 smb_tid=2 smb_pid=2948 smb_uid=100 smb_mid=1344 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 36 (0x24) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 36 (0x24) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=41 [2011/07/20 15:27:14.795162, 10] ../lib/util/util.c:278(_dump_data) [0000] 00 00 00 00 00 3E 99 72 88 EB 46 CC 01 00 1C 54 .....>.r .ëFÌ...T [0010] 72 12 47 CC 01 3E 99 72 88 EB 46 CC 01 3E 99 72 r.GÌ.>.r .ëFÌ.>.r [0020] 88 EB 46 CC 01 10 00 00 00 .ëFÌ.... . [2011/07/20 15:27:14.796819, 10] lib/util_sock.c:731(read_smb_length_return_keepalive) got smb length of 76 [2011/07/20 15:27:14.796972, 6] smbd/process.c:1486(process_smb) got message type 0x0 of len 0x4c [2011/07/20 15:27:14.797067, 3] smbd/process.c:1489(process_smb) Transaction 22 of length 80 (0 toread) [2011/07/20 15:27:14.797170, 5] lib/util.c:617(show_msg) [2011/07/20 15:27:14.797230, 5] lib/util.c:627(show_msg) size=76 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51207 smb_tid=2 smb_pid=2948 smb_uid=100 smb_mid=1408 smt_wct=15 smb_vwv[ 0]= 8 (0x8) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2 (0x2) smb_vwv[ 3]= 24 (0x18) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 8 (0x8) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 5 (0x5) smb_bcc=11 [2011/07/20 15:27:14.798199, 10] ../lib/util/util.c:278(_dump_data) [0000] 00 00 00 02 01 00 00 00 00 00 00 ........ ... [2011/07/20 15:27:14.798495, 3] smbd/process.c:1298(switch_message) switch message SMBtrans2 (pid 33685582) conn 0x2039e3d8 [2011/07/20 15:27:14.798614, 4] smbd/uid.c:257(change_to_user) change_to_user: Skipping user change - already user [2011/07/20 15:27:14.798700, 3] smbd/trans2.c:5100(call_trans2qfilepathinfo) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 258 [2011/07/20 15:27:14.798794, 5] smbd/filename.c:169(unix_convert) unix_convert called on file "" [2011/07/20 15:27:14.798902, 5] smbd/filename.c:203(unix_convert) conversion finished "" -> . [2011/07/20 15:27:14.799011, 10] locking/locking.c:926(fetch_share_mode_unlocked) fetch_share_mode_unlocked: no share_mode record around (file not open) [2011/07/20 15:27:14.799092, 3] smbd/trans2.c:5226(call_trans2qfilepathinfo) call_trans2qfilepathinfo . (fnum = -1) level=258 call=5 total_data=0 [2011/07/20 15:27:14.799210, 5] smbd/trans2.c:4198(smbd_do_qfilepathinfo) smbd_do_qfilepathinfo: . (fnum = -1) level=258 max_data=24 [2011/07/20 15:27:14.799320, 8] smbd/dosmode.c:613(dos_mode) dos_mode: . [2011/07/20 15:27:14.799405, 8] smbd/dosmode.c:207(dos_mode_from_sbuf) dos_mode_from_sbuf returning d [2011/07/20 15:27:14.799488, 8] smbd/dosmode.c:666(dos_mode) dos_mode returning d [2011/07/20 15:27:14.799590, 10] smbd/trans2.c:4453(smbd_do_qfilepathinfo) smbd_do_qfilepathinfo: SMB_FILE_STANDARD_INFORMATION [2011/07/20 15:27:14.799671, 9] smbd/trans2.c:927(send_trans2_replies) t2_rep: params_sent_thistime = 2, data_sent_thistime = 24, useable_space = 65473 [2011/07/20 15:27:14.799774, 9] smbd/trans2.c:929(send_trans2_replies) t2_rep: params_to_send = 2, data_to_send = 24, paramsize = 2, datasize = 24 [2011/07/20 15:27:14.799883, 5] lib/util.c:617(show_msg) [2011/07/20 15:27:14.799940, 5] lib/util.c:627(show_msg) size=84 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49155 smb_tid=2 smb_pid=2948 smb_uid=100 smb_mid=1408 smt_wct=10 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 24 (0x18) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 24 (0x18) smb_vwv[ 7]= 60 (0x3C) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=29 [2011/07/20 15:27:14.800673, 10] ../lib/util/util.c:278(_dump_data) [0000] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ........ ........ [0010] 00 00 00 00 00 01 00 00 00 00 01 00 00 ........ ..... [2011/07/20 15:27:14.801976, 10] lib/util_sock.c:731(read_smb_length_return_keepalive) got smb length of 70 [2011/07/20 15:27:14.802073, 6] smbd/process.c:1486(process_smb) got message type 0x0 of len 0x46 [2011/07/20 15:27:14.802167, 3] smbd/process.c:1489(process_smb) Transaction 23 of length 74 (0 toread) [2011/07/20 15:27:14.802267, 5] lib/util.c:617(show_msg) [2011/07/20 15:27:14.802327, 5] lib/util.c:627(show_msg) size=70 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51207 smb_tid=2 smb_pid=2948 smb_uid=100 smb_mid=1472 smt_wct=15 smb_vwv[ 0]= 2 (0x2) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 560 (0x230) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 0 (0x0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 2 (0x2) smb_vwv[10]= 68 (0x44) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 1 (0x1) smb_vwv[14]= 3 (0x3) smb_bcc=5 [2011/07/20 15:27:14.803278, 10] ../lib/util/util.c:278(_dump_data) [0000] 00 00 00 02 01 ..... [2011/07/20 15:27:14.803490, 3] smbd/process.c:1298(switch_message) switch message SMBtrans2 (pid 33685582) conn 0x2039e3d8 [2011/07/20 15:27:14.803599, 4] smbd/uid.c:257(change_to_user) change_to_user: Skipping user change - already user [2011/07/20 15:27:14.803681, 3] smbd/trans2.c:3464(call_trans2qfsinfo) call_trans2qfsinfo: level = 258 [2011/07/20 15:27:14.803776, 3] smbd/trans2.c:2906(smbd_do_qfsinfo) smbd_do_qfsinfo: level = 258 [2011/07/20 15:27:14.803884, 5] smbd/trans2.c:3048(smbd_do_qfsinfo) smbd_do_qfsinfo : SMB_QUERY_FS_VOLUME_INFO namelen = 11, vol=toolconnect serv=toolconnect [2011/07/20 15:27:14.803991, 9] smbd/trans2.c:927(send_trans2_replies) t2_rep: params_sent_thistime = 0, data_sent_thistime = 40, useable_space = 65475 [2011/07/20 15:27:14.804095, 9] smbd/trans2.c:929(send_trans2_replies) t2_rep: params_to_send = 0, data_to_send = 40, paramsize = 0, datasize = 40 [2011/07/20 15:27:14.804204, 5] lib/util.c:617(show_msg) [2011/07/20 15:27:14.804262, 5] lib/util.c:627(show_msg) size=96 smb_com=0x32 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=49155 smb_tid=2 smb_pid=2948 smb_uid=100 smb_mid=1472 smt_wct=10 smb_vwv[ 0]= 0 (0x0) smb_vwv[ 1]= 40 (0x28) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 40 (0x28) smb_vwv[ 7]= 56 (0x38) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=41 [2011/07/20 15:27:14.804999, 10] ../lib/util/util.c:278(_dump_data) [0000] 00 00 00 00 00 00 00 00 00 E9 B0 06 8B 16 00 00 ........ .é°..... [0010] 00 00 00 74 00 6F 00 6F 00 6C 00 63 00 6F 00 6E ...t.o.o .l.c.o.n [0020] 00 6E 00 65 00 63 00 74 00 .n.e.c.t . [2011/07/20 15:27:14.805817, 4] smbd/trans2.c:3480(call_trans2qfsinfo) SMBtrans2 info_level = 258 [2011/07/20 15:27:17.396576, 10] lib/events.c:131(run_events) Running timed event "smbd_idle_event_handler" 203770d8 [2011/07/20 15:27:17.396804, 10] smbd/process.c:683(smbd_idle_event_handler) smbd_idle_event_handler: idle_evt(keepalive) 0 called [2011/07/20 15:27:17.396962, 10] smbd/process.c:694(smbd_idle_event_handler) smbd_idle_event_handler: idle_evt(keepalive) 0 rescheduled [2011/07/20 15:27:17.397103, 10] lib/events.c:131(run_events) Running timed event "smbd_idle_event_handler" 20377238 [2011/07/20 15:27:17.397223, 10] smbd/process.c:683(smbd_idle_event_handler) smbd_idle_event_handler: idle_evt(deadtime) 0 called [2011/07/20 15:27:17.397320, 10] smbd/process.c:694(smbd_idle_event_handler) smbd_idle_event_handler: idle_evt(deadtime) 0 rescheduled [2011/07/20 15:27:17.397447, 10] lib/events.c:131(run_events) Running timed event "smbd_idle_event_handler" 20378068 [2011/07/20 15:27:17.397568, 10] smbd/process.c:683(smbd_idle_event_handler) smbd_idle_event_handler: idle_evt(housekeeping) 0 called [2011/07/20 15:27:17.397716, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2011/07/20 15:27:17.397840, 5] auth/token_util.c:525(debug_nt_user_token) NT user token: (NULL) [2011/07/20 15:27:17.397917, 5] auth/token_util.c:551(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2011/07/20 15:27:17.398090, 5] smbd/uid.c:369(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2011/07/20 15:27:17.398210, 3] smbd/process.c:2085(check_reload) Printcap cache time expired. [2011/07/20 15:27:17.398284, 3] printing/pcap.c:137(pcap_cache_reload) reloading printcap cache [2011/07/20 15:27:17.398420, 5] printing/print_aix.c:42(aix_cache_reload) reloading aix printcap cache [2011/07/20 15:27:17.398751, 3] printing/pcap.c:249(pcap_cache_reload) reload status: ok [2011/07/20 15:27:17.399042, 7] param/loadparm.c:9410(lp_servicenumber) lp_servicenumber: couldn't find printers [2011/07/20 15:27:17.399125, 10] smbd/server.c:770(reload_printers) reloading printer services from pcap cache [2011/07/20 15:27:17.399335, 7] param/loadparm.c:9410(lp_servicenumber) lp_servicenumber: couldn't find printers [2011/07/20 15:27:17.399417, 5] param/loadparm.c:6965(process_registry_service) process_registry_service: service name printers [2011/07/20 15:27:17.399503, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [printers] [2011/07/20 15:27:17.399619, 10] registry/reg_backend_db.c:451(regdb_open) regdb_open: incrementing refcount (2) [2011/07/20 15:27:17.399747, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SOFTWARE/Samba/smbconf/printers] [2011/07/20 15:27:17.399831, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SOFTWARE/Samba/smbconf/printers] [2011/07/20 15:27:17.399934, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2011/07/20 15:27:17.400006, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 202ff9d8 for key [/HKLM/SOFTWARE/Samba/smbconf/printers] [2011/07/20 15:27:17.400195, 10] registry/reg_backend_db.c:1471(regdb_fetch_keys_internal) key [HKLM\SOFTWARE\Samba\smbconf\printers] not found [2011/07/20 15:27:17.400280, 10] registry/reg_backend_db.c:485(regdb_close) regdb_close: decrementing refcount (2) [2011/07/20 15:27:17.400527, 7] param/loadparm.c:9410(lp_servicenumber) lp_servicenumber: couldn't find printers [2011/07/20 15:27:17.400612, 10] smbd/process.c:694(smbd_idle_event_handler) smbd_idle_event_handler: idle_evt(housekeeping) 0 rescheduled