[2009/09/04 11:26:52, 6] param/loadparm.c:lp_file_list_changed(6699) lp_file_list_changed() file /10/samba-3.2.6/lib/smb.conf -> /10/samba-3.2.6/lib/smb.conf last mod_time: Fri Sep 4 11:19:32 2009 [2009/09/04 11:26:52, 3] smbd/oplock.c:init_oplocks(875) init_oplocks: initializing messages. [2009/09/04 11:26:52, 3] smbd/oplock_linux.c:linux_init_kernel_oplocks(241) Linux kernel oplocks enabled [2009/09/04 11:26:52, 10] lib/events.c:event_add_timed(128) Added timed event "idle_evt(keepalive)": b8404908 [2009/09/04 11:26:52, 10] lib/events.c:event_add_timed(128) Added timed event "idle_evt(deadtime)": b8404b30 [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 10] lib/util_sock.c:read_smb_length_return_keepalive(1118) got smb length of 190 [2009/09/04 11:26:52, 3] lib/access.c:only_ipaddrs_in_list(362) only_ipaddrs_in_list: list has non-ip address (147.8.101.) [2009/09/04 11:26:52, 3] lib/access.c:check_access(396) check_access: hostnames in host allow/deny list. [2009/09/04 11:26:52, 2] lib/access.c:check_access(406) Allowed connection from localhost (127.0.0.1) [2009/09/04 11:26:52, 6] smbd/process.c:process_smb(1547) got message type 0x0 of len 0xbe [2009/09/04 11:26:52, 3] smbd/process.c:process_smb(1550) Transaction 0 of length 194 (0 toread) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=190 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=0 smb_pid=2875 smb_uid=0 smb_mid=1 smt_wct=0 smb_bcc=155 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 02 50 43 20 4E 45 54 57 4F 52 4B 20 50 52 4F 47 .PC NETW ORK PROG [010] 52 41 4D 20 31 2E 30 00 02 4D 49 43 52 4F 53 4F RAM 1.0. .MICROSO [020] 46 54 20 4E 45 54 57 4F 52 4B 53 20 31 2E 30 33 FT NETWO RKS 1.03 [030] 00 02 4D 49 43 52 4F 53 4F 46 54 20 4E 45 54 57 ..MICROS OFT NETW [040] 4F 52 4B 53 20 33 2E 30 00 02 4C 41 4E 4D 41 4E ORKS 3.0 ..LANMAN [050] 31 2E 30 00 02 4C 4D 31 2E 32 58 30 30 32 00 02 1.0..LM1 .2X002.. [060] 44 4F 53 20 4C 41 4E 4D 41 4E 32 2E 31 00 02 4C DOS LANM AN2.1..L [070] 41 4E 4D 41 4E 32 2E 31 00 02 53 61 6D 62 61 00 ANMAN2.1 ..Samba. [080] 02 4E 54 20 4C 41 4E 4D 41 4E 20 31 2E 30 00 02 .NT LANM AN 1.0.. [090] 4E 54 20 4C 4D 20 30 2E 31 32 00 NT LM 0. 12. [2009/09/04 11:26:52, 3] smbd/process.c:switch_message(1361) switch message SMBnegprot (pid 2876) conn 0x0 [2009/09/04 11:26:52, 0] smbd/process.c:smb_dump(1309) created /tmp/SMBnegprot.3.req len 194 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] smbd/uid.c:change_to_root_user(337) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 5] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [PC NETWORK PROGRAM 1.0] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [MICROSOFT NETWORKS 1.03] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [MICROSOFT NETWORKS 3.0] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [LANMAN1.0] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [LM1.2X002] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [DOS LANMAN2.1] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [LANMAN2.1] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [Samba] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [NT LANMAN 1.0] [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(568) Requested protocol [NT LM 0.12] [2009/09/04 11:26:52, 10] lib/util.c:set_remote_arch(2198) set_remote_arch: Client arch is 'Samba' [2009/09/04 11:26:52, 6] param/loadparm.c:lp_file_list_changed(6699) lp_file_list_changed() file /10/samba-3.2.6/lib/smb.conf -> /10/samba-3.2.6/lib/smb.conf last mod_time: Fri Sep 4 11:19:32 2009 [2009/09/04 11:26:52, 5] smbd/connection.c:claim_connection(142) claiming [] [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100) Locking key 3C0B0000FFFFFFFF00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129) Allocated locked data 0x0xb84064d8 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42) Unlocking key 3C0B0000FFFFFFFF00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000 [2009/09/04 11:26:52, 6] param/loadparm.c:lp_file_list_changed(6699) lp_file_list_changed() file /10/samba-3.2.6/lib/smb.conf -> /10/samba-3.2.6/lib/smb.conf last mod_time: Fri Sep 4 11:19:32 2009 [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_nt1(392) using SPNEGO [2009/09/04 11:26:52, 3] smbd/negprot.c:reply_negprot(673) Selected protocol NT LANMAN 1.0 [2009/09/04 11:26:52, 5] smbd/negprot.c:reply_negprot(680) negprot index=8 [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=127 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=2875 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]=15360 (0x3C00) smb_vwv[ 8]= 11 (0xB) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]= 128 (0x80) smb_vwv[12]=26278 (0x66A6) smb_vwv[13]= 3979 (0xF8B) smb_vwv[14]=51757 (0xCA2D) smb_vwv[15]= 8193 (0x2001) smb_vwv[16]= 254 (0xFE) smb_bcc=58 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 78 30 39 33 00 00 00 00 00 00 00 00 00 00 00 00 x093.... ........ [010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E `(..+... ....0... [020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A 0...+... ..7..... [030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 10] lib/util_sock.c:read_smb_length_return_keepalive(1118) got smb length of 160 [2009/09/04 11:26:52, 6] smbd/process.c:process_smb(1547) got message type 0x0 of len 0xa0 [2009/09/04 11:26:52, 3] smbd/process.c:process_smb(1550) Transaction 1 of length 164 (0 toread) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=160 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=0 smb_pid=2875 smb_uid=0 smb_mid=2 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=65535 (0xFFFF) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 1 (0x1) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 79 (0x4F) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]=53340 (0xD05C) smb_vwv[11]=32768 (0x8000) smb_bcc=101 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 60 4D 06 06 2B 06 01 05 05 02 A0 43 30 41 A0 0E `M..+... ...C0A.. [010] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A2 2F 0...+... ..7..../ [020] 04 2D 4E 54 4C 4D 53 53 50 00 01 00 00 00 15 82 .-NTLMSS P....... [030] 08 60 09 00 09 00 20 00 00 00 04 00 04 00 29 00 .`.... . ......). [040] 00 00 57 4F 52 4B 47 52 4F 55 50 58 30 39 33 55 ..WORKGR OUPX093U [050] 00 6E 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 .n.i.x.. .S.a.m.b [060] 00 61 00 00 00 .a... [2009/09/04 11:26:52, 3] smbd/process.c:switch_message(1361) switch message SMBsesssetupX (pid 2876) conn 0x0 [2009/09/04 11:26:52, 0] smbd/process.c:smb_dump(1309) created /tmp/SMBsesssetupX.13.req len 164 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] smbd/uid.c:change_to_root_user(337) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/09/04 11:26:52, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1409) wct=12 flg2=0xc801 [2009/09/04 11:26:52, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1173) Doing spnego session setup [2009/09/04 11:26:52, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1208) NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[] [2009/09/04 11:26:52, 10] smbd/password.c:register_initial_vuid(188) register_initial_vuid: allocated vuid = 100 [2009/09/04 11:26:52, 10] smbd/sesssetup.c:check_spnego_blob_complete(1121) check_spnego_blob_complete: needed_len = 79, pblob->length = 79 [2009/09/04 11:26:52, 5] smbd/sesssetup.c:parse_spnego_mechanisms(749) parse_spnego_mechanisms: Got OID 1 3 6 1 4 1 311 2 2 10 [2009/09/04 11:26:52, 3] smbd/sesssetup.c:reply_spnego_negotiate(800) reply_spnego_negotiate: Got secblob of size 45 [2009/09/04 11:26:52, 5] auth/auth.c:make_auth_context_subsystem(491) Making default auth method list for standalone security=user, encrypt passwords = yes [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(46) Attempting to register auth backend sam [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(58) Successfully added auth method 'sam' [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(46) Attempting to register auth backend sam_ignoredomain [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(58) Successfully added auth method 'sam_ignoredomain' [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(46) Attempting to register auth backend unix [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(58) Successfully added auth method 'unix' [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(46) Attempting to register auth backend winbind [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(58) Successfully added auth method 'winbind' [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(46) Attempting to register auth backend smbserver [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(58) Successfully added auth method 'smbserver' [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(46) Attempting to register auth backend trustdomain [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(58) Successfully added auth method 'trustdomain' [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(46) Attempting to register auth backend ntdomain [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(58) Successfully added auth method 'ntdomain' [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(46) Attempting to register auth backend guest [2009/09/04 11:26:52, 5] auth/auth.c:smb_register_auth(58) Successfully added auth method 'guest' [2009/09/04 11:26:52, 5] auth/auth.c:load_auth_module(387) load_auth_module: Attempting to find an auth method to match guest [2009/09/04 11:26:52, 5] auth/auth.c:load_auth_module(412) load_auth_module: auth method guest has a valid init [2009/09/04 11:26:52, 5] auth/auth.c:load_auth_module(387) load_auth_module: Attempting to find an auth method to match sam [2009/09/04 11:26:52, 5] auth/auth.c:load_auth_module(412) load_auth_module: auth method sam has a valid init [2009/09/04 11:26:52, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62) Got NTLMSSP neg_flags=0x60088215 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2009/09/04 11:26:52, 5] auth/auth.c:get_ntlm_challenge(96) auth_get_challenge: module guest did not want to specify a challenge [2009/09/04 11:26:52, 5] auth/auth.c:get_ntlm_challenge(96) auth_get_challenge: module sam did not want to specify a challenge [2009/09/04 11:26:52, 5] auth/auth.c:get_ntlm_challenge(136) auth_context challenge created by random [2009/09/04 11:26:52, 5] auth/auth.c:get_ntlm_challenge(137) challenge is: [2009/09/04 11:26:52, 5] lib/util.c:dump_data(2223) [000] 73 04 78 1B F5 F3 7E E4 s.x...~. [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=246 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=2875 smb_uid=100 smb_mid=2 smt_wct=4 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 149 (0x95) smb_bcc=203 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] A1 81 92 30 81 8F A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 7A 04 78 4E 54 4C .....7.. ..z.xNTL [020] 4D 53 53 50 00 02 00 00 00 08 00 08 00 30 00 00 MSSP.... .....0.. [030] 00 15 82 8A 60 73 04 78 1B F5 F3 7E E4 00 00 00 ....`s.x ...~.... [040] 00 00 00 00 00 40 00 40 00 38 00 00 00 58 00 30 .....@.@ .8...X.0 [050] 00 39 00 33 00 02 00 08 00 58 00 30 00 39 00 33 .9.3.... .X.0.9.3 [060] 00 01 00 08 00 58 00 30 00 39 00 33 00 04 00 0C .....X.0 .9.3.... [070] 00 68 00 6B 00 75 00 2E 00 68 00 6B 00 03 00 10 .h.k.u.. .h.k.... [080] 00 78 00 2E 00 68 00 6B 00 75 00 2E 00 68 00 6B .x...h.k .u...h.k [090] 00 00 00 00 00 55 00 6E 00 69 00 78 00 00 00 53 .....U.n .i.x...S [0A0] 00 61 00 6D 00 62 00 61 00 20 00 33 00 2E 00 32 .a.m.b.a . .3...2 [0B0] 00 2E 00 36 00 00 00 57 00 4F 00 52 00 4B 00 47 ...6...W .O.R.K.G [0C0] 00 52 00 4F 00 55 00 50 00 00 00 .R.O.U.P ... [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 10] lib/util_sock.c:read_smb_length_return_keepalive(1118) got smb length of 240 [2009/09/04 11:26:52, 6] smbd/process.c:process_smb(1547) got message type 0x0 of len 0xf0 [2009/09/04 11:26:52, 3] smbd/process.c:process_smb(1550) Transaction 2 of length 244 (0 toread) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=240 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=0 smb_pid=2875 smb_uid=100 smb_mid=3 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=65535 (0xFFFF) smb_vwv[ 3]= 2 (0x2) smb_vwv[ 4]= 1 (0x1) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 158 (0x9E) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]=53340 (0xD05C) smb_vwv[11]=32768 (0x8000) smb_bcc=181 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] A1 81 9B 30 81 98 A2 81 95 04 81 92 4E 54 4C 4D ...0.... ....NTLM [010] 53 53 50 00 03 00 00 00 18 00 18 00 40 00 00 00 SSP..... ....@... [020] 18 00 18 00 58 00 00 00 00 00 00 00 70 00 00 00 ....X... ....p... [030] 0A 00 0A 00 70 00 00 00 08 00 08 00 7A 00 00 00 ....p... ....z... [040] 10 00 10 00 82 00 00 00 15 82 08 60 A6 E9 18 CE ........ ...`.... [050] 48 4E 14 82 00 00 00 00 00 00 00 00 00 00 00 00 HN...... ........ [060] 00 00 00 00 E2 81 1E 50 84 04 85 55 47 57 2E 95 .......P ...UGW.. [070] 74 20 41 0D 36 56 5F 74 E5 8F 23 24 75 00 73 00 t A.6V_t ..#$u.s. [080] 65 00 72 00 31 00 58 00 30 00 39 00 33 00 4B 25 e.r.1.X. 0.9.3.K% [090] 14 B4 E0 8A C7 58 29 77 51 94 D6 05 67 54 00 55 .....X)w Q...gT.U [0A0] 00 6E 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 .n.i.x.. .S.a.m.b [0B0] 00 61 00 00 00 .a... [2009/09/04 11:26:52, 3] smbd/process.c:switch_message(1361) switch message SMBsesssetupX (pid 2876) conn 0x0 [2009/09/04 11:26:52, 0] smbd/process.c:smb_dump(1309) created /tmp/SMBsesssetupX.14.req len 244 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] smbd/uid.c:change_to_root_user(337) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/09/04 11:26:52, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1409) wct=12 flg2=0xc801 [2009/09/04 11:26:52, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1173) Doing spnego session setup [2009/09/04 11:26:52, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1208) NativeOS=[Unix] NativeLanMan=[Samba] PrimaryDomain=[] [2009/09/04 11:26:52, 10] smbd/sesssetup.c:check_spnego_blob_complete(1121) check_spnego_blob_complete: needed_len = 158, pblob->length = 158 [2009/09/04 11:26:52, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(745) Got user=[user1] domain=[] workstation=[X093] len1=24 len2=24 [2009/09/04 11:26:52, 5] auth/auth_ntlmssp.c:auth_ntlmssp_set_challenge(68) auth_context challenge set by NTLMSSP callback (NTLM2) [2009/09/04 11:26:52, 5] auth/auth_ntlmssp.c:auth_ntlmssp_set_challenge(69) challenge is: [2009/09/04 11:26:52, 5] lib/util.c:dump_data(2223) [000] 4E E7 73 D7 63 FC 8B BE N.s.c... [2009/09/04 11:26:52, 6] param/loadparm.c:lp_file_list_changed(6699) lp_file_list_changed() file /10/samba-3.2.6/lib/smb.conf -> /10/samba-3.2.6/lib/smb.conf last mod_time: Fri Sep 4 11:19:32 2009 [2009/09/04 11:26:52, 5] auth/auth_util.c:make_user_info_map(206) make_user_info_map: Mapping user []\[user1] from workstation [X093] [2009/09/04 11:26:52, 5] auth/auth_util.c:make_user_info(120) attempting to make a user_info for user1 (user1) [2009/09/04 11:26:52, 5] auth/auth_util.c:make_user_info(130) making strings for user1's user_info struct [2009/09/04 11:26:52, 5] auth/auth_util.c:make_user_info(162) making blobs for user1's user_info struct [2009/09/04 11:26:52, 10] auth/auth_util.c:make_user_info(180) made an encrypted user_info for user1 (user1) [2009/09/04 11:26:52, 3] auth/auth.c:check_ntlm_password(220) check_ntlm_password: Checking password for unmapped user []\[user1]@[X093] with the new password interface [2009/09/04 11:26:52, 3] auth/auth.c:check_ntlm_password(223) check_ntlm_password: mapped user is: [X093]\[user1]@[X093] [2009/09/04 11:26:52, 10] auth/auth.c:check_ntlm_password(232) check_ntlm_password: auth_context challenge created by NTLMSSP callback (NTLM2) [2009/09/04 11:26:52, 10] auth/auth.c:check_ntlm_password(234) challenge is: [2009/09/04 11:26:52, 5] lib/util.c:dump_data(2223) [000] 4E E7 73 D7 63 FC 8B BE N.s.c... [2009/09/04 11:26:52, 10] auth/auth.c:check_ntlm_password(260) check_ntlm_password: guest had nothing to say [2009/09/04 11:26:52, 8] lib/util.c:is_myname(2098) is_myname("X093") returns 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_unpack(655) tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 5 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 6 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 7 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 8 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 9 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 10 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 21 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_username(580) pdb_set_username: setting username user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 12 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_domain(603) pdb_set_domain: setting domain X093, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 14 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_nt_username(626) pdb_set_nt_username: setting nt username , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 15 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_fullname(649) pdb_set_full_name: setting full name , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 13 -> now SET [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_homedir(742) pdb_set_homedir: setting home dir \\x093\user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 1 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(718) pdb_set_dir_drive: setting dir drive F:, was NULL [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 3 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_logon_script(672) pdb_set_logon_script: setting logon script , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 4 -> now DEFAULT [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_profile_path(695) pdb_set_profile_path: setting profile path \\x093\user1\profile, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 2 -> now DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 23 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_workstations(785) pdb_set_workstations: setting workstations , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 24 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 26 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 32 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 33 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 34 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_user_sid(509) pdb_set_user_sid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 18 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 from rid 1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 16 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 29 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 30 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 31 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 20 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 17 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 27 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: maximum password age, val: -1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_alloc(133) Finding user user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(77) Trying _Get_Pwnam(), username as lowercase is user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(110) Get_Pwnam_internals did find user [user1]! [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 3: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 1: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 4: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 2: DEFAULT [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_pack_va(501) tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 198 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_pack_va(501) tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_unpack(655) tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 5 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 6 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 7 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 8 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 9 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 10 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 21 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_username(580) pdb_set_username: setting username user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 12 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_domain(603) pdb_set_domain: setting domain X093, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 14 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_nt_username(626) pdb_set_nt_username: setting nt username , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 15 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_fullname(649) pdb_set_full_name: setting full name , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 13 -> now SET [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_homedir(742) pdb_set_homedir: setting home dir \\x093\user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 1 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(718) pdb_set_dir_drive: setting dir drive F:, was NULL [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 3 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_logon_script(672) pdb_set_logon_script: setting logon script , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 4 -> now DEFAULT [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_profile_path(695) pdb_set_profile_path: setting profile path \\x093\user1\profile, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 2 -> now DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 23 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_workstations(785) pdb_set_workstations: setting workstations , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 24 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 26 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 32 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 33 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 34 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_user_sid(509) pdb_set_user_sid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 18 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 from rid 1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 16 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 29 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 30 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 31 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 20 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 17 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 27 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 9] passdb/passdb.c:pdb_update_autolock_flag(1417) pdb_update_autolock_flag: Account user1 not autolocked, no check needed [2009/09/04 11:26:52, 4] libsmb/ntlm_check.c:ntlm_password_check(328) ntlm_password_check: Checking NT MD4 password [2009/09/04 11:26:52, 4] auth/auth_sam.c:sam_account_ok(137) sam_account_ok: Checking SMB password for user user1 [2009/09/04 11:26:52, 5] auth/auth_sam.c:logon_hours_ok(119) logon_hours_ok: user user1 allowed to logon at this time (Fri Sep 4 03:26:52 2009 ) [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: maximum password age, val: -1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/system_smbd.c:sys_getgrouplist(122) sys_getgrouplist: user [user1] [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_gid_to_sid(1170) LEGACY: gid 100 -> sid S-1-22-2-100 [2009/09/04 11:26:52, 5] auth/auth_util.c:make_server_info_sam(650) make_server_info_sam: made server info for user user1 -> user1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] auth/auth.c:check_ntlm_password(269) check_ntlm_password: sam authentication for user [user1] succeeded [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] auth/auth.c:check_ntlm_password(295) check_ntlm_password: PAM Account for user [user1] succeeded [2009/09/04 11:26:52, 2] auth/auth.c:check_ntlm_password(308) check_ntlm_password: authentication for user [user1] -> [user1] -> [user1] succeeded [2009/09/04 11:26:52, 5] auth/auth_util.c:free_user_info(1985) attempting to free (and zero) a user_info structure [2009/09/04 11:26:52, 10] auth/auth_util.c:free_user_info(1989) structure was created for user1 [2009/09/04 11:26:52, 10] auth/token_util.c:create_local_nt_token(304) Create local NT token for S-1-5-21-3850432308-4052430627-3054637991-1216 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-5-32-544 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-5-32-545 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] lib/privileges.c:get_privileges(63) get_privileges: No privileges assigned to SID [S-1-5-21-3850432308-4052430627-3054637991-1216] [2009/09/04 11:26:52, 3] lib/privileges.c:get_privileges(63) get_privileges: No privileges assigned to SID [S-1-22-2-100] [2009/09/04 11:26:52, 5] lib/privileges.c:get_privileges_for_sids(128) get_privileges_for_sids: sid = S-1-1-0 Privilege set: SE_PRIV 0x0 0x0 0x0 0x0 [2009/09/04 11:26:52, 3] lib/privileges.c:get_privileges(63) get_privileges: No privileges assigned to SID [S-1-5-2] [2009/09/04 11:26:52, 3] lib/privileges.c:get_privileges(63) get_privileges: No privileges assigned to SID [S-1-5-11] [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:sid_to_gid(1413) sid S-1-22-2-100 -> gid 100 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-1-0 [2009/09/04 11:26:52, 10] auth/auth_util.c:create_local_token(755) Could not convert SID S-1-1-0 to gid, ignoring it [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-5-2 [2009/09/04 11:26:52, 10] auth/auth_util.c:create_local_token(755) Could not convert SID S-1-5-2 to gid, ignoring it [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-5-11 [2009/09/04 11:26:52, 10] auth/auth_util.c:create_local_token(755) Could not convert SID S-1-5-11 to gid, ignoring it [2009/09/04 11:26:52, 10] auth/token_util.c:debug_nt_user_token(472) NT user token of user S-1-5-21-3850432308-4052430627-3054637991-1216 contains 5 SIDs SID[ 0]: S-1-5-21-3850432308-4052430627-3054637991-1216 SID[ 1]: S-1-22-2-100 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SE_PRIV 0x0 0x0 0x0 0x0 [2009/09/04 11:26:52, 10] auth/auth_ntlmssp.c:auth_ntlmssp_check_password(137) Got NT session key of length 16 [2009/09/04 11:26:52, 10] auth/auth_ntlmssp.c:auth_ntlmssp_check_password(144) Got LM session key of length 16 [2009/09/04 11:26:52, 10] libsmb/ntlmssp.c:ntlmssp_server_auth(811) ntlmssp_server_auth: Created NTLM2 session key. [2009/09/04 11:26:52, 3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(337) NTLMSSP Sign/Seal - Initialising with flags: [2009/09/04 11:26:52, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62) Got NTLMSSP neg_flags=0x60088215 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 22: DEFAULT [2009/09/04 11:26:52, 10] smbd/password.c:register_existing_vuid(310) register_existing_vuid: (108,100) user1 user1 X093 guest=0 [2009/09/04 11:26:52, 3] smbd/password.c:register_existing_vuid(314) register_existing_vuid: User name: user1 Real name: [2009/09/04 11:26:52, 3] smbd/password.c:register_existing_vuid(326) register_existing_vuid: UNIX uid 108 is UNIX user user1, and will be vuid 100 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100) Locking key 49442F323837362F31303000 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129) Allocated locked data 0x0xb8401e10 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42) Unlocking key 49442F323837362F31303000 [2009/09/04 11:26:52, 7] param/loadparm.c:lp_servicenumber(9002) lp_servicenumber: couldn't find user1 [2009/09/04 11:26:52, 3] smbd/password.c:register_existing_vuid(350) Adding homes service for user 'user1' using home directory: '/home/user1' [2009/09/04 11:26:52, 7] param/loadparm.c:lp_servicenumber(9002) lp_servicenumber: couldn't find homes [2009/09/04 11:26:52, 6] param/loadparm.c:lp_file_list_changed(6699) lp_file_list_changed() file /10/samba-3.2.6/lib/smb.conf -> /10/samba-3.2.6/lib/smb.conf last mod_time: Fri Sep 4 11:19:32 2009 [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=106 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=2875 smb_uid=100 smb_mid=3 smt_wct=4 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 9 (0x9) smb_bcc=63 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] A1 07 30 05 A0 03 0A 01 00 55 00 6E 00 69 00 78 ..0..... .U.n.i.x [010] 00 00 00 53 00 61 00 6D 00 62 00 61 00 20 00 33 ...S.a.m .b.a. .3 [020] 00 2E 00 32 00 2E 00 36 00 00 00 57 00 4F 00 52 ...2...6 ...W.O.R [030] 00 4B 00 47 00 52 00 4F 00 55 00 50 00 00 00 .K.G.R.O .U.P... [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 10] lib/util_sock.c:read_smb_length_return_keepalive(1118) got smb length of 82 [2009/09/04 11:26:52, 6] smbd/process.c:process_smb(1547) got message type 0x0 of len 0x52 [2009/09/04 11:26:52, 3] smbd/process.c:process_smb(1550) Transaction 3 of length 86 (0 toread) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=82 smb_com=0x75 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=0 smb_pid=2875 smb_uid=100 smb_mid=4 smt_wct=4 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 8 (0x8) smb_vwv[ 3]= 1 (0x1) smb_bcc=39 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 00 5C 00 5C 00 31 00 32 00 37 00 2E 00 30 00 2E .\.\.1.2 .7...0.. [010] 00 30 00 2E 00 31 00 5C 00 49 00 50 00 43 00 24 .0...1.\ .I.P.C.$ [020] 00 00 00 49 50 43 00 ...IPC. [2009/09/04 11:26:52, 3] smbd/process.c:switch_message(1361) switch message SMBtconX (pid 2876) conn 0x0 [2009/09/04 11:26:52, 0] smbd/process.c:smb_dump(1309) created /tmp/SMBtconX.7.req len 86 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] smbd/uid.c:change_to_root_user(337) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/09/04 11:26:52, 4] smbd/reply.c:reply_tcon_and_X(687) Client requested device type [IPC] for share [IPC$] [2009/09/04 11:26:52, 5] smbd/service.c:make_connection(1376) making a connection to 'normal' service ipc$ [2009/09/04 11:26:52, 3] lib/access.c:only_ipaddrs_in_list(362) only_ipaddrs_in_list: list has non-ip address (147.8.101.) [2009/09/04 11:26:52, 3] lib/access.c:check_access(396) check_access: hostnames in host allow/deny list. [2009/09/04 11:26:52, 2] lib/access.c:check_access(406) Allowed connection from 127.0.0.1 (127.0.0.1) [2009/09/04 11:26:52, 3] lib/util_sid.c:string_to_sid(228) string_to_sid: Sid root does not start with 'S-'. [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: X093\root => X093 (domain), root (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x073 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] passdb/pdb_tdb.c:tdbsam_getsampwnam(911) pdb_getsampwnam (TDB): error fetching database. Key: USER_root [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: Unix User\root => Unix User (domain), root (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x073 [2009/09/04 11:26:52, 3] lib/util_sid.c:string_to_sid(228) string_to_sid: Sid nobody does not start with 'S-'. [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: X093\nobody => X093 (domain), nobody (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x073 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] passdb/pdb_tdb.c:tdbsam_getsampwnam(911) pdb_getsampwnam (TDB): error fetching database. Key: USER_nobody [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: Unix User\nobody => Unix User (domain), nobody (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x073 [2009/09/04 11:26:52, 3] lib/util_sid.c:string_to_sid(228) string_to_sid: Sid @users does not start with 'S-'. [2009/09/04 11:26:52, 5] smbd/password.c:user_in_netgroup(463) Unable to get default yp domain, let's try without specifying it [2009/09/04 11:26:52, 5] smbd/password.c:user_in_netgroup(467) looking for user user1 of domain (ANY) in netgroup users [2009/09/04 11:26:52, 5] smbd/password.c:user_in_netgroup(483) looking for user user1 of domain (ANY) in netgroup users [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: X093\users => X093 (domain), users (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x077 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: Unix Group\users => Unix Group (domain), users (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x077 [2009/09/04 11:26:52, 10] smbd/share_access.c:user_ok_token(231) user_ok_token: share IPC$ is ok for unix user user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_alloc(133) Finding user user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(77) Trying _Get_Pwnam(), username as lowercase is user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(110) Get_Pwnam_internals did find user [user1]! [2009/09/04 11:26:52, 10] smbd/service.c:set_conn_connectpath(157) set_conn_connectpath: service IPC$, connectpath = /tmp [2009/09/04 11:26:52, 3] smbd/service.c:make_connection_snum(936) Connect path is '/tmp' for service [IPC$] [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100) Locking key 494E464F2F76657273696F6E00 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129) Allocated locked data 0x0xb8401e10 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42) Unlocking key 494E464F2F76657273696F6E00 [2009/09/04 11:26:52, 10] lib/util_seaccess.c:se_map_generic(175) se_map_generic(): mapped mask 0x10000000 to 0x001f01ff [2009/09/04 11:26:52, 10] lib/util_seaccess.c:se_access_check(232) se_access_check: requested access 0x00000002, for NT token with 5 entries and first sid S-1-5-21-3850432308-4052430627-3054637991-1216. [2009/09/04 11:26:52, 3] lib/util_seaccess.c:se_access_check(249) [2009/09/04 11:26:52, 3] lib/util_seaccess.c:se_access_check(252) se_access_check: user sid is S-1-5-21-3850432308-4052430627-3054637991-1216 se_access_check: also S-1-22-2-100 se_access_check: also S-1-1-0 se_access_check: also S-1-5-2 se_access_check: also S-1-5-11 se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 2 [2009/09/04 11:26:52, 5] lib/util_seaccess.c:se_access_check(310) se_access_check: access (2) granted. [2009/09/04 11:26:52, 3] smbd/vfs.c:vfs_init_default(96) Initialising default vfs hooks [2009/09/04 11:26:52, 10] smbd/vfs.c:vfs_find_backend_entry(48) vfs_find_backend_entry called for /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:smb_register_vfs(86) Successfully added vfs backend '/[Default VFS]/' [2009/09/04 11:26:52, 3] smbd/vfs.c:vfs_init_custom(130) Initialising custom vfs hooks from [/[Default VFS]/] [2009/09/04 11:26:52, 10] smbd/vfs.c:vfs_find_backend_entry(48) vfs_find_backend_entry called for /[Default VFS]/ Successfully loaded vfs module [/[Default VFS]/] with the new modules system [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #0 (type 0, layer 0) Making operation type 0 opaque [module /[Default VFS]/] Accepting operation type 0 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #1 (type 1, layer 0) Making operation type 1 opaque [module /[Default VFS]/] Accepting operation type 1 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #2 (type 2, layer 0) Making operation type 2 opaque [module /[Default VFS]/] Accepting operation type 2 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #3 (type 3, layer 0) Making operation type 3 opaque [module /[Default VFS]/] Accepting operation type 3 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #4 (type 4, layer 0) Making operation type 4 opaque [module /[Default VFS]/] Accepting operation type 4 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #5 (type 5, layer 0) Making operation type 5 opaque [module /[Default VFS]/] Accepting operation type 5 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #6 (type 6, layer 0) Making operation type 6 opaque [module /[Default VFS]/] Accepting operation type 6 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #7 (type 7, layer 0) Making operation type 7 opaque [module /[Default VFS]/] Accepting operation type 7 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #8 (type 8, layer 0) Making operation type 8 opaque [module /[Default VFS]/] Accepting operation type 8 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #9 (type 9, layer 0) Making operation type 9 opaque [module /[Default VFS]/] Accepting operation type 9 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #10 (type 10, layer 0) Making operation type 10 opaque [module /[Default VFS]/] Accepting operation type 10 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #11 (type 11, layer 0) Making operation type 11 opaque [module /[Default VFS]/] Accepting operation type 11 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #12 (type 12, layer 0) Making operation type 12 opaque [module /[Default VFS]/] Accepting operation type 12 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #13 (type 13, layer 0) Making operation type 13 opaque [module /[Default VFS]/] Accepting operation type 13 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #14 (type 14, layer 0) Making operation type 14 opaque [module /[Default VFS]/] Accepting operation type 14 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #15 (type 15, layer 0) Making operation type 15 opaque [module /[Default VFS]/] Accepting operation type 15 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #16 (type 16, layer 0) Making operation type 16 opaque [module /[Default VFS]/] Accepting operation type 16 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #17 (type 17, layer 0) Making operation type 17 opaque [module /[Default VFS]/] Accepting operation type 17 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #18 (type 18, layer 0) Making operation type 18 opaque [module /[Default VFS]/] Accepting operation type 18 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #19 (type 19, layer 0) Making operation type 19 opaque [module /[Default VFS]/] Accepting operation type 19 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #20 (type 20, layer 0) Making operation type 20 opaque [module /[Default VFS]/] Accepting operation type 20 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #21 (type 21, layer 0) Making operation type 21 opaque [module /[Default VFS]/] Accepting operation type 21 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #22 (type 22, layer 0) Making operation type 22 opaque [module /[Default VFS]/] Accepting operation type 22 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #23 (type 23, layer 0) Making operation type 23 opaque [module /[Default VFS]/] Accepting operation type 23 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #24 (type 24, layer 0) Making operation type 24 opaque [module /[Default VFS]/] Accepting operation type 24 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #25 (type 25, layer 0) Making operation type 25 opaque [module /[Default VFS]/] Accepting operation type 25 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #26 (type 26, layer 0) Making operation type 26 opaque [module /[Default VFS]/] Accepting operation type 26 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #27 (type 27, layer 0) Making operation type 27 opaque [module /[Default VFS]/] Accepting operation type 27 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #28 (type 28, layer 0) Making operation type 28 opaque [module /[Default VFS]/] Accepting operation type 28 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #29 (type 29, layer 0) Making operation type 29 opaque [module /[Default VFS]/] Accepting operation type 29 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #30 (type 30, layer 0) Making operation type 30 opaque [module /[Default VFS]/] Accepting operation type 30 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #31 (type 31, layer 0) Making operation type 31 opaque [module /[Default VFS]/] Accepting operation type 31 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #32 (type 32, layer 0) Making operation type 32 opaque [module /[Default VFS]/] Accepting operation type 32 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #33 (type 33, layer 0) Making operation type 33 opaque [module /[Default VFS]/] Accepting operation type 33 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #34 (type 34, layer 0) Making operation type 34 opaque [module /[Default VFS]/] Accepting operation type 34 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #35 (type 35, layer 0) Making operation type 35 opaque [module /[Default VFS]/] Accepting operation type 35 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #36 (type 36, layer 0) Making operation type 36 opaque [module /[Default VFS]/] Accepting operation type 36 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #37 (type 37, layer 0) Making operation type 37 opaque [module /[Default VFS]/] Accepting operation type 37 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #38 (type 38, layer 0) Making operation type 38 opaque [module /[Default VFS]/] Accepting operation type 38 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #39 (type 39, layer 0) Making operation type 39 opaque [module /[Default VFS]/] Accepting operation type 39 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #40 (type 40, layer 0) Making operation type 40 opaque [module /[Default VFS]/] Accepting operation type 40 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #41 (type 41, layer 0) Making operation type 41 opaque [module /[Default VFS]/] Accepting operation type 41 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #42 (type 42, layer 0) Making operation type 42 opaque [module /[Default VFS]/] Accepting operation type 42 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #43 (type 43, layer 0) Making operation type 43 opaque [module /[Default VFS]/] Accepting operation type 43 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #44 (type 44, layer 0) Making operation type 44 opaque [module /[Default VFS]/] Accepting operation type 44 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #45 (type 45, layer 0) Making operation type 45 opaque [module /[Default VFS]/] Accepting operation type 45 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #46 (type 46, layer 0) Making operation type 46 opaque [module /[Default VFS]/] Accepting operation type 46 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #47 (type 47, layer 0) Making operation type 47 opaque [module /[Default VFS]/] Accepting operation type 47 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #48 (type 48, layer 0) Making operation type 48 opaque [module /[Default VFS]/] Accepting operation type 48 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #49 (type 49, layer 0) Making operation type 49 opaque [module /[Default VFS]/] Accepting operation type 49 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #50 (type 50, layer 0) Making operation type 50 opaque [module /[Default VFS]/] Accepting operation type 50 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #51 (type 51, layer 0) Making operation type 51 opaque [module /[Default VFS]/] Accepting operation type 51 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #52 (type 52, layer 0) Making operation type 52 opaque [module /[Default VFS]/] Accepting operation type 52 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #53 (type 53, layer 0) Making operation type 53 opaque [module /[Default VFS]/] Accepting operation type 53 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #54 (type 54, layer 0) Making operation type 54 opaque [module /[Default VFS]/] Accepting operation type 54 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #55 (type 55, layer 0) Making operation type 55 opaque [module /[Default VFS]/] Accepting operation type 55 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #56 (type 56, layer 0) Making operation type 56 opaque [module /[Default VFS]/] Accepting operation type 56 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #57 (type 57, layer 0) Making operation type 57 opaque [module /[Default VFS]/] Accepting operation type 57 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #58 (type 58, layer 0) Making operation type 58 opaque [module /[Default VFS]/] Accepting operation type 58 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #59 (type 59, layer 0) Making operation type 59 opaque [module /[Default VFS]/] Accepting operation type 59 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #60 (type 60, layer 0) Making operation type 60 opaque [module /[Default VFS]/] Accepting operation type 60 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #61 (type 61, layer 0) Making operation type 61 opaque [module /[Default VFS]/] Accepting operation type 61 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #62 (type 62, layer 0) Making operation type 62 opaque [module /[Default VFS]/] Accepting operation type 62 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #63 (type 63, layer 0) Making operation type 63 opaque [module /[Default VFS]/] Accepting operation type 63 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #64 (type 64, layer 0) Making operation type 64 opaque [module /[Default VFS]/] Accepting operation type 64 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #65 (type 65, layer 0) Making operation type 65 opaque [module /[Default VFS]/] Accepting operation type 65 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #66 (type 66, layer 0) Making operation type 66 opaque [module /[Default VFS]/] Accepting operation type 66 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #67 (type 67, layer 0) Making operation type 67 opaque [module /[Default VFS]/] Accepting operation type 67 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #68 (type 68, layer 0) Making operation type 68 opaque [module /[Default VFS]/] Accepting operation type 68 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #69 (type 69, layer 0) Making operation type 69 opaque [module /[Default VFS]/] Accepting operation type 69 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #70 (type 70, layer 0) Making operation type 70 opaque [module /[Default VFS]/] Accepting operation type 70 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #71 (type 71, layer 0) Making operation type 71 opaque [module /[Default VFS]/] Accepting operation type 71 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #72 (type 72, layer 0) Making operation type 72 opaque [module /[Default VFS]/] Accepting operation type 72 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #73 (type 73, layer 0) Making operation type 73 opaque [module /[Default VFS]/] Accepting operation type 73 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #74 (type 74, layer 0) Making operation type 74 opaque [module /[Default VFS]/] Accepting operation type 74 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #75 (type 75, layer 0) Making operation type 75 opaque [module /[Default VFS]/] Accepting operation type 75 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #76 (type 76, layer 0) Making operation type 76 opaque [module /[Default VFS]/] Accepting operation type 76 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #77 (type 77, layer 0) Making operation type 77 opaque [module /[Default VFS]/] Accepting operation type 77 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #78 (type 78, layer 0) Making operation type 78 opaque [module /[Default VFS]/] Accepting operation type 78 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #79 (type 79, layer 0) Making operation type 79 opaque [module /[Default VFS]/] Accepting operation type 79 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #80 (type 80, layer 0) Making operation type 80 opaque [module /[Default VFS]/] Accepting operation type 80 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #81 (type 81, layer 0) Making operation type 81 opaque [module /[Default VFS]/] Accepting operation type 81 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #82 (type 82, layer 0) Making operation type 82 opaque [module /[Default VFS]/] Accepting operation type 82 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #83 (type 83, layer 0) Making operation type 83 opaque [module /[Default VFS]/] Accepting operation type 83 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #84 (type 84, layer 0) Making operation type 84 opaque [module /[Default VFS]/] Accepting operation type 84 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #85 (type 85, layer 0) Making operation type 85 opaque [module /[Default VFS]/] Accepting operation type 85 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #86 (type 86, layer 0) Making operation type 86 opaque [module /[Default VFS]/] Accepting operation type 86 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #87 (type 87, layer 0) Making operation type 87 opaque [module /[Default VFS]/] Accepting operation type 87 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #88 (type 88, layer 0) Making operation type 88 opaque [module /[Default VFS]/] Accepting operation type 88 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #89 (type 89, layer 0) Making operation type 89 opaque [module /[Default VFS]/] Accepting operation type 89 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #90 (type 90, layer 0) Making operation type 90 opaque [module /[Default VFS]/] Accepting operation type 90 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #91 (type 91, layer 0) Making operation type 91 opaque [module /[Default VFS]/] Accepting operation type 91 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #92 (type 92, layer 0) Making operation type 92 opaque [module /[Default VFS]/] Accepting operation type 92 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #93 (type 93, layer 0) Making operation type 93 opaque [module /[Default VFS]/] Accepting operation type 93 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #94 (type 94, layer 0) Making operation type 94 opaque [module /[Default VFS]/] Accepting operation type 94 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #95 (type 95, layer 0) Making operation type 95 opaque [module /[Default VFS]/] Accepting operation type 95 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #96 (type 96, layer 0) Making operation type 96 opaque [module /[Default VFS]/] Accepting operation type 96 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #97 (type 97, layer 0) Making operation type 97 opaque [module /[Default VFS]/] Accepting operation type 97 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #98 (type 98, layer 0) Making operation type 98 opaque [module /[Default VFS]/] Accepting operation type 98 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #99 (type 99, layer 0) Making operation type 99 opaque [module /[Default VFS]/] Accepting operation type 99 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #100 (type 100, layer 0) Making operation type 100 opaque [module /[Default VFS]/] Accepting operation type 100 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #101 (type 101, layer 0) Making operation type 101 opaque [module /[Default VFS]/] Accepting operation type 101 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/vfs.c:vfs_init_custom(193) Checking operation #102 (type 102, layer 0) Making operation type 102 opaque [module /[Default VFS]/] Accepting operation type 102 from module /[Default VFS]/ [2009/09/04 11:26:52, 5] smbd/connection.c:claim_connection(142) claiming [IPC$] [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100) Locking key 3C0B00000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129) Allocated locked data 0x0xb8405968 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42) Unlocking key 3C0B00000100000049504324000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000 [2009/09/04 11:26:52, 3] lib/util_sid.c:string_to_sid(228) string_to_sid: Sid root does not start with 'S-'. [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: X093\root => X093 (domain), root (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x073 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] passdb/pdb_tdb.c:tdbsam_getsampwnam(911) pdb_getsampwnam (TDB): error fetching database. Key: USER_root [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: Unix User\root => Unix User (domain), root (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x073 [2009/09/04 11:26:52, 3] lib/util_sid.c:string_to_sid(228) string_to_sid: Sid nobody does not start with 'S-'. [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: X093\nobody => X093 (domain), nobody (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x073 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] passdb/pdb_tdb.c:tdbsam_getsampwnam(911) pdb_getsampwnam (TDB): error fetching database. Key: USER_nobody [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: Unix User\nobody => Unix User (domain), nobody (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x073 [2009/09/04 11:26:52, 3] lib/util_sid.c:string_to_sid(228) string_to_sid: Sid @users does not start with 'S-'. [2009/09/04 11:26:52, 5] smbd/password.c:user_in_netgroup(463) Unable to get default yp domain, let's try without specifying it [2009/09/04 11:26:52, 5] smbd/password.c:user_in_netgroup(467) looking for user user1 of domain (ANY) in netgroup users [2009/09/04 11:26:52, 5] smbd/password.c:user_in_netgroup(483) looking for user user1 of domain (ANY) in netgroup users [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: X093\users => X093 (domain), users (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x077 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(69) lookup_name: Unix Group\users => Unix Group (domain), users (name) [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:lookup_name(70) lookup_name: flags = 0x077 [2009/09/04 11:26:52, 10] smbd/share_access.c:user_ok_token(231) user_ok_token: share IPC$ is ok for unix user user1 [2009/09/04 11:26:52, 10] smbd/share_access.c:is_share_read_only_for_token(275) is_share_read_only_for_user: share IPC$ is read-only for unix user user1 [2009/09/04 11:26:52, 10] lib/util_seaccess.c:se_map_generic(175) se_map_generic(): mapped mask 0x10000000 to 0x001f01ff [2009/09/04 11:26:52, 10] lib/util_seaccess.c:se_access_check(232) se_access_check: requested access 0x00000001, for NT token with 5 entries and first sid S-1-5-21-3850432308-4052430627-3054637991-1216. [2009/09/04 11:26:52, 3] lib/util_seaccess.c:se_access_check(249) [2009/09/04 11:26:52, 3] lib/util_seaccess.c:se_access_check(252) se_access_check: user sid is S-1-5-21-3850432308-4052430627-3054637991-1216 se_access_check: also S-1-22-2-100 se_access_check: also S-1-1-0 se_access_check: also S-1-5-2 se_access_check: also S-1-5-11 se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 1 [2009/09/04 11:26:52, 5] lib/util_seaccess.c:se_access_check(310) se_access_check: access (1) granted. [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (108, 100) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(472) NT user token of user S-1-5-21-3850432308-4052430627-3054637991-1216 contains 5 SIDs SID[ 0]: S-1-5-21-3850432308-4052430627-3054637991-1216 SID[ 1]: S-1-22-2-100 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SE_PRIV 0x0 0x0 0x0 0x0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 108 Primary group is 100 and contains 1 supplementary groups Group[ 0]: 100 [2009/09/04 11:26:52, 5] smbd/uid.c:change_to_user(322) change_to_user uid=(0,108) gid=(0,100) [2009/09/04 11:26:52, 3] smbd/service.c:make_connection_snum(1190) x093 (127.0.0.1) connect to service IPC$ initially as user user1 (uid=108, gid=100) (pid 2876) [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 5] smbd/uid.c:change_to_root_user(337) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/09/04 11:26:52, 3] smbd/reply.c:reply_tcon_and_X(761) tconX service=IPC$ [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=56 smb_com=0x75 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=4 smt_wct=7 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 1 (0x1) smb_vwv[ 3]= 511 (0x1FF) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 511 (0x1FF) smb_vwv[ 6]= 0 (0x0) smb_bcc=7 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 49 50 43 00 00 00 00 IPC.... [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 10] lib/util_sock.c:read_smb_length_return_keepalive(1118) got smb length of 96 [2009/09/04 11:26:52, 6] smbd/process.c:process_smb(1547) got message type 0x0 of len 0x60 [2009/09/04 11:26:52, 3] smbd/process.c:process_smb(1550) Transaction 4 of length 100 (0 toread) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=96 smb_com=0xa2 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=5 smt_wct=24 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 2560 (0xA00) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]=40704 (0x9F00) smb_vwv[ 8]= 513 (0x201) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_vwv[15]= 768 (0x300) smb_vwv[16]= 0 (0x0) smb_vwv[17]= 256 (0x100) smb_vwv[18]= 0 (0x0) smb_vwv[19]= 0 (0x0) smb_vwv[20]= 0 (0x0) smb_vwv[21]= 512 (0x200) smb_vwv[22]= 0 (0x0) smb_vwv[23]= 0 (0x0) smb_bcc=13 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 18 5C 00 73 00 61 00 6D 00 72 00 00 00 .\.s.a.m .r... [2009/09/04 11:26:52, 3] smbd/process.c:switch_message(1361) switch message SMBntcreateX (pid 2876) conn 0xb8407070 [2009/09/04 11:26:52, 0] smbd/process.c:smb_dump(1309) created /tmp/SMBntcreateX.4.req len 100 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (108, 100) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(472) NT user token of user S-1-5-21-3850432308-4052430627-3054637991-1216 contains 5 SIDs SID[ 0]: S-1-5-21-3850432308-4052430627-3054637991-1216 SID[ 1]: S-1-22-2-100 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SE_PRIV 0x0 0x0 0x0 0x0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 108 Primary group is 100 and contains 1 supplementary groups Group[ 0]: 100 [2009/09/04 11:26:52, 5] smbd/uid.c:change_to_user(322) change_to_user uid=(0,108) gid=(0,100) [2009/09/04 11:26:52, 4] smbd/vfs.c:vfs_ChDir(739) vfs_ChDir to /tmp [2009/09/04 11:26:52, 10] smbd/nttrans.c:reply_ntcreate_and_X(488) reply_ntcreate_and_X: flags = 0x0, access_mask = 0x2019f file_attributes = 0x0, share_access = 0x3, create_disposition = 0x1 create_options = 0x0 root_dir_fid = 0x0, fname = samr [2009/09/04 11:26:52, 4] smbd/nttrans.c:nt_open_pipe(295) nt_open_pipe: Opening pipe \samr. [2009/09/04 11:26:52, 3] smbd/nttrans.c:nt_open_pipe(320) nt_open_pipe: Known pipe samr opening. [2009/09/04 11:26:52, 4] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(165) Open pipe requested samr (pipes_open=0) [2009/09/04 11:26:52, 4] rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(275) Create pipe requested samr [2009/09/04 11:26:52, 10] rpc_server/srv_lsa_hnd.c:init_pipe_handle_list(77) init_pipe_handles: created handle list for pipe samr [2009/09/04 11:26:52, 10] rpc_server/srv_lsa_hnd.c:init_pipe_handle_list(93) init_pipe_handles: pipe_handles ref count = 1 for pipe samr [2009/09/04 11:26:52, 4] rpc_server/srv_pipe_hnd.c:make_internal_rpc_pipe_p(356) Created internal pipe samr (pipes_open=0) [2009/09/04 11:26:52, 4] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(253) Opened pipe samr with handle 73c0 (pipes_open=1) [2009/09/04 11:26:52, 5] rpc_server/srv_pipe_hnd.c:open_rpc_pipe_p(259) open pipes: name samr pnum=73c0 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100) Locking key 73616D722F323837362F323936333200 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129) Allocated locked data 0x0xb8401f68 [2009/09/04 11:26:52, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42) Unlocking key 73616D722F323837362F323936333200 [2009/09/04 11:26:52, 5] smbd/nttrans.c:do_ntcreate_pipe_open(408) do_ntcreate_pipe_open: open pipe = \samr [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=103 smb_com=0xa2 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=5 smt_wct=34 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=49152 (0xC000) smb_vwv[ 3]= 371 (0x173) 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]= 0 (0x0) smb_vwv[10]= 0 (0x0) smb_vwv[11]= 0 (0x0) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_vwv[14]= 0 (0x0) smb_vwv[15]= 0 (0x0) smb_vwv[16]= 0 (0x0) smb_vwv[17]= 0 (0x0) smb_vwv[18]= 0 (0x0) smb_vwv[19]= 0 (0x0) smb_vwv[20]= 0 (0x0) smb_vwv[21]=32768 (0x8000) smb_vwv[22]= 0 (0x0) smb_vwv[23]= 0 (0x0) smb_vwv[24]= 0 (0x0) smb_vwv[25]= 0 (0x0) smb_vwv[26]= 0 (0x0) smb_vwv[27]= 0 (0x0) smb_vwv[28]= 0 (0x0) smb_vwv[29]= 0 (0x0) smb_vwv[30]= 0 (0x0) smb_vwv[31]= 512 (0x200) smb_vwv[32]=65280 (0xFF00) smb_vwv[33]= 5 (0x5) smb_bcc=0 [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 10] lib/util_sock.c:read_smb_length_return_keepalive(1118) got smb length of 207 [2009/09/04 11:26:52, 6] smbd/process.c:process_smb(1547) got message type 0x0 of len 0xcf [2009/09/04 11:26:52, 3] smbd/process.c:process_smb(1550) Transaction 5 of length 211 (0 toread) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=207 smb_com=0x25 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=6 smt_wct=16 smb_vwv[ 0]= 0 (0x0) smb_vwv[ 1]= 125 (0x7D) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 4280 (0x10B8) 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]= 0 (0x0) smb_vwv[10]= 82 (0x52) smb_vwv[11]= 125 (0x7D) smb_vwv[12]= 82 (0x52) smb_vwv[13]= 2 (0x2) smb_vwv[14]= 38 (0x26) smb_vwv[15]=29632 (0x73C0) smb_bcc=140 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 00 5C 00 50 00 49 00 50 00 45 00 5C 00 00 00 05 .\.P.I.P .E.\.... [010] 00 0B 03 10 00 00 00 7D 00 2D 00 01 00 00 00 B8 .......} .-...... [020] 10 B8 10 00 00 00 00 01 00 00 00 00 00 01 00 78 ........ .......x [030] 57 34 12 34 12 CD AB EF 00 01 23 45 67 89 AC 01 W4.4.... ..#Eg... [040] 00 00 00 04 5D 88 8A EB 1C C9 11 9F E8 08 00 2B ....]... .......+ [050] 10 48 60 02 00 00 00 0A 06 00 00 01 00 00 00 4E .H`..... .......N [060] 54 4C 4D 53 53 50 00 01 00 00 00 35 82 08 60 09 TLMSSP.. ...5..`. [070] 00 09 00 20 00 00 00 04 00 04 00 29 00 00 00 57 ... .... ...)...W [080] 4F 52 4B 47 52 4F 55 50 58 30 39 33 ORKGROUP X093 [2009/09/04 11:26:52, 3] smbd/process.c:switch_message(1361) switch message SMBtrans (pid 2876) conn 0xb8407070 [2009/09/04 11:26:52, 0] smbd/process.c:smb_dump(1309) created /tmp/SMBtrans.13.req len 211 [2009/09/04 11:26:52, 4] smbd/uid.c:change_to_user(225) change_to_user: Skipping user change - already user [2009/09/04 11:26:52, 3] smbd/ipc.c:handle_trans(436) trans <\PIPE\> data=125 params=0 setup=2 [2009/09/04 11:26:52, 5] smbd/ipc.c:handle_trans(469) calling named_pipe [2009/09/04 11:26:52, 3] smbd/ipc.c:named_pipe(387) named pipe command on <> name [2009/09/04 11:26:52, 5] smbd/ipc.c:api_fd_reply(307) api_fd_reply [2009/09/04 11:26:52, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1258) search for pipe pnum=73c0 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1262) pipe name samr pnum=73c0 (pipes_open=1) [2009/09/04 11:26:52, 3] smbd/ipc.c:api_fd_reply(345) Got API command 0x26 on pipe "samr" (pnum 73c0) [2009/09/04 11:26:52, 10] smbd/ipc.c:api_fd_reply(350) api_fd_reply: p:0xb8401b28 max_trans_reply: 4280 [2009/09/04 11:26:52, 6] rpc_server/srv_pipe_hnd.c:write_to_pipe(927) write_to_pipe: 73c0 name: samr open: Yes len: 125 [2009/09/04 11:26:52, 50] lib/util.c:dump_data(2223) [000] 05 00 0B 03 10 00 00 00 7D 00 2D 00 01 00 00 00 ........ }.-..... [010] B8 10 B8 10 00 00 00 00 01 00 00 00 00 00 01 00 ........ ........ [020] 78 57 34 12 34 12 CD AB EF 00 01 23 45 67 89 AC xW4.4... ...#Eg.. [030] 01 00 00 00 04 5D 88 8A EB 1C C9 11 9F E8 08 00 .....].. ........ [040] 2B 10 48 60 02 00 00 00 0A 06 00 00 01 00 00 00 +.H`.... ........ [050] 4E 54 4C 4D 53 53 50 00 01 00 00 00 35 82 08 60 NTLMSSP. ....5..` [060] 09 00 09 00 20 00 00 00 04 00 04 00 29 00 00 00 .... ... ....)... [070] 57 4F 52 4B 47 52 4F 55 50 58 30 39 33 WORKGROU PX093 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 125 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 0, pdu_needed_len = 0, incoming data = 125 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:fill_rpc_header(385) fill_rpc_header: data_to_copy = 125, len_needed_to_complete_hdr = 16, receive_len = 0 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 16 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 109 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 0, incoming data = 109 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0000 major : 05 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0001 minor : 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0002 pkt_type : 0b [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0003 flags : 03 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0004 pack_type0: 10 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0005 pack_type1: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0006 pack_type2: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0007 pack_type3: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0008 frag_len : 007d [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 000a auth_len : 002d [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 000c call_id : 00000001 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(472) unmarshall_rpc_header: using little-endian RPC [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(501) unmarshall_rpc_header: type = 11, flags = 3 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 0 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 109 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 109, incoming data = 109 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_complete_pdu(709) process_complete_pdu: processing packet type 11 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe.c:api_pipe_bind_req(1553) api_pipe_bind_req: decode request. 1553 [2009/09/04 11:26:52, 3] rpc_server/srv_pipe.c:api_pipe_bind_req(1564) api_pipe_bind_req: \PIPE\samr -> \PIPE\samr [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr_rb [2009/09/04 11:26:52, 6] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr_bba [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0000 max_tsize: 10b8 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0002 max_rsize: 10b8 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0004 assoc_gid: 00000000 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0008 num_contexts: 01 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 000c context_id : 0000 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 000e num_transfer_syntaxes: 01 [2009/09/04 11:26:52, 6] rpc_parse/parse_prs.c:prs_debug(88) 00000f smb_io_rpc_iface [2009/09/04 11:26:52, 7] rpc_parse/parse_prs.c:prs_debug(88) 000010 smb_io_uuid uuid [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0010 data : 12345778 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0014 data : 1234 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0016 data : abcd [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8s(865) 0018 data : ef 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8s(865) 001a data : 01 23 45 67 89 ac [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0020 version: 00000001 [2009/09/04 11:26:52, 6] rpc_parse/parse_prs.c:prs_debug(88) 000024 smb_io_rpc_iface [2009/09/04 11:26:52, 7] rpc_parse/parse_prs.c:prs_debug(88) 000024 smb_io_uuid uuid [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0024 data : 8a885d04 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0028 data : 1ceb [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 002a data : 11c9 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8s(865) 002c data : 9f e8 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8s(865) 002e data : 08 00 2b 10 48 60 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0034 version: 00000002 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe.c:api_pipe_bind_req(1608) api_pipe_bind_req: make response. 1608 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000038 smb_io_rpc_hdr_auth [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0038 auth_type : 0a [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0039 auth_level : 06 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 003a auth_pad_len : 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 003b auth_reserved: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 003c auth_context_id: 00000001 [2009/09/04 11:26:52, 5] auth/auth.c:make_auth_context_subsystem(491) Making default auth method list for standalone security=user, encrypt passwords = yes [2009/09/04 11:26:52, 5] auth/auth.c:load_auth_module(387) load_auth_module: Attempting to find an auth method to match guest [2009/09/04 11:26:52, 5] auth/auth.c:load_auth_module(412) load_auth_module: auth method guest has a valid init [2009/09/04 11:26:52, 5] auth/auth.c:load_auth_module(387) load_auth_module: Attempting to find an auth method to match sam [2009/09/04 11:26:52, 5] auth/auth.c:load_auth_module(412) load_auth_module: auth method sam has a valid init [2009/09/04 11:26:52, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62) Got NTLMSSP neg_flags=0x60088235 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_SEAL NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2009/09/04 11:26:52, 5] auth/auth.c:get_ntlm_challenge(96) auth_get_challenge: module guest did not want to specify a challenge [2009/09/04 11:26:52, 5] auth/auth.c:get_ntlm_challenge(96) auth_get_challenge: module sam did not want to specify a challenge [2009/09/04 11:26:52, 5] auth/auth.c:get_ntlm_challenge(136) auth_context challenge created by random [2009/09/04 11:26:52, 5] auth/auth.c:get_ntlm_challenge(137) challenge is: [2009/09/04 11:26:52, 5] lib/util.c:dump_data(2223) [000] 93 A4 CF 8B 53 C8 42 24 ....S.B$ [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr_auth [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0000 auth_type : 0a [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0001 auth_level : 06 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0002 auth_pad_len : 08 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0003 auth_reserved: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0004 auth_context_id: 00000001 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe.c:pipe_ntlmssp_auth_bind(1486) pipe_ntlmssp_auth_bind: NTLMSSP auth started [2009/09/04 11:26:52, 3] rpc_server/srv_pipe.c:check_bind_req(991) check_bind_req for \PIPE\samr checking \PIPE\lsarpc checking \PIPE\lsarpc checking \PIPE\samr [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr_ba [2009/09/04 11:26:52, 6] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr_bba [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0000 max_tsize: 10b8 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0002 max_rsize: 10b8 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0004 assoc_gid: 00007a77 [2009/09/04 11:26:52, 6] rpc_parse/parse_prs.c:prs_debug(88) 000008 smb_io_rpc_addr_str [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0008 len: 000b [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8s(865) 000a str: \PIPE\samr. [2009/09/04 11:26:52, 6] rpc_parse/parse_prs.c:prs_debug(88) 000015 smb_io_rpc_results [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0018 num_results: 01 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 001c result : 0000 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 001e reason : 0000 [2009/09/04 11:26:52, 6] rpc_parse/parse_prs.c:prs_debug(88) 000020 smb_io_rpc_iface [2009/09/04 11:26:52, 7] rpc_parse/parse_prs.c:prs_debug(88) 000020 smb_io_uuid uuid [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0020 data : 8a885d04 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0024 data : 1ceb [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0026 data : 11c9 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8s(865) 0028 data : 9f e8 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8s(865) 002a data : 08 00 2b 10 48 60 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0030 version: 00000002 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0000 major : 05 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0001 minor : 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0002 pkt_type : 0c [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0003 flags : 03 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0004 pack_type0: 10 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0005 pack_type1: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0006 pack_type2: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0007 pack_type3: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0008 frag_len : 00c4 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 000a auth_len : 0078 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 000c call_id : 00000001 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 109 [2009/09/04 11:26:52, 6] rpc_server/srv_pipe_hnd.c:read_from_pipe(985) read_from_pipe: 73c0 name: samr len: 4280 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:read_from_internal_pipe(1045) read_from_pipe: samr: current_pdu_len = 196, current_pdu_sent = 0 returning 196 bytes. [2009/09/04 11:26:52, 5] smbd/ipc.c:copy_trans_params_and_data(60) copy_trans_params_and_data: params[0..0] data[0..196] (align 0) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=252 smb_com=0x25 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=6 smt_wct=10 smb_vwv[ 0]= 0 (0x0) smb_vwv[ 1]= 196 (0xC4) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 196 (0xC4) smb_vwv[ 7]= 56 (0x38) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=197 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 00 05 00 0C 03 10 00 00 00 C4 00 78 00 01 00 00 ........ ...x.... [010] 00 B8 10 B8 10 77 7A 00 00 0B 00 5C 50 49 50 45 .....wz. ...\PIPE [020] 5C 73 61 6D 72 00 00 00 00 01 00 00 00 00 00 00 \samr... ........ [030] 00 04 5D 88 8A EB 1C C9 11 9F E8 08 00 2B 10 48 ..]..... .....+.H [040] 60 02 00 00 00 0A 06 08 00 01 00 00 00 4E 54 4C `....... .....NTL [050] 4D 53 53 50 00 02 00 00 00 08 00 08 00 30 00 00 MSSP.... .....0.. [060] 00 35 82 8A 60 93 A4 CF 8B 53 C8 42 24 00 00 00 .5..`... .S.B$... [070] 00 00 00 00 00 40 00 40 00 38 00 00 00 58 00 30 .....@.@ .8...X.0 [080] 00 39 00 33 00 02 00 08 00 58 00 30 00 39 00 33 .9.3.... .X.0.9.3 [090] 00 01 00 08 00 58 00 30 00 39 00 33 00 04 00 0C .....X.0 .9.3.... [0A0] 00 68 00 6B 00 75 00 2E 00 68 00 6B 00 03 00 10 .h.k.u.. .h.k.... [0B0] 00 78 00 2E 00 68 00 6B 00 75 00 2E 00 68 00 6B .x...h.k .u...h.k [0C0] 00 00 00 00 00 ..... [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 10] lib/util_sock.c:read_smb_length_return_keepalive(1118) got smb length of 238 [2009/09/04 11:26:52, 6] smbd/process.c:process_smb(1547) got message type 0x0 of len 0xee [2009/09/04 11:26:52, 3] smbd/process.c:process_smb(1550) Transaction 6 of length 242 (0 toread) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=238 smb_com=0x2f smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=7 smt_wct=14 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]=29632 (0x73C0) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 8 (0x8) smb_vwv[ 8]= 174 (0xAE) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 174 (0xAE) smb_vwv[11]= 64 (0x40) smb_vwv[12]= 0 (0x0) smb_vwv[13]= 0 (0x0) smb_bcc=175 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 00 05 00 10 03 10 00 00 00 AE 00 92 00 01 00 00 ........ ........ [010] 00 00 00 00 00 0A 06 00 00 01 00 00 00 4E 54 4C ........ .....NTL [020] 4D 53 53 50 00 03 00 00 00 18 00 18 00 40 00 00 MSSP.... .....@.. [030] 00 18 00 18 00 58 00 00 00 00 00 00 00 70 00 00 .....X.. .....p.. [040] 00 0A 00 0A 00 70 00 00 00 08 00 08 00 7A 00 00 .....p.. .....z.. [050] 00 10 00 10 00 82 00 00 00 35 82 08 60 D0 63 50 ........ .5..`.cP [060] 71 5F 24 C1 F7 00 00 00 00 00 00 00 00 00 00 00 q_$..... ........ [070] 00 00 00 00 00 4B 84 F2 FE 68 94 56 A9 5A ED 72 .....K.. .h.V.Z.r [080] FB A6 64 94 1D 3B 35 95 F2 A8 A5 69 BB 75 00 73 ..d..;5. ...i.u.s [090] 00 65 00 72 00 31 00 58 00 30 00 39 00 33 00 4A .e.r.1.X .0.9.3.J [0A0] 09 7B 1E 42 E9 AD D1 70 2F 4C 78 2A 0E D2 78 .{.B...p /Lx*..x [2009/09/04 11:26:52, 3] smbd/process.c:switch_message(1361) switch message SMBwriteX (pid 2876) conn 0xb8407070 [2009/09/04 11:26:52, 0] smbd/process.c:smb_dump(1309) created /tmp/SMBwriteX.5.req len 242 [2009/09/04 11:26:52, 4] smbd/uid.c:change_to_user(225) change_to_user: Skipping user change - already user [2009/09/04 11:26:52, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1258) search for pipe pnum=73c0 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1262) pipe name samr pnum=73c0 (pipes_open=1) [2009/09/04 11:26:52, 6] rpc_server/srv_pipe_hnd.c:write_to_pipe(927) write_to_pipe: 73c0 name: samr open: Yes len: 174 [2009/09/04 11:26:52, 50] lib/util.c:dump_data(2223) [000] 05 00 10 03 10 00 00 00 AE 00 92 00 01 00 00 00 ........ ........ [010] 00 00 00 00 0A 06 00 00 01 00 00 00 4E 54 4C 4D ........ ....NTLM [020] 53 53 50 00 03 00 00 00 18 00 18 00 40 00 00 00 SSP..... ....@... [030] 18 00 18 00 58 00 00 00 00 00 00 00 70 00 00 00 ....X... ....p... [040] 0A 00 0A 00 70 00 00 00 08 00 08 00 7A 00 00 00 ....p... ....z... [050] 10 00 10 00 82 00 00 00 35 82 08 60 D0 63 50 71 ........ 5..`.cPq [060] 5F 24 C1 F7 00 00 00 00 00 00 00 00 00 00 00 00 _$...... ........ [070] 00 00 00 00 4B 84 F2 FE 68 94 56 A9 5A ED 72 FB ....K... h.V.Z.r. [080] A6 64 94 1D 3B 35 95 F2 A8 A5 69 BB 75 00 73 00 .d..;5.. ..i.u.s. [090] 65 00 72 00 31 00 58 00 30 00 39 00 33 00 4A 09 e.r.1.X. 0.9.3.J. [0A0] 7B 1E 42 E9 AD D1 70 2F 4C 78 2A 0E D2 78 {.B...p/ Lx*..x [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 174 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 0, pdu_needed_len = 0, incoming data = 174 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:fill_rpc_header(385) fill_rpc_header: data_to_copy = 174, len_needed_to_complete_hdr = 16, receive_len = 0 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 16 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 158 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 0, incoming data = 158 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0000 major : 05 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0001 minor : 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0002 pkt_type : 10 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0003 flags : 03 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0004 pack_type0: 10 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0005 pack_type1: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0006 pack_type2: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0007 pack_type3: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0008 frag_len : 00ae [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 000a auth_len : 0092 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 000c call_id : 00000001 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(472) unmarshall_rpc_header: using little-endian RPC [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(501) unmarshall_rpc_header: type = 16, flags = 3 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 0 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 158 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 158, incoming data = 158 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_complete_pdu(709) process_complete_pdu: processing packet type 16 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe.c:api_pipe_bind_auth3(736) api_pipe_bind_auth3: decode request. 736 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0000 pad: 00000000 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000004 smb_io_rpc_hdr_auth [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0004 auth_type : 0a [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0005 auth_level : 06 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0006 auth_pad_len : 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0007 auth_reserved: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0008 auth_context_id: 00000001 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe.c:pipe_ntlmssp_verify_final(613) pipe_ntlmssp_verify_final: pipe samr checking user details [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(108, 100) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(745) Got user=[user1] domain=[] workstation=[X093] len1=24 len2=24 [2009/09/04 11:26:52, 5] auth/auth_ntlmssp.c:auth_ntlmssp_set_challenge(68) auth_context challenge set by NTLMSSP callback (NTLM2) [2009/09/04 11:26:52, 5] auth/auth_ntlmssp.c:auth_ntlmssp_set_challenge(69) challenge is: [2009/09/04 11:26:52, 5] lib/util.c:dump_data(2223) [000] D8 24 41 63 00 D1 B4 A0 .$Ac.... [2009/09/04 11:26:52, 6] param/loadparm.c:lp_file_list_changed(6699) lp_file_list_changed() file /10/samba-3.2.6/lib/smb.conf -> /10/samba-3.2.6/lib/smb.conf last mod_time: Fri Sep 4 11:19:32 2009 [2009/09/04 11:26:52, 5] auth/auth_util.c:make_user_info_map(206) make_user_info_map: Mapping user []\[user1] from workstation [X093] [2009/09/04 11:26:52, 5] auth/auth_util.c:make_user_info(120) attempting to make a user_info for user1 (user1) [2009/09/04 11:26:52, 5] auth/auth_util.c:make_user_info(130) making strings for user1's user_info struct [2009/09/04 11:26:52, 5] auth/auth_util.c:make_user_info(162) making blobs for user1's user_info struct [2009/09/04 11:26:52, 10] auth/auth_util.c:make_user_info(180) made an encrypted user_info for user1 (user1) [2009/09/04 11:26:52, 3] auth/auth.c:check_ntlm_password(220) check_ntlm_password: Checking password for unmapped user []\[user1]@[X093] with the new password interface [2009/09/04 11:26:52, 3] auth/auth.c:check_ntlm_password(223) check_ntlm_password: mapped user is: [X093]\[user1]@[X093] [2009/09/04 11:26:52, 10] auth/auth.c:check_ntlm_password(232) check_ntlm_password: auth_context challenge created by NTLMSSP callback (NTLM2) [2009/09/04 11:26:52, 10] auth/auth.c:check_ntlm_password(234) challenge is: [2009/09/04 11:26:52, 5] lib/util.c:dump_data(2223) [000] D8 24 41 63 00 D1 B4 A0 .$Ac.... [2009/09/04 11:26:52, 10] auth/auth.c:check_ntlm_password(260) check_ntlm_password: guest had nothing to say [2009/09/04 11:26:52, 8] lib/util.c:is_myname(2098) is_myname("X093") returns 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_unpack(655) tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 5 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 6 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 7 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 8 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 9 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 10 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 21 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_username(580) pdb_set_username: setting username user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 12 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_domain(603) pdb_set_domain: setting domain X093, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 14 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_nt_username(626) pdb_set_nt_username: setting nt username , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 15 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_fullname(649) pdb_set_full_name: setting full name , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 13 -> now SET [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_homedir(742) pdb_set_homedir: setting home dir \\x093\user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 1 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(718) pdb_set_dir_drive: setting dir drive F:, was NULL [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 3 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_logon_script(672) pdb_set_logon_script: setting logon script , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 4 -> now DEFAULT [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_profile_path(695) pdb_set_profile_path: setting profile path \\x093\user1\profile, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 2 -> now DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 23 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_workstations(785) pdb_set_workstations: setting workstations , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 24 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 26 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 32 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 33 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 34 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_user_sid(509) pdb_set_user_sid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 18 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 from rid 1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 16 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 29 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 30 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 31 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 20 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 17 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 27 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: maximum password age, val: -1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_alloc(133) Finding user user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(77) Trying _Get_Pwnam(), username as lowercase is user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(110) Get_Pwnam_internals did find user [user1]! [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 3: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 1: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 4: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 2: DEFAULT [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_pack_va(501) tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 198 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_pack_va(501) tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_unpack(655) tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 5 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 6 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 7 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 8 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 9 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 10 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 21 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_username(580) pdb_set_username: setting username user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 12 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_domain(603) pdb_set_domain: setting domain X093, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 14 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_nt_username(626) pdb_set_nt_username: setting nt username , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 15 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_fullname(649) pdb_set_full_name: setting full name , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 13 -> now SET [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_homedir(742) pdb_set_homedir: setting home dir \\x093\user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 1 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(718) pdb_set_dir_drive: setting dir drive F:, was NULL [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 3 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_logon_script(672) pdb_set_logon_script: setting logon script , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 4 -> now DEFAULT [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_profile_path(695) pdb_set_profile_path: setting profile path \\x093\user1\profile, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 2 -> now DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 23 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_workstations(785) pdb_set_workstations: setting workstations , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 24 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 26 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 32 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 33 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 34 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_user_sid(509) pdb_set_user_sid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 18 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 from rid 1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 16 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 29 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 30 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 31 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 20 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 17 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 27 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 9] passdb/passdb.c:pdb_update_autolock_flag(1417) pdb_update_autolock_flag: Account user1 not autolocked, no check needed [2009/09/04 11:26:52, 4] libsmb/ntlm_check.c:ntlm_password_check(328) ntlm_password_check: Checking NT MD4 password [2009/09/04 11:26:52, 4] auth/auth_sam.c:sam_account_ok(137) sam_account_ok: Checking SMB password for user user1 [2009/09/04 11:26:52, 5] auth/auth_sam.c:logon_hours_ok(119) logon_hours_ok: user user1 allowed to logon at this time (Fri Sep 4 03:26:52 2009 ) [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: maximum password age, val: -1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/system_smbd.c:sys_getgrouplist(122) sys_getgrouplist: user [user1] [2009/09/04 11:26:52, 5] auth/auth_util.c:make_server_info_sam(650) make_server_info_sam: made server info for user user1 -> user1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] auth/auth.c:check_ntlm_password(269) check_ntlm_password: sam authentication for user [user1] succeeded [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/auth.c:check_ntlm_password(295) check_ntlm_password: PAM Account for user [user1] succeeded [2009/09/04 11:26:52, 2] auth/auth.c:check_ntlm_password(308) check_ntlm_password: authentication for user [user1] -> [user1] -> [user1] succeeded [2009/09/04 11:26:52, 5] auth/auth_util.c:free_user_info(1985) attempting to free (and zero) a user_info structure [2009/09/04 11:26:52, 10] auth/auth_util.c:free_user_info(1989) structure was created for user1 [2009/09/04 11:26:52, 10] auth/token_util.c:create_local_nt_token(304) Create local NT token for S-1-5-21-3850432308-4052430627-3054637991-1216 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-5-32-544 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-5-32-545 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] lib/privileges.c:get_privileges(63) get_privileges: No privileges assigned to SID [S-1-5-21-3850432308-4052430627-3054637991-1216] [2009/09/04 11:26:52, 3] lib/privileges.c:get_privileges(63) get_privileges: No privileges assigned to SID [S-1-22-2-100] [2009/09/04 11:26:52, 5] lib/privileges.c:get_privileges_for_sids(128) get_privileges_for_sids: sid = S-1-1-0 Privilege set: SE_PRIV 0x0 0x0 0x0 0x0 [2009/09/04 11:26:52, 3] lib/privileges.c:get_privileges(63) get_privileges: No privileges assigned to SID [S-1-5-2] [2009/09/04 11:26:52, 3] lib/privileges.c:get_privileges(63) get_privileges: No privileges assigned to SID [S-1-5-11] [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:sid_to_gid(1413) sid S-1-22-2-100 -> gid 100 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-1-0 [2009/09/04 11:26:52, 10] auth/auth_util.c:create_local_token(755) Could not convert SID S-1-1-0 to gid, ignoring it [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-5-2 [2009/09/04 11:26:52, 10] auth/auth_util.c:create_local_token(755) Could not convert SID S-1-5-2 to gid, ignoring it [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 10] passdb/lookup_sid.c:legacy_sid_to_gid(1244) LEGACY: mapping failed for sid S-1-5-11 [2009/09/04 11:26:52, 10] auth/auth_util.c:create_local_token(755) Could not convert SID S-1-5-11 to gid, ignoring it [2009/09/04 11:26:52, 10] auth/token_util.c:debug_nt_user_token(472) NT user token of user S-1-5-21-3850432308-4052430627-3054637991-1216 contains 5 SIDs SID[ 0]: S-1-5-21-3850432308-4052430627-3054637991-1216 SID[ 1]: S-1-22-2-100 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SE_PRIV 0x0 0x0 0x0 0x0 [2009/09/04 11:26:52, 10] auth/auth_ntlmssp.c:auth_ntlmssp_check_password(137) Got NT session key of length 16 [2009/09/04 11:26:52, 10] auth/auth_ntlmssp.c:auth_ntlmssp_check_password(144) Got LM session key of length 16 [2009/09/04 11:26:52, 10] libsmb/ntlmssp.c:ntlmssp_server_auth(811) ntlmssp_server_auth: Created NTLM2 session key. [2009/09/04 11:26:52, 3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(337) NTLMSSP Sign/Seal - Initialising with flags: [2009/09/04 11:26:52, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62) Got NTLMSSP neg_flags=0x60088235 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_SEAL NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (108, 100) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe.c:pipe_ntlmssp_verify_final(666) pipe_ntlmssp_verify_final: OK: user: user1 domain: workstation: X093 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 158 [2009/09/04 11:26:52, 3] smbd/pipes.c:reply_pipe_write_and_X(251) writeX-IPC pnum=73c0 nwritten=174 [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=47 smb_com=0x2f smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=7 smt_wct=6 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 174 (0xAE) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_bcc=0 [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 11] lib/events.c:run_events(257) run_events: Nothing to do [2009/09/04 11:26:52, 10] lib/util_sock.c:read_smb_length_return_keepalive(1118) got smb length of 1298 [2009/09/04 11:26:52, 6] smbd/process.c:process_smb(1547) got message type 0x0 of len 0x512 [2009/09/04 11:26:52, 3] smbd/process.c:process_smb(1550) Transaction 7 of length 1302 (0 toread) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:52, 5] lib/util.c:show_msg(652) size=1298 smb_com=0x25 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=8 smt_wct=16 smb_vwv[ 0]= 0 (0x0) smb_vwv[ 1]= 1216 (0x4C0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 4280 (0x10B8) 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]= 0 (0x0) smb_vwv[10]= 82 (0x52) smb_vwv[11]= 1216 (0x4C0) smb_vwv[12]= 82 (0x52) smb_vwv[13]= 2 (0x2) smb_vwv[14]= 38 (0x26) smb_vwv[15]=29632 (0x73C0) smb_bcc=1231 [2009/09/04 11:26:52, 10] lib/util.c:dump_data(2223) [000] 00 5C 00 50 00 49 00 50 00 45 00 5C 00 00 00 05 .\.P.I.P .E.\.... [010] 00 00 03 10 00 00 00 C0 04 10 00 02 00 00 00 8C ........ ........ [020] 04 00 00 00 00 37 00 84 AC BA E1 60 A8 E9 C7 7C .....7.. ...`...| [030] 6F 55 35 3B A2 45 57 5F C2 82 E0 3B EB D7 94 DE oU5;.EW_ ...;.... [040] A7 29 AF C1 06 2F A3 39 EB 30 C7 3B 81 21 46 9E .).../.9 .0.;.!F. [050] C0 EF 84 0D FD DA 0E 19 C5 25 75 5E 5D 80 28 26 ........ .%u^].(& [060] 2A 12 C2 33 0A EE 53 32 70 D4 AE F1 ED 63 AC CF *..3..S2 p....c.. [070] 93 46 9D AC 00 A3 3D 4B B8 D9 E8 C7 93 44 26 D2 .F....=K .....D&. [080] C1 D9 85 96 F7 DE 14 81 B0 EF 62 E2 00 63 06 4D ........ ..b..c.M [090] 73 EA 7A 6F 6B CF D3 BF EF D7 F4 8D 67 B2 F0 A7 s.zok... ....g... [0A0] 1D F6 C9 E3 98 B0 67 F4 BF 37 78 51 15 DF 19 C6 ......g. .7xQ.... [0B0] 70 0F F5 C3 31 17 1E 6F 40 1E 41 0D C2 97 3F 57 p...1..o @.A...?W [0C0] D4 7C 4C 06 90 75 4B 30 DE 89 E1 EB 3E D7 9D 5D .|L..uK0 ....>..] [0D0] B8 33 DF 5D 93 FC ED 06 D1 81 E5 6A 8B 7A CC EC .3.].... ...j.z.. [0E0] 3E D9 3E 16 2A CF 4B E2 99 BD 8A 3D 26 D8 19 53 >.>.*.K. ...=&..S [0F0] C0 DF B8 DD D9 28 9F AC 18 4C A7 3D A8 B3 CD 1B .....(.. .L.=.... [100] ED 69 2F A3 C0 5F D7 B8 C7 FE F4 FB D6 42 80 15 .i/.._.. .....B.. [110] AA 28 35 11 28 E4 8C 2B 59 EA 25 5B D1 29 53 2B .(5.(..+ Y.%[.)S+ [120] 8D C0 F5 A3 40 BC 9D 68 E9 8D C6 5A 53 72 AD 8B ....@..h ...ZSr.. [130] 19 C3 DC 09 73 47 B7 06 ED 54 FB EC A8 A2 67 A2 ....sG.. .T....g. [140] 32 F3 5A E1 E2 29 2C EB 3F 65 CE 99 33 EB 6F C8 2.Z..),. ?e..3.o. [150] C2 D1 48 04 6A 1E F5 F4 CE 58 45 45 4F 4A 42 5B ..H.j... .XEEOJB[ [160] 9F A9 0E 72 AC C1 3F 03 AC F4 AC 81 12 DC 56 BD ...r..?. ......V. [170] 9D DF 5C 41 6E C8 6E F9 1C E8 14 BD AE 3F CB 6D ..\An.n. .....?.m [180] 9D FE 51 EC AB 26 BE 6C AA 0B CE 06 A1 E8 91 63 ..Q..&.l .......c [190] 60 C6 38 37 32 87 91 CD 48 3F 1C CA 40 13 7F BA `.872... H?..@... [1A0] 2A 6C 6E FC FC 0B FD BC F4 46 C5 99 FA 1B 0B FB *ln..... .F...... [1B0] 27 6C 16 FE 1B 8B 97 58 E1 D0 82 86 CF 65 67 4C 'l.....X .....egL [1C0] 35 B6 CD 82 C0 4B 93 8A 62 93 5F 9B 2E 5E 90 97 5....K.. b._..^.. [1D0] AC F6 DF EC 07 2C BD A1 BA BA 7E 41 C5 52 C1 88 .....,.. ..~A.R.. [1E0] 7F 2F 6A CA CE FC D0 91 1C D9 D1 95 F6 68 AA 33 ./j..... .....h.3 [1F0] B6 E6 71 8A 92 97 62 ED 58 43 A4 A6 0A F7 AC FF ..q...b. XC...... [200] 54 E7 CB 36 7E 4B 68 12 B6 D1 2B E9 28 CB CF 51 T..6~Kh. ..+.(..Q [210] 1B 5F F9 98 93 B4 3A 42 F1 CB 1B D3 5E 09 2B B7 ._....:B ....^.+. [220] AB AF 26 CA F6 B8 4E 0A 2A 53 D3 29 25 61 80 41 ..&...N. *S.)%a.A [230] E3 C2 AF 75 BE 6E 78 36 4D B0 39 2A F7 74 D4 5C ...u.nx6 M.9*.t.\ [240] 51 82 7A 94 82 45 96 0D CC B9 10 C6 0A 4C BD 0F Q.z..E.. .....L.. [250] 0C 2C C3 3E 9B ED 03 71 73 CA F1 4E FF 1A 89 5A .,.>...q s..N...Z [260] 47 1C F4 F0 F1 2C E0 3B 79 6A 91 C8 94 1F 4A 57 G....,.; yj....JW [270] C6 BB A9 EF 25 E9 4F 16 55 80 14 FD 97 C6 26 E9 ....%.O. U.....&. [280] 3F A7 B3 F8 C9 D0 95 B6 D1 8A 5C 0B B7 34 29 6F ?....... ..\..4)o [290] C1 30 0E CB 16 5E 65 11 9D 23 09 8D 45 43 0C 1C .0...^e. .#..EC.. [2A0] F8 6F 5F 16 D0 8C A0 01 69 FF AC 78 8F 02 74 41 .o_..... i..x..tA [2B0] A1 86 D2 C3 A0 DC 92 95 A0 B5 51 D0 78 08 11 A0 ........ ..Q.x... [2C0] 51 BF 5F C3 B9 E9 11 FD 1B 23 9E D0 BB 21 84 46 Q._..... .#...!.F [2D0] F3 AF 59 89 C6 4D E8 3C 64 F7 53 8D FF 0C F8 BD ..Y..M.< d.S..... [2E0] 72 73 5A 2B 51 84 0F 29 1B 91 7A 36 50 4B 09 95 rsZ+Q..) ..z6PK.. [2F0] 9C 66 E6 82 C2 C8 6E CA 3C A9 03 E2 1F AC A6 8C .f....n. <....... [300] E9 BA 2A F5 C1 06 AD 31 C6 FD 74 7E 08 67 C4 E6 ..*....1 ..t~.g.. [310] AC 2F 5A B6 36 41 99 55 CB 89 F2 0F 3D 60 22 99 ./Z.6A.U ....=`". [320] 6C 74 14 55 AD D5 C7 FB 52 AB 3C 54 18 DA 3C 8C lt.U.... R... [370] 5D BD C6 EA 66 98 3E 77 9D 17 B8 38 66 AB CE 91 ]...f.>w ...8f... [380] 4F 90 C5 9F A7 3A 1B 7F 5C F8 80 CD 8D 99 64 2B O....:.. \.....d+ [390] 36 9A 94 FC 8A 2B 11 5A 2D 54 45 58 F6 20 51 FB 6....+.Z -TEX. Q. [3A0] 6B 6C 4C C7 C8 EC FB 19 66 3E 0A 86 9F 14 52 5D klL..... f>....R] [3B0] 60 70 7D 9F 15 95 A6 57 2C 24 63 D3 DB 0E 12 91 `p}....W ,$c..... [3C0] 6F C2 89 BF DB 00 E4 87 5A 69 BC 10 A9 FB 12 CA o....... Zi...... [3D0] 50 67 04 B1 BF FB B8 FD C7 B7 EA 7D 4A A1 75 5B Pg...... ...}J.u[ [3E0] DF 70 91 E2 87 45 5B EC 5D 3C C1 8F 54 9D 05 A6 .p...E[. ]<..T... [3F0] 65 9E 9B 21 C3 30 36 F1 85 41 2F C4 6A 53 3D DD e..!.06. .A/.jS=. [400] E8 49 CD E5 5F 92 27 8E C2 DE 3B CF 18 E2 D5 B6 .I.._.'. ..;..... [410] 0B 59 CB 54 21 D1 38 26 BE EF 43 38 C6 22 F0 A0 .Y.T!.8& ..C8.".. [420] 47 67 A2 46 CD 17 07 88 A5 FE 0B B4 8B C5 A3 A9 Gg.F.... ........ [430] D2 C2 32 27 59 46 E7 1B 4E BC 10 E8 13 55 7E A5 ..2'YF.. N....U~. [440] C5 0E C7 44 DE 62 88 59 52 0B A0 55 4E C8 9B EA ...D.b.Y R..UN... [450] 0A 1C 29 B8 48 0C E9 47 C5 AA DE 1B 6F 59 11 E1 ..).H..G ....oY.. [460] E6 1A 0B D5 A0 60 A0 97 07 06 0F 00 12 B3 30 A7 .....`.. ......0. [470] 73 E3 B8 B9 0E D7 9F 0F CD ED 20 FD E5 08 BE 4A s....... .. ....J [480] F0 26 AB 87 4D 34 FE 01 4C 52 1F 28 33 91 75 31 .&..M4.. LR.(3.u1 [490] A7 91 D4 38 12 32 EA 12 0E B9 57 EC C5 43 45 E8 ...8.2.. ..W..CE. [4A0] BC 61 54 79 19 83 43 22 F1 44 7A 27 66 0E 62 98 .aTy..C" .Dz'f.b. [4B0] 66 9F 9F 80 33 70 C9 0A 06 04 00 01 00 00 00 01 f...3p.. ........ [4C0] 00 00 00 95 83 10 11 FB 21 B8 95 00 00 00 00 ........ !...... [2009/09/04 11:26:52, 3] smbd/process.c:switch_message(1361) switch message SMBtrans (pid 2876) conn 0xb8407070 [2009/09/04 11:26:52, 0] smbd/process.c:smb_dump(1309) created /tmp/SMBtrans.14.req len 1302 [2009/09/04 11:26:52, 4] smbd/uid.c:change_to_user(225) change_to_user: Skipping user change - already user [2009/09/04 11:26:52, 3] smbd/ipc.c:handle_trans(436) trans <\PIPE\> data=1216 params=0 setup=2 [2009/09/04 11:26:52, 5] smbd/ipc.c:handle_trans(469) calling named_pipe [2009/09/04 11:26:52, 3] smbd/ipc.c:named_pipe(387) named pipe command on <> name [2009/09/04 11:26:52, 5] smbd/ipc.c:api_fd_reply(307) api_fd_reply [2009/09/04 11:26:52, 4] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1258) search for pipe pnum=73c0 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe_hnd.c:get_rpc_pipe(1262) pipe name samr pnum=73c0 (pipes_open=1) [2009/09/04 11:26:52, 3] smbd/ipc.c:api_fd_reply(345) Got API command 0x26 on pipe "samr" (pnum 73c0) [2009/09/04 11:26:52, 10] smbd/ipc.c:api_fd_reply(350) api_fd_reply: p:0xb8401b28 max_trans_reply: 4280 [2009/09/04 11:26:52, 6] rpc_server/srv_pipe_hnd.c:write_to_pipe(927) write_to_pipe: 73c0 name: samr open: Yes len: 1216 [2009/09/04 11:26:52, 50] lib/util.c:dump_data(2223) [000] 05 00 00 03 10 00 00 00 C0 04 10 00 02 00 00 00 ........ ........ [010] 8C 04 00 00 00 00 37 00 84 AC BA E1 60 A8 E9 C7 ......7. ....`... [020] 7C 6F 55 35 3B A2 45 57 5F C2 82 E0 3B EB D7 94 |oU5;.EW _...;... [030] DE A7 29 AF C1 06 2F A3 39 EB 30 C7 3B 81 21 46 ..).../. 9.0.;.!F [040] 9E C0 EF 84 0D FD DA 0E 19 C5 25 75 5E 5D 80 28 ........ ..%u^].( [050] 26 2A 12 C2 33 0A EE 53 32 70 D4 AE F1 ED 63 AC &*..3..S 2p....c. [060] CF 93 46 9D AC 00 A3 3D 4B B8 D9 E8 C7 93 44 26 ..F....= K.....D& [070] D2 C1 D9 85 96 F7 DE 14 81 B0 EF 62 E2 00 63 06 ........ ...b..c. [080] 4D 73 EA 7A 6F 6B CF D3 BF EF D7 F4 8D 67 B2 F0 Ms.zok.. .....g.. [090] A7 1D F6 C9 E3 98 B0 67 F4 BF 37 78 51 15 DF 19 .......g ..7xQ... [0A0] C6 70 0F F5 C3 31 17 1E 6F 40 1E 41 0D C2 97 3F .p...1.. o@.A...? [0B0] 57 D4 7C 4C 06 90 75 4B 30 DE 89 E1 EB 3E D7 9D W.|L..uK 0....>.. [0C0] 5D B8 33 DF 5D 93 FC ED 06 D1 81 E5 6A 8B 7A CC ].3.]... ....j.z. [0D0] EC 3E D9 3E 16 2A CF 4B E2 99 BD 8A 3D 26 D8 19 .>.>.*.K ....=&.. [0E0] 53 C0 DF B8 DD D9 28 9F AC 18 4C A7 3D A8 B3 CD S.....(. ..L.=... [0F0] 1B ED 69 2F A3 C0 5F D7 B8 C7 FE F4 FB D6 42 80 ..i/.._. ......B. [100] 15 AA 28 35 11 28 E4 8C 2B 59 EA 25 5B D1 29 53 ..(5.(.. +Y.%[.)S [110] 2B 8D C0 F5 A3 40 BC 9D 68 E9 8D C6 5A 53 72 AD +....@.. h...ZSr. [120] 8B 19 C3 DC 09 73 47 B7 06 ED 54 FB EC A8 A2 67 .....sG. ..T....g [130] A2 32 F3 5A E1 E2 29 2C EB 3F 65 CE 99 33 EB 6F .2.Z..), .?e..3.o [140] C8 C2 D1 48 04 6A 1E F5 F4 CE 58 45 45 4F 4A 42 ...H.j.. ..XEEOJB [150] 5B 9F A9 0E 72 AC C1 3F 03 AC F4 AC 81 12 DC 56 [...r..? .......V [160] BD 9D DF 5C 41 6E C8 6E F9 1C E8 14 BD AE 3F CB ...\An.n ......?. [170] 6D 9D FE 51 EC AB 26 BE 6C AA 0B CE 06 A1 E8 91 m..Q..&. l....... [180] 63 60 C6 38 37 32 87 91 CD 48 3F 1C CA 40 13 7F c`.872.. .H?..@.. [190] BA 2A 6C 6E FC FC 0B FD BC F4 46 C5 99 FA 1B 0B .*ln.... ..F..... [1A0] FB 27 6C 16 FE 1B 8B 97 58 E1 D0 82 86 CF 65 67 .'l..... X.....eg [1B0] 4C 35 B6 CD 82 C0 4B 93 8A 62 93 5F 9B 2E 5E 90 L5....K. .b._..^. [1C0] 97 AC F6 DF EC 07 2C BD A1 BA BA 7E 41 C5 52 C1 ......,. ...~A.R. [1D0] 88 7F 2F 6A CA CE FC D0 91 1C D9 D1 95 F6 68 AA ../j.... ......h. [1E0] 33 B6 E6 71 8A 92 97 62 ED 58 43 A4 A6 0A F7 AC 3..q...b .XC..... [1F0] FF 54 E7 CB 36 7E 4B 68 12 B6 D1 2B E9 28 CB CF .T..6~Kh ...+.(.. [200] 51 1B 5F F9 98 93 B4 3A 42 F1 CB 1B D3 5E 09 2B Q._....: B....^.+ [210] B7 AB AF 26 CA F6 B8 4E 0A 2A 53 D3 29 25 61 80 ...&...N .*S.)%a. [220] 41 E3 C2 AF 75 BE 6E 78 36 4D B0 39 2A F7 74 D4 A...u.nx 6M.9*.t. [230] 5C 51 82 7A 94 82 45 96 0D CC B9 10 C6 0A 4C BD \Q.z..E. ......L. [240] 0F 0C 2C C3 3E 9B ED 03 71 73 CA F1 4E FF 1A 89 ..,.>... qs..N... [250] 5A 47 1C F4 F0 F1 2C E0 3B 79 6A 91 C8 94 1F 4A ZG....,. ;yj....J [260] 57 C6 BB A9 EF 25 E9 4F 16 55 80 14 FD 97 C6 26 W....%.O .U.....& [270] E9 3F A7 B3 F8 C9 D0 95 B6 D1 8A 5C 0B B7 34 29 .?...... ...\..4) [280] 6F C1 30 0E CB 16 5E 65 11 9D 23 09 8D 45 43 0C o.0...^e ..#..EC. [290] 1C F8 6F 5F 16 D0 8C A0 01 69 FF AC 78 8F 02 74 ..o_.... .i..x..t [2A0] 41 A1 86 D2 C3 A0 DC 92 95 A0 B5 51 D0 78 08 11 A....... ...Q.x.. [2B0] A0 51 BF 5F C3 B9 E9 11 FD 1B 23 9E D0 BB 21 84 .Q._.... ..#...!. [2C0] 46 F3 AF 59 89 C6 4D E8 3C 64 F7 53 8D FF 0C F8 F..Y..M. . [360] B8 5D BD C6 EA 66 98 3E 77 9D 17 B8 38 66 AB CE .]...f.> w...8f.. [370] 91 4F 90 C5 9F A7 3A 1B 7F 5C F8 80 CD 8D 99 64 .O....:. .\.....d [380] 2B 36 9A 94 FC 8A 2B 11 5A 2D 54 45 58 F6 20 51 +6....+. Z-TEX. Q [390] FB 6B 6C 4C C7 C8 EC FB 19 66 3E 0A 86 9F 14 52 .klL.... .f>....R [3A0] 5D 60 70 7D 9F 15 95 A6 57 2C 24 63 D3 DB 0E 12 ]`p}.... W,$c.... [3B0] 91 6F C2 89 BF DB 00 E4 87 5A 69 BC 10 A9 FB 12 .o...... .Zi..... [3C0] CA 50 67 04 B1 BF FB B8 FD C7 B7 EA 7D 4A A1 75 .Pg..... ....}J.u [3D0] 5B DF 70 91 E2 87 45 5B EC 5D 3C C1 8F 54 9D 05 [.p...E[ .]<..T.. [3E0] A6 65 9E 9B 21 C3 30 36 F1 85 41 2F C4 6A 53 3D .e..!.06 ..A/.jS= [3F0] DD E8 49 CD E5 5F 92 27 8E C2 DE 3B CF 18 E2 D5 ..I.._.' ...;.... [400] B6 0B 59 CB 54 21 D1 38 26 BE EF 43 38 C6 22 F0 ..Y.T!.8 &..C8.". [410] A0 47 67 A2 46 CD 17 07 88 A5 FE 0B B4 8B C5 A3 .Gg.F... ........ [420] A9 D2 C2 32 27 59 46 E7 1B 4E BC 10 E8 13 55 7E ...2'YF. .N....U~ [430] A5 C5 0E C7 44 DE 62 88 59 52 0B A0 55 4E C8 9B ....D.b. YR..UN.. [440] EA 0A 1C 29 B8 48 0C E9 47 C5 AA DE 1B 6F 59 11 ...).H.. G....oY. [450] E1 E6 1A 0B D5 A0 60 A0 97 07 06 0F 00 12 B3 30 ......`. .......0 [460] A7 73 E3 B8 B9 0E D7 9F 0F CD ED 20 FD E5 08 BE .s...... ... .... [470] 4A F0 26 AB 87 4D 34 FE 01 4C 52 1F 28 33 91 75 J.&..M4. .LR.(3.u [480] 31 A7 91 D4 38 12 32 EA 12 0E B9 57 EC C5 43 45 1...8.2. ...W..CE [490] E8 BC 61 54 79 19 83 43 22 F1 44 7A 27 66 0E 62 ..aTy..C ".Dz'f.b [4A0] 98 66 9F 9F 80 33 70 C9 0A 06 04 00 01 00 00 00 .f...3p. ........ [4B0] 01 00 00 00 95 83 10 11 FB 21 B8 95 00 00 00 00 ........ .!...... [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 1216 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 0, pdu_needed_len = 0, incoming data = 1216 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:fill_rpc_header(385) fill_rpc_header: data_to_copy = 1216, len_needed_to_complete_hdr = 16, receive_len = 0 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 16 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 1200 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 0, incoming data = 1200 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0000 major : 05 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0001 minor : 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0002 pkt_type : 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0003 flags : 03 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0004 pack_type0: 10 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0005 pack_type1: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0006 pack_type2: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0007 pack_type3: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0008 frag_len : 04c0 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 000a auth_len : 0010 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 000c call_id : 00000002 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(472) unmarshall_rpc_header: using little-endian RPC [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:unmarshall_rpc_header(501) unmarshall_rpc_header: type = 0, flags = 3 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 0 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(949) write_to_pipe: data_left = 1200 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_incoming_data(842) process_incoming_data: Start: pdu_received_len = 16, pdu_needed_len = 1200, incoming data = 1200 [2009/09/04 11:26:52, 10] rpc_server/srv_pipe_hnd.c:process_complete_pdu(709) process_complete_pdu: processing packet type 0 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr_req req [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0000 alloc_hint: 0000048c [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0004 context_id: 0000 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0006 opnum : 0037 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_debug(88) 000498 smb_io_rpc_hdr_auth hdr_auth [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0498 auth_type : 0a [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0499 auth_level : 06 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 049a auth_pad_len : 04 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint8(624) 049b auth_reserved: 00 [2009/09/04 11:26:52, 5] rpc_parse/parse_prs.c:prs_uint32(718) 049c auth_context_id: 00000001 [2009/09/04 11:26:52, 10] libsmb/ntlmssp_sign.c:ntlmssp_unseal_packet(309) ntlmssp_unseal_packet: seal [2009/09/04 11:26:52, 100] libsmb/ntlmssp_sign.c:ntlmssp_make_packet_signature(87) ntlmssp_make_packet_signature: RECV seq = 0, len = 1168, pdu_len = 1200 [2009/09/04 11:26:52, 10] libsmb/ntlmssp_sign.c:ntlmssp_check_packet(222) ntlmssp_check_packet: NTLMSSP signature OK ! [2009/09/04 11:26:52, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(519) free_pipe_context: destroying talloc pool of size 69 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(108, 100) : sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (108, 100) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(472) NT user token of user S-1-5-21-3850432308-4052430627-3054637991-1216 contains 5 SIDs SID[ 0]: S-1-5-21-3850432308-4052430627-3054637991-1216 SID[ 1]: S-1-22-2-100 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-11 SE_PRIV 0x0 0x0 0x0 0x0 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 108 Primary group is 100 and contains 1 supplementary groups Group[ 0]: 100 [2009/09/04 11:26:52, 5] rpc_server/srv_pipe.c:api_pipe_request(2262) Requested \PIPE\samr [2009/09/04 11:26:52, 4] rpc_server/srv_pipe.c:api_rpcTNP(2297) api_rpcTNP: samr op 0x37 - created /tmp/in_samr_55.1.prs [2009/09/04 11:26:52, 3] rpc_server/srv_pipe.c:api_rpcTNP(2304) api_rpcTNP: rpc command: SAMR_CHANGEPASSWORDUSER2 [2009/09/04 11:26:52, 6] rpc_server/srv_pipe.c:api_rpcTNP(2323) api_rpc_cmds[55].fn == 0xb7c8c399 samr_ChangePasswordUser2: struct samr_ChangePasswordUser2 in: struct samr_ChangePasswordUser2 server : * server: struct lsa_String length : 0x0016 (22) size : 0x0016 (22) string : * string : '\\127.0.0.1' account : * account: struct lsa_String length : 0x000a (10) size : 0x000a (10) string : * string : 'user1' nt_password : * nt_password: struct samr_CryptPassword data : 62a1769885f8552a10ccfbb02062f0eadc30807f575de3aeaf7a4ce576b1115f83a0020558ff2eebd2069663ba8ba8e2af6a5e49bc1ea6eea31d39e51a4613a5f3f68d0ddaf5a2666efad89972e0e47e5e06dcc0e2d7fcfc21e3b4374462ca0c767cb40f9d7503d74f67b34d22368cde7858c95351eab25a509e5e77cf54b85ab4339f1a1aea4deed25a403dee9744fd160a6e5f2c1e031a8bf9cc597c507bda9269e4fcad0523996549ab28b482e677e4ab6308a27556d6ffef1ec4c0fe2b1934faeb5a8b40a50059b1b2bb1bce730911cf1b1631783110d033dbbe4d9eec52d2322e80caf7efe5e71d39fd03ec8d3b5fa0a60dedfd717c1c8e8de1bf8561310b70322e310bd7f303ffaa3ed2c45ce1519c3fce4e79d369294db6d23586fbe53aaa2871d9ab47857ed09829e57caab51d3465a997c39e15698bf5592f0b60e12c79163e075b4e1a23ff5a8f6c0c0d879639f43317e427b2b2dd410366b9a2edfdbf5011093da3d78e599cee33f288ca9fb3c6b12c74c700956736314adcc7c02549107ca66257b5d1d358952badf10955a064bc4cbf8687fe3ee39ddb57313b1593d14f1e2baf0020a5d2807cd2b6c3f4943c97334cb17373537bbfcc8f0dc68cfd2463ca4e8bfbc036f4ede070408bb288c2ba4cd33b9801e1e5113096f79305f32f49e731ec +> aa113b6ac2ac7c69a88334cd9b56937ee46a16361f4af3f3f920c4cc1d nt_verifier : * nt_verifier: struct samr_Password hash : 34465ee87b098f34c820065172b66cf6 lm_change : 0x01 (1) lm_password : * lm_password: struct samr_CryptPassword data : 00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000 +> 0000000000000000000000000000000000000000000000000000000000 lm_verifier : * lm_verifier: struct samr_Password hash : 00000000000000000000000000000000 [2009/09/04 11:26:52, 5] rpc_server/srv_samr_nt.c:_samr_ChangePasswordUser2(1938) _samr_ChangePasswordUser2: 1938 [2009/09/04 11:26:52, 5] rpc_server/srv_samr_nt.c:_samr_ChangePasswordUser2(1943) _samr_ChangePasswordUser2: user: user1 wks: \\127.0.0.1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(108, 100) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_unpack(655) tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 5 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 6 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 7 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 8 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 9 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 10 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 21 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_username(580) pdb_set_username: setting username user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 12 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_domain(603) pdb_set_domain: setting domain X093, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 14 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_nt_username(626) pdb_set_nt_username: setting nt username , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 15 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_fullname(649) pdb_set_full_name: setting full name , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 13 -> now SET [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_homedir(742) pdb_set_homedir: setting home dir \\x093\user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 1 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(718) pdb_set_dir_drive: setting dir drive F:, was NULL [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 3 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_logon_script(672) pdb_set_logon_script: setting logon script , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 4 -> now DEFAULT [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_profile_path(695) pdb_set_profile_path: setting profile path \\x093\user1\profile, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 2 -> now DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 23 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_workstations(785) pdb_set_workstations: setting workstations , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 24 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 26 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 32 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 33 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 34 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_user_sid(509) pdb_set_user_sid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 18 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 from rid 1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 16 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 29 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 30 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 31 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 20 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 17 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 27 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: maximum password age, val: -1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_alloc(133) Finding user user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(77) Trying _Get_Pwnam(), username as lowercase is user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(110) Get_Pwnam_internals did find user [user1]! [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 3: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 1: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 4: DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 2: DEFAULT [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_pack_va(501) tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 198 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_pack_va(501) tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 18] lib/util_tdb.c:tdb_unpack(655) tdb_unpack(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 5 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 6 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 7 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 8 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 9 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 10 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 21 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_username(580) pdb_set_username: setting username user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 12 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_domain(603) pdb_set_domain: setting domain X093, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 14 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_nt_username(626) pdb_set_nt_username: setting nt username , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 15 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_fullname(649) pdb_set_full_name: setting full name , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 13 -> now SET [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_homedir(742) pdb_set_homedir: setting home dir \\x093\user1, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 1 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_dir_drive(718) pdb_set_dir_drive: setting dir drive F:, was NULL [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 3 -> now DEFAULT [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_logon_script(672) pdb_set_logon_script: setting logon script , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 4 -> now DEFAULT [2009/09/04 11:26:52, 4] lib/substitute.c:automount_server(500) Home server: x093 [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_profile_path(695) pdb_set_profile_path: setting profile path \\x093\user1\profile, was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(494) element 2 -> now DEFAULT [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 23 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_workstations(785) pdb_set_workstations: setting workstations , was [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 24 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 26 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 32 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 33 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 34 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_get_set.c:pdb_set_user_sid(509) pdb_set_user_sid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 18 -> now SET [2009/09/04 11:26:52, 10] passdb/pdb_compat.c:pdb_set_user_sid_from_rid(72) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3850432308-4052430627-3054637991-1216 from rid 1216 [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 16 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 29 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 30 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 31 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 20 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 17 -> now SET [2009/09/04 11:26:52, 11] passdb/pdb_get_set.c:pdb_set_init_flags(482) element 27 -> now SET [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (108, 100) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(108, 100) : sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: minimum password age, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: min password length, val: 5 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_alloc(133) Finding user user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(77) Trying _Get_Pwnam(), username as lowercase is user1 [2009/09/04 11:26:52, 5] lib/username.c:Get_Pwnam_internals(110) Get_Pwnam_internals did find user [user1]! [2009/09/04 11:26:52, 3] smbd/chgpasswd.c:chgpasswd(507) chgpasswd: Password change (as_root=Yes) for user: user1 [2009/09/04 11:26:52, 10] smbd/chgpasswd.c:findpty(89) findpty: Allocated slave pty /dev/pts/1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:52, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:52, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:52, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:52, 3] smbd/chgpasswd.c:chat_with_program(474) chat_with_program: Dochild for user user1 (uid=0,gid=0) (as_root = Yes) [2009/09/04 11:26:52, 10] smbd/chgpasswd.c:dochild(233) Invoking '/99/bin/passwd user1' as password change program. [2009/09/04 11:26:53, 10] lib/util_sock.c:read_socket_with_timeout(989) read_socket_with_timeout: timeout read. select timed out. [2009/09/04 11:26:53, 100] smbd/chgpasswd.c:expect(303) expect: expected [*new*password*] received [Changing password for user1 Enter the new password (minimum of 5, maximum of 127 characters) Please use a combination of upper and lower case letters and numbers. New password: ] match yes [2009/09/04 11:26:53, 10] smbd/chgpasswd.c:expect(314) expect: returning True [2009/09/04 11:26:53, 100] smbd/chgpasswd.c:expect(255) expect: sending [1234abcd ] [2009/09/04 11:26:53, 10] lib/util_sock.c:read_socket_with_timeout(989) read_socket_with_timeout: timeout read. select timed out. [2009/09/04 11:26:53, 100] smbd/chgpasswd.c:expect(303) expect: expected [*re*enter*new*password*] received [ Re-enter new password: ] match yes [2009/09/04 11:26:53, 10] smbd/chgpasswd.c:expect(314) expect: returning True [2009/09/04 11:26:53, 100] smbd/chgpasswd.c:expect(255) expect: sending [1234abcd ] [2009/09/04 11:26:53, 0] lib/util_sock.c:read_socket_with_timeout(1014) read_socket_with_timeout: timeout read. read error = Input/output error. [2009/09/04 11:26:53, 100] smbd/chgpasswd.c:expect(303) expect: expected [*password*changed*] received [ Password changed. ] match yes [2009/09/04 11:26:53, 10] smbd/chgpasswd.c:expect(314) expect: returning True [2009/09/04 11:26:53, 3] smbd/chgpasswd.c:chat_with_program(490) chat_with_program: Password change successful for user user1 [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_set_init_flags(471) element 33 -> now CHANGED [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_set_init_flags(471) element 32 -> now CHANGED [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_set_init_flags(471) element 11 -> now CHANGED [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_set_init_flags(471) element 21 -> now CHANGED [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:53, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:53, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:53, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:53, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_set_init_flags(471) element 34 -> now CHANGED [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:53, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:53, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:53, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:53, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: maximum password age, val: -1 [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 3: DEFAULT [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 1: DEFAULT [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 4: DEFAULT [2009/09/04 11:26:53, 11] passdb/pdb_get_set.c:pdb_get_init_flags(273) element 2: DEFAULT [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:push_sec_ctx(224) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2009/09/04 11:26:53, 3] smbd/uid.c:push_conn_ctx(407) push_conn_ctx(100) : conn_ctx_stack_ndx = 1 [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:set_sec_ctx(324) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2009/09/04 11:26:53, 5] auth/token_util.c:debug_nt_user_token(466) NT user token: (NULL) [2009/09/04 11:26:53, 5] auth/token_util.c:debug_unix_user_token(492) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/09/04 11:26:53, 10] lib/account_pol.c:account_policy_get(332) account_policy_get: name: password history, val: 0 [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/09/04 11:26:53, 18] lib/util_tdb.c:tdb_pack_va(501) tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 0) -> 198 [2009/09/04 11:26:53, 18] lib/util_tdb.c:tdb_pack_va(501) tdb_pack_va(dddddddBBBBBBBBBBBBddBBBdwdBwwd, 198) -> 198 [2009/09/04 11:26:53, 5] passdb/pdb_tdb.c:tdb_update_samacct_only(1107) Storing account user1 with RID 1216 [2009/09/04 11:26:53, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100) Locking key 555345525F757365723100 [2009/09/04 11:26:53, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129) Allocated locked data 0x0xb840d840 [2009/09/04 11:26:53, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42) Unlocking key 555345525F757365723100 [2009/09/04 11:26:53, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(100) Locking key 5249445F303030303034633000 [2009/09/04 11:26:53, 10] lib/dbwrap_tdb.c:db_tdb_fetch_locked(129) Allocated locked data 0x0xb8402fa0 [2009/09/04 11:26:53, 10] lib/dbwrap_tdb.c:db_tdb_record_destr(42) Unlocking key 5249445F303030303034633000 [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (108, 100) - sec_ctx_stack_ndx = 1 [2009/09/04 11:26:53, 5] rpc_server/srv_samr_nt.c:_samr_ChangePasswordUser2(1964) _samr_ChangePasswordUser2: 1964 samr_ChangePasswordUser2: struct samr_ChangePasswordUser2 out: struct samr_ChangePasswordUser2 result : NT_STATUS_OK [2009/09/04 11:26:53, 0] rpc_parse/parse_prs.c:prs_dump_region(73) created /tmp/out_samr_55.1.prs [2009/09/04 11:26:53, 5] rpc_server/srv_pipe.c:api_rpcTNP(2351) api_rpcTNP: called samr successfully [2009/09/04 11:26:53, 3] smbd/sec_ctx.c:pop_sec_ctx(432) pop_sec_ctx (108, 100) - sec_ctx_stack_ndx = 0 [2009/09/04 11:26:53, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(519) free_pipe_context: destroying talloc pool of size 0 [2009/09/04 11:26:53, 10] rpc_server/srv_pipe_hnd.c:write_to_internal_pipe(953) write_to_pipe: data_used = 1200 [2009/09/04 11:26:53, 6] rpc_server/srv_pipe_hnd.c:read_from_pipe(985) read_from_pipe: 73c0 name: samr len: 4280 [2009/09/04 11:26:53, 10] rpc_server/srv_pipe_hnd.c:read_from_internal_pipe(1059) read_from_pipe: samr: fault_state = 0 : data_sent_length = 0, prs_offset(&p->out_data.rdata) = 4. [2009/09/04 11:26:53, 10] rpc_server/srv_pipe.c:create_next_pdu_ntlmssp(135) create_next_pdu_ntlmssp: adding sign/seal padding of 4 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_debug(88) 000000 smb_io_rpc_hdr hdr [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0000 major : 05 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0001 minor : 00 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0002 pkt_type : 02 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0003 flags : 03 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0004 pack_type0: 10 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0005 pack_type1: 00 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0006 pack_type2: 00 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0007 pack_type3: 00 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0008 frag_len : 0038 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint16(689) 000a auth_len : 0010 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint32(718) 000c call_id : 00000002 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_debug(88) 000010 smb_io_rpc_hdr_resp resp [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0010 alloc_hint: 00000004 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint16(689) 0014 context_id: 0000 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0016 cancel_ct : 00 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0017 reserved : 00 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_debug(88) 000020 smb_io_rpc_hdr_auth hdr_auth [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0020 auth_type : 0a [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0021 auth_level : 06 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0022 auth_pad_len : 04 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint8(624) 0023 auth_reserved: 00 [2009/09/04 11:26:53, 5] rpc_parse/parse_prs.c:prs_uint32(718) 0024 auth_context_id: 00000001 [2009/09/04 11:26:53, 10] libsmb/ntlmssp_sign.c:ntlmssp_seal_packet(248) ntlmssp_seal_data: seal [2009/09/04 11:26:53, 100] libsmb/ntlmssp_sign.c:ntlmssp_make_packet_signature(76) ntlmssp_make_packet_signature: SEND seq = 0, len = 8, pdu_len = 40 [2009/09/04 11:26:53, 5] smbd/ipc.c:copy_trans_params_and_data(60) copy_trans_params_and_data: params[0..0] data[0..56] (align 0) [2009/09/04 11:26:53, 5] lib/util.c:show_msg(642) [2009/09/04 11:26:53, 5] lib/util.c:show_msg(652) size=112 smb_com=0x25 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=1 smb_pid=2875 smb_uid=100 smb_mid=8 smt_wct=10 smb_vwv[ 0]= 0 (0x0) smb_vwv[ 1]= 56 (0x38) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 0 (0x0) smb_vwv[ 4]= 56 (0x38) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 56 (0x38) smb_vwv[ 7]= 56 (0x38) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_bcc=57 [2009/09/04 11:26:53, 10] lib/util.c:dump_data(2223) [000] 00 05 00 02 03 10 00 00 00 38 00 10 00 02 00 00 ........ .8...... [010] 00 04 00 00 00 00 00 00 00 C7 3B 8E 3C 6C 21 7C ........ ..;.