[2015/02/12 14:39:33.195846, 5] ../source3/smbd/reply.c:668(reply_special) init msg_type=0x81 msg_flags=0x0 [2015/02/12 14:39:33.197392, 3] ../source3/smbd/process.c:1879(process_smb) Transaction 0 of length 137 (0 toread) [2015/02/12 14:39:33.197522, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.197567, 5] ../source3/lib/util.c:178(show_msg) size=133 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51283 smb_tid=0 smb_pid=65279 smb_uid=0 smb_mid=0 smt_wct=0 smb_bcc=98 [2015/02/12 14:39:33.197869, 3] ../source3/smbd/process.c:1489(switch_message) switch message SMBnegprot (pid 6754) conn 0x0 [2015/02/12 14:39:33.197967, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.198075, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.198148, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.198264, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.235621, 3] ../source3/smbd/negprot.c:575(reply_negprot) Requested protocol [PC NETWORK PROGRAM 1.0] [2015/02/12 14:39:33.235832, 3] ../source3/smbd/negprot.c:575(reply_negprot) Requested protocol [LANMAN1.0] [2015/02/12 14:39:33.235901, 3] ../source3/smbd/negprot.c:575(reply_negprot) Requested protocol [Windows for Workgroups 3.1a] [2015/02/12 14:39:33.235965, 3] ../source3/smbd/negprot.c:575(reply_negprot) Requested protocol [LM1.2X002] [2015/02/12 14:39:33.236074, 3] ../source3/smbd/negprot.c:575(reply_negprot) Requested protocol [LANMAN2.1] [2015/02/12 14:39:33.236136, 3] ../source3/smbd/negprot.c:575(reply_negprot) Requested protocol [NT LM 0.12] [2015/02/12 14:39:33.236398, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/serverid.tdb [2015/02/12 14:39:33.236583, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/serverid.tdb [2015/02/12 14:39:33.237380, 5] ../source3/auth/auth.c:477(make_auth_context_subsystem) Making default auth method list for server role = 'domain member' [2015/02/12 14:39:33.237548, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend sam [2015/02/12 14:39:33.237626, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'sam' [2015/02/12 14:39:33.237682, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend sam_ignoredomain [2015/02/12 14:39:33.237745, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'sam_ignoredomain' [2015/02/12 14:39:33.237807, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend unix [2015/02/12 14:39:33.237868, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'unix' [2015/02/12 14:39:33.237924, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend winbind [2015/02/12 14:39:33.237979, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'winbind' [2015/02/12 14:39:33.238067, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend wbc [2015/02/12 14:39:33.238125, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'wbc' [2015/02/12 14:39:33.238180, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend trustdomain [2015/02/12 14:39:33.238235, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'trustdomain' [2015/02/12 14:39:33.238287, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend ntdomain [2015/02/12 14:39:33.238344, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'ntdomain' [2015/02/12 14:39:33.238459, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend guest [2015/02/12 14:39:33.238518, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'guest' [2015/02/12 14:39:33.238578, 5] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend samba4 [2015/02/12 14:39:33.238634, 5] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'samba4' [2015/02/12 14:39:33.238685, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match guest [2015/02/12 14:39:33.238752, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method guest has a valid init [2015/02/12 14:39:33.238815, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match sam [2015/02/12 14:39:33.238872, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method sam has a valid init [2015/02/12 14:39:33.238927, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match winbind:ntdomain [2015/02/12 14:39:33.238982, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match ntdomain [2015/02/12 14:39:33.239069, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method ntdomain has a valid init [2015/02/12 14:39:33.239125, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method winbind has a valid init [2015/02/12 14:39:33.255493, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'gssapi_spnego' registered [2015/02/12 14:39:33.255725, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'gssapi_krb5' registered [2015/02/12 14:39:33.255795, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'gssapi_krb5_sasl' registered [2015/02/12 14:39:33.292895, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'sasl-DIGEST-MD5' registered [2015/02/12 14:39:33.293132, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'spnego' registered [2015/02/12 14:39:33.293211, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'schannel' registered [2015/02/12 14:39:33.293285, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'naclrpc_as_system' registered [2015/02/12 14:39:33.293347, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'sasl-EXTERNAL' registered [2015/02/12 14:39:33.293409, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'ntlmssp' registered [2015/02/12 14:39:33.293476, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'http_basic' registered [2015/02/12 14:39:33.293546, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'http_ntlm' registered [2015/02/12 14:39:33.293620, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'krb5' registered [2015/02/12 14:39:33.293682, 3] ../auth/gensec/gensec_start.c:885(gensec_register) GENSEC backend 'fake_gssapi_krb5' registered [2015/02/12 14:39:33.294377, 5] ../auth/gensec/gensec_start.c:664(gensec_start_mech) Starting GENSEC mechanism spnego [2015/02/12 14:39:33.294696, 5] ../auth/gensec/gensec_start.c:664(gensec_start_mech) Starting GENSEC submechanism ntlmssp [2015/02/12 14:39:33.295199, 3] ../source3/smbd/negprot.c:395(reply_nt1) using SPNEGO [2015/02/12 14:39:33.295279, 3] ../source3/smbd/negprot.c:683(reply_negprot) Selected protocol NT LM 0.12 [2015/02/12 14:39:33.295337, 5] ../source3/smbd/negprot.c:690(reply_negprot) negprot index=5 [2015/02/12 14:39:33.295412, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.295452, 5] ../source3/lib/util.c:178(show_msg) size=159 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51283 smb_tid=0 smb_pid=65279 smb_uid=0 smb_mid=0 smt_wct=17 smb_vwv[ 0]= 5 (0x5) 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]=25088 (0x6200) smb_vwv[ 8]= 26 (0x1A) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]= 243 (0xF3) smb_vwv[11]=45440 (0xB180) smb_vwv[12]=13818 (0x35FA) smb_vwv[13]=64416 (0xFBA0) smb_vwv[14]=53318 (0xD046) smb_vwv[15]=11265 (0x2C01) smb_vwv[16]= 1 (0x1) smb_bcc=90 [2015/02/12 14:39:33.304226, 3] ../source3/smbd/process.c:1879(process_smb) Transaction 1 of length 240 (0 toread) [2015/02/12 14:39:33.304320, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.304382, 5] ../source3/lib/util.c:178(show_msg) size=236 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=0 smb_mid=64 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 236 (0xEC) smb_vwv[ 2]=16644 (0x4104) smb_vwv[ 3]= 50 (0x32) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 74 (0x4A) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 212 (0xD4) smb_vwv[11]=40960 (0xA000) smb_bcc=177 [2015/02/12 14:39:33.304801, 3] ../source3/smbd/process.c:1489(switch_message) switch message SMBsesssetupX (pid 6754) conn 0x0 [2015/02/12 14:39:33.304867, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.304925, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.304980, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.305112, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.332770, 3] ../source3/smbd/sesssetup.c:609(reply_sesssetup_and_X) wct=12 flg2=0xc807 [2015/02/12 14:39:33.332910, 2] ../source3/smbd/sesssetup.c:551(setup_new_vc_session) setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources. [2015/02/12 14:39:33.332986, 3] ../source3/smbd/sesssetup.c:142(reply_sesssetup_and_X_spnego) Doing spnego session setup [2015/02/12 14:39:33.333144, 3] ../source3/smbd/sesssetup.c:183(reply_sesssetup_and_X_spnego) NativeOS=[Windows 2002 Service Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[] [2015/02/12 14:39:33.333269, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 1 for /opt/local/samba4/var/lock/smbXsrv_session_global.tdb [2015/02/12 14:39:33.335291, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /opt/local/samba4/var/lock/smbXsrv_session_global.tdb [2015/02/12 14:39:33.335437, 5] ../source3/auth/auth.c:477(make_auth_context_subsystem) Making default auth method list for server role = 'domain member' [2015/02/12 14:39:33.335525, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match guest [2015/02/12 14:39:33.335590, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method guest has a valid init [2015/02/12 14:39:33.335646, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match sam [2015/02/12 14:39:33.335705, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method sam has a valid init [2015/02/12 14:39:33.335758, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match winbind:ntdomain [2015/02/12 14:39:33.335814, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match ntdomain [2015/02/12 14:39:33.335870, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method ntdomain has a valid init [2015/02/12 14:39:33.335923, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method winbind has a valid init [2015/02/12 14:39:33.336272, 5] ../auth/gensec/gensec_start.c:664(gensec_start_mech) Starting GENSEC mechanism spnego [2015/02/12 14:39:33.336363, 4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.336444, 4] ../source3/smbd/uid.c:491(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2015/02/12 14:39:33.336504, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.336559, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.336614, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.337135, 5] ../auth/gensec/gensec_start.c:664(gensec_start_mech) Starting GENSEC submechanism ntlmssp [2015/02/12 14:39:33.337312, 3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0xa2088207 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_NEGOTIATE_OEM NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_NEGOTIATE_VERSION NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_56 [2015/02/12 14:39:33.343850, 4] ../source3/smbd/sec_ctx.c:421(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.344135, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.344184, 5] ../source3/lib/util.c:178(show_msg) size=274 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=62578 smb_mid=64 smt_wct=4 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 169 (0xA9) smb_bcc=231 [2015/02/12 14:39:33.350908, 3] ../source3/smbd/process.c:1879(process_smb) Transaction 2 of length 326 (0 toread) [2015/02/12 14:39:33.350994, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.351079, 5] ../source3/lib/util.c:178(show_msg) size=322 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=62578 smb_mid=128 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 322 (0x142) smb_vwv[ 2]=16644 (0x4104) smb_vwv[ 3]= 50 (0x32) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 160 (0xA0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 212 (0xD4) smb_vwv[11]=40960 (0xA000) smb_bcc=263 [2015/02/12 14:39:33.351497, 3] ../source3/smbd/process.c:1489(switch_message) switch message SMBsesssetupX (pid 6754) conn 0x0 [2015/02/12 14:39:33.351611, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.351673, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.351729, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.351827, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.351888, 3] ../source3/smbd/sesssetup.c:609(reply_sesssetup_and_X) wct=12 flg2=0xc807 [2015/02/12 14:39:33.351945, 2] ../source3/smbd/sesssetup.c:551(setup_new_vc_session) setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources. [2015/02/12 14:39:33.352003, 3] ../source3/smbd/sesssetup.c:142(reply_sesssetup_and_X_spnego) Doing spnego session setup [2015/02/12 14:39:33.352102, 3] ../source3/smbd/sesssetup.c:183(reply_sesssetup_and_X_spnego) NativeOS=[Windows 2002 Service Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[] [2015/02/12 14:39:33.352169, 4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.352231, 4] ../source3/smbd/uid.c:491(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2015/02/12 14:39:33.352348, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.352401, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.352450, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.352639, 3] ../auth/ntlmssp/ntlmssp_server.c:359(ntlmssp_server_preauth) Got user=[schulz] domain=[ADI] workstation=[TETRA] len1=24 len2=24 [2015/02/12 14:39:33.352759, 5] ../source3/auth/auth_ntlmssp.c:124(auth3_set_challenge) auth_context challenge set by NTLMSSP callback (NTLM2) [2015/02/12 14:39:33.352821, 5] ../source3/auth/auth_ntlmssp.c:125(auth3_set_challenge) challenge is: [2015/02/12 14:39:33.352877, 5] ../lib/util/util.c:559(dump_data) [0000] C7 40 4D F6 19 87 49 91 .@M...I. [2015/02/12 14:39:33.353087, 3] ../source3/param/loadparm.c:3647(lp_load_ex) lp_load_ex: refreshing parameters [2015/02/12 14:39:33.353169, 5] ../source3/param/loadparm.c:1234(free_param_opts) Freeing parametrics: [2015/02/12 14:39:33.353588, 3] ../source3/param/loadparm.c:564(init_globals) Initialising global parameters [2015/02/12 14:39:33.353947, 5] ../lib/util/debug.c:334(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 [2015/02/12 14:39:33.354712, 3] ../source3/param/loadparm.c:2597(lp_do_section) Processing section "[global]" doing parameter max protocol = NT1 doing parameter workgroup = adi doing parameter server string = doing parameter security = domain doing parameter load printers = yes doing parameter printcap name = /etc/printers.samba doing parameter printing = sysv doing parameter guest account = nobody2 doing parameter log file = /opt/local/samba4/var/logs/%h/log.%m doing parameter max log size = 150 doing parameter password server = starfish2 doing parameter passdb backend = tdbsam doing parameter socket options = TCP_NODELAY doing parameter dns proxy = no doing parameter dos filemode = yes doing parameter delete readonly = yes doing parameter name resolve order = bcast host doing parameter host msdfs = yes doing parameter msdfs root = yes doing parameter unix extensions = no doing parameter wide links = yes doing parameter include = /opt/local/samba4/etc/smb.conf.%h [2015/02/12 14:39:33.356567, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[homes]" doing parameter comment = Home Directories doing parameter browseable = no doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.356921, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[users]" doing parameter comment = User Directories doing parameter path = /home/users doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.357275, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[projects]" doing parameter comment = Project Directories doing parameter path = /home/projects doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.357599, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[zacltest1]" doing parameter comment = Acl test doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.357869, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[zacltest2]" doing parameter comment = Acl test doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.358297, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[zacltest3]" doing parameter comment = Acl test doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes doing parameter inherit acls = yes [2015/02/12 14:39:33.358619, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[zacltest4]" doing parameter comment = Acl test doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes doing parameter inherit acls = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.358985, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[root]" doing parameter comment = All Directories doing parameter path = / doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.359286, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[ftp]" doing parameter comment = Ftp Directories doing parameter path = /home/users/ftp doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.359634, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[adi-rel]" doing parameter comment = Adi Software Directories doing parameter path = /home/projects/adirel/release/winnt doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.359935, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[adi-projects]" doing parameter comment = ADI Project Directories doing parameter path = /home/projects/adirel/projects doing parameter browseable = yes doing parameter writable = yes doing parameter force create mode = 0666 doing parameter force directory mode = 0666 [2015/02/12 14:39:33.360383, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[production_test_results]" doing parameter comment = sqa production test results doing parameter path = /home/projects/sqa/production_test_results doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.360717, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[system_configuration_info]" doing parameter comment = sqa system configuration info doing parameter path = /home/projects/sqa/system_configuration_info doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.361097, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[production_board_test_results]" doing parameter comment = sqa production board test results doing parameter path = /home/projects/sqa/production_board_test_results doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.361478, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[marketing]" doing parameter comment = Marketing Project Directories doing parameter path = /home/projects/marketing doing parameter browseable = yes doing parameter writable = yes doing parameter force create mode = 0664 doing parameter force directory mode = 0664 [2015/02/12 14:39:33.361860, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[CustomerSupport]" doing parameter comment = CustomerSupport Project Directories doing parameter path = /home/projects/CustomerSupport doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.362216, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[foom]" doing parameter comment = Foom Project Directories doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.362595, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[proposals]" doing parameter comment = proposals doing parameter path = /home/projects/proposals doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.362915, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[bms]" doing parameter comment = bms doing parameter path = /home/projects/bms doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.363281, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[timecards]" doing parameter comment = Timecard Submission doing parameter path = /home/projects/timecards doing parameter browseable = yes doing parameter writable = yes doing parameter force create mode = 0666 doing parameter force directory mode = 0666 [2015/02/12 14:39:33.363665, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[ProjectManagement]" doing parameter comment = Project Management Directories doing parameter path = /home/projects/ProjectManagement doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.364019, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[adi]" doing parameter comment = Adi Software Directories doing parameter path = /home/projects/adirel doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.364318, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[ariel]" doing parameter comment = Software Development Directories doing parameter path = /home/projects/ariel doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.364591, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[proteus]" doing parameter comment = Software Development Directories doing parameter path = /home/projects/proteus doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.364898, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[support]" doing parameter comment = Customer Support Directories doing parameter path = /home/users/support doing parameter browseable = yes doing parameter writable = yes doing parameter create mask = 0777 doing parameter force create mode = 0666 [2015/02/12 14:39:33.365319, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[scanner]" doing parameter comment = Customer Support Directories doing parameter path = /home/users/scanner doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.365591, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[hwdesign]" doing parameter comment = Hardware Design Directories doing parameter path = /home/projects/hwdesign doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.365939, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[HR Recruitment]" doing parameter comment = HR Recruitment doing parameter path = /home/projects/HR_Recruitment doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.366289, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[www]" doing parameter comment = Hardware Design Directories doing parameter path = /home/projects/www doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.366584, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[hr]" doing parameter comment = Human Resources doing parameter path = /home/projects/hr doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.366891, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[W000Axxxx]" doing parameter comment = Hardware Design W000Axxxx Directory doing parameter path = /home/projects/hwdesign/Release/W_Software/W000Axxxx doing parameter browseable = yes doing parameter writable = no [2015/02/12 14:39:33.367199, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[W_Software]" doing parameter comment = Hardware Design W_Software Directory doing parameter path = /home/projects/hwdesign/Release/W_Software doing parameter browseable = yes doing parameter writable = no [2015/02/12 14:39:33.367499, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[printers]" doing parameter comment = All Printers doing parameter path = /var/tmp/samba doing parameter browseable = no doing parameter writable = no doing parameter printable = yes [2015/02/12 14:39:33.368058, 4] ../source3/param/loadparm.c:3688(lp_load_ex) pm_process() returned Yes [2015/02/12 14:39:33.368268, 3] ../source3/param/loadparm.c:1495(lp_add_ipc) adding IPC service [2015/02/12 14:39:33.368472, 5] ../source3/auth/auth_util.c:116(make_user_info_map) Mapping user [ADI]\[schulz] from workstation [TETRA] [2015/02/12 14:39:33.368970, 5] ../source3/libsmb/trustdom_cache.c:195(trustdom_cache_fetch_timestamp) no timestamp for trusted domain cache located. [2015/02/12 14:39:33.396978, 5] ../source3/libsmb/namequery.c:208(saf_fetch) saf_fetch: failed to find server for "ADI" domain [2015/02/12 14:39:33.397193, 3] ../source3/libsmb/namequery.c:3103(get_dc_list) get_dc_list: preferred server list: ", starfish2" [2015/02/12 14:39:33.397390, 5] ../source3/libads/sitename_cache.c:102(sitename_fetch) sitename_fetch: No stored sitename for [2015/02/12 14:39:33.397617, 5] ../source3/libsmb/namecache.c:160(namecache_fetch) no entry for starfish2#20 found. [2015/02/12 14:39:33.397755, 3] ../source3/libsmb/namequery.c:1806(name_resolve_bcast_send) name_resolve_bcast: Attempting broadcast lookup for name starfish2<0x20> [2015/02/12 14:39:33.398320, 5] ../lib/util/util_net.c:890(print_socket_options) Socket options: SO_KEEPALIVE = 0 SO_REUSEADDR = 1 SO_BROADCAST = 1 Could not test socket option TCP_NODELAY. Could not test socket option TCP_KEEPCNT. Could not test socket option TCP_KEEPIDLE. Could not test socket option TCP_KEEPINTVL. IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 110592 SO_RCVBUF = 110592 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 Could not test socket option TCP_QUICKACK. Could not test socket option TCP_DEFER_ACCEPT. [2015/02/12 14:39:33.400801, 4] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) nmb packet from 192.168.2.178(35072) header: id=5214 opcode=Query(0) response=Yes header: flags: bcast=No rec_avail=No rec_des=Yes trunc=No auth=Yes header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 answers: nmb_name=STARFISH2<20> rr_type=32 rr_class=1 ttl=300000 answers 0 char `..... hex 6000C0A802B2 [2015/02/12 14:39:33.401189, 2] ../source3/libsmb/namequery.c:1356(name_query_validator) Got a positive name query response from 192.168.2.178 ( 192.168.2.178 ) [2015/02/12 14:39:33.401616, 5] ../source3/libsmb/namecache.c:78(namecache_store) namecache_store: storing 1 address for starfish2#20: 192.168.2.178 [2015/02/12 14:39:33.402116, 4] ../source3/libsmb/namequery.c:3240(get_dc_list) get_dc_list: returning 1 ip addresses in an ordered list [2015/02/12 14:39:33.402188, 4] ../source3/libsmb/namequery.c:3241(get_dc_list) get_dc_list: 192.168.2.178:0 [2015/02/12 14:39:33.402334, 5] ../source3/libsmb/namecache.c:299(namecache_status_fetch) namecache_status_fetch: no entry for NBT/ADI#1C.20.192.168.2.178 found. [2015/02/12 14:39:33.405217, 4] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) nmb packet from 192.168.2.178(35072) header: id=19066 opcode=Query(0) response=Yes header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=Yes header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 answers: nmb_name=ADI<1c> rr_type=33 rr_class=1 ttl=0 answers 0 char .STARFISH2 hex 10535441524649534832202020202020 answers 10 char .D.STARFISH2 hex 00440053544152464953483220202020 answers 20 char D.ADI hex 20202044004144492020202020202020 answers 30 char ...ADI hex 2020202000C400414449202020202020 answers 40 char ...ADI hex 2020202020201CC40041444920202020 answers 50 char .D.ADI hex 20202020202020201B44004144492020 answers 60 char ...ADI hex 202020202020202020201EC400414449 answers 70 char .D.. hex 2020202020202020202020201D440001 answers 80 char .__MSBROWSE__... hex 025F5F4D5342524F5753455F5F0201C4 answers 90 char .STARFISH2 hex 00535441524649534832202020202020 answers a0 char .D.STARFISH2$ hex 03440053544152464953483224202020 answers b0 char .D.ADMINISTRAT hex 202003440041444D494E495354524154 answers c0 char OR .D.JFCENTRAL hex 4F5220200344004A4643454E5452414C answers d0 char .D.INet~Se hex 202020202020034400494E65747E5365 answers e0 char rvices ...IS~ST hex 72766963657320201CC40049537E5354 answers f0 char ARFISH2....D.STA hex 41524649534832000000004400535441 answers 100 char RFISH2 BD.S hex 52464953483220202020202042440053 answers 110 char TARFISH2 .D hex 5441524649534832202020202020BE44 answers 120 char ...[8.......... hex 0000065B38177F000000000000000000 answers 130 char ................ hex 00000000000000000000000000000000 answers 140 char ............... hex 000000000000000000000000000000 [2015/02/12 14:39:33.407168, 3] ../source3/libsmb/namequery_dc.c:207(rpc_dc_name) rpc_dc_name: Returning DC STARFISH2 (192.168.2.178) for domain ADI [2015/02/12 14:39:33.407618, 3] ../source3/lib/util_sock.c:617(open_socket_out_send) Connecting to 192.168.2.178 at port 445 [2015/02/12 14:39:33.408452, 5] ../lib/util/util_net.c:890(print_socket_options) Socket options: SO_KEEPALIVE = 0 SO_REUSEADDR = 0 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 16384 SO_RCVBUF = 87380 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2015/02/12 14:39:33.518008, 5] ../source3/rpc_client/cli_pipe.c:1748(rpc_pipe_bind_send) Bind RPC Pipe: host STARFISH2 auth_type 0, auth_level 1 [2015/02/12 14:39:33.518506, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.521864, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 52 [2015/02/12 14:39:33.522406, 5] ../source3/rpc_client/cli_pipe.c:1619(check_bind_response) check_bind_response: accepted! [2015/02/12 14:39:33.559952, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.562332, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 32 [2015/02/12 14:39:33.562809, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.564815, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 24 [2015/02/12 14:39:33.568189, 5] ../source3/libsmb/trustdom_cache.c:164(trustdom_cache_fetch) no entry for trusted domain ADI found. [2015/02/12 14:39:33.568462, 5] ../source3/auth/user_info.c:62(make_user_info) attempting to make a user_info for schulz (schulz) [2015/02/12 14:39:33.568532, 5] ../source3/auth/user_info.c:70(make_user_info) making strings for schulz's user_info struct [2015/02/12 14:39:33.568612, 5] ../source3/auth/user_info.c:108(make_user_info) making blobs for schulz's user_info struct [2015/02/12 14:39:33.568681, 3] ../source3/auth/auth.c:178(auth_check_ntlm_password) check_ntlm_password: Checking password for unmapped user [ADI]\[schulz]@[TETRA] with the new password interface [2015/02/12 14:39:33.568801, 3] ../source3/auth/auth.c:181(auth_check_ntlm_password) check_ntlm_password: mapped user is: [ADI]\[schulz]@[TETRA] [2015/02/12 14:39:33.568858, 5] ../lib/util/util.c:559(dump_data) [0000] C7 40 4D F6 19 87 49 91 .@M...I. [2015/02/12 14:39:33.569009, 4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2015/02/12 14:39:33.569114, 4] ../source3/smbd/uid.c:491(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2015/02/12 14:39:33.569173, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2015/02/12 14:39:33.569227, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.569280, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.569589, 4] ../source3/smbd/sec_ctx.c:421(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.569796, 5] ../source3/libsmb/namequery.c:208(saf_fetch) saf_fetch: failed to find server for "ADI" domain [2015/02/12 14:39:33.569868, 3] ../source3/libsmb/namequery.c:3103(get_dc_list) get_dc_list: preferred server list: ", starfish2" [2015/02/12 14:39:33.569983, 5] ../source3/libads/sitename_cache.c:102(sitename_fetch) sitename_fetch: No stored sitename for [2015/02/12 14:39:33.570137, 5] ../source3/libsmb/namecache.c:165(namecache_fetch) name starfish2#20 found. [2015/02/12 14:39:33.570489, 4] ../source3/libsmb/namequery.c:3240(get_dc_list) get_dc_list: returning 1 ip addresses in an ordered list [2015/02/12 14:39:33.570555, 4] ../source3/libsmb/namequery.c:3241(get_dc_list) get_dc_list: 192.168.2.178:0 [2015/02/12 14:39:33.570659, 5] ../source3/libsmb/namecache.c:299(namecache_status_fetch) namecache_status_fetch: no entry for NBT/ADI#1C.20.192.168.2.178 found. [2015/02/12 14:39:33.573464, 4] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) nmb packet from 192.168.2.178(35072) header: id=12633 opcode=Query(0) response=Yes header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=Yes header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 answers: nmb_name=ADI<1c> rr_type=33 rr_class=1 ttl=0 answers 0 char .STARFISH2 hex 10535441524649534832202020202020 answers 10 char .D.STARFISH2 hex 00440053544152464953483220202020 answers 20 char D.ADI hex 20202044004144492020202020202020 answers 30 char ...ADI hex 2020202000C400414449202020202020 answers 40 char ...ADI hex 2020202020201CC40041444920202020 answers 50 char .D.ADI hex 20202020202020201B44004144492020 answers 60 char ...ADI hex 202020202020202020201EC400414449 answers 70 char .D.. hex 2020202020202020202020201D440001 answers 80 char .__MSBROWSE__... hex 025F5F4D5342524F5753455F5F0201C4 answers 90 char .STARFISH2 hex 00535441524649534832202020202020 answers a0 char .D.STARFISH2$ hex 03440053544152464953483224202020 answers b0 char .D.ADMINISTRAT hex 202003440041444D494E495354524154 answers c0 char OR .D.JFCENTRAL hex 4F5220200344004A4643454E5452414C answers d0 char .D.INet~Se hex 202020202020034400494E65747E5365 answers e0 char rvices ...IS~ST hex 72766963657320201CC40049537E5354 answers f0 char ARFISH2....D.STA hex 41524649534832000000004400535441 answers 100 char RFISH2 BD.S hex 52464953483220202020202042440053 answers 110 char TARFISH2 .D hex 5441524649534832202020202020BE44 answers 120 char ...[8.......... hex 0000065B38177F000000000000000000 answers 130 char ................ hex 00000000000000000000000000000000 answers 140 char ............... hex 000000000000000000000000000000 [2015/02/12 14:39:33.575194, 3] ../source3/libsmb/namequery_dc.c:207(rpc_dc_name) rpc_dc_name: Returning DC STARFISH2 (192.168.2.178) for domain ADI [2015/02/12 14:39:33.576564, 3] ../source3/lib/util_sock.c:617(open_socket_out_send) Connecting to 192.168.2.178 at port 445 [2015/02/12 14:39:33.577235, 5] ../lib/util/util_net.c:890(print_socket_options) Socket options: SO_KEEPALIVE = 0 SO_REUSEADDR = 0 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 16384 SO_RCVBUF = 87380 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2015/02/12 14:39:33.587809, 5] ../source3/rpc_client/cli_pipe.c:1748(rpc_pipe_bind_send) Bind RPC Pipe: host STARFISH2 auth_type 0, auth_level 1 [2015/02/12 14:39:33.588018, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.591057, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 52 [2015/02/12 14:39:33.591299, 5] ../source3/rpc_client/cli_pipe.c:1619(check_bind_response) check_bind_response: accepted! [2015/02/12 14:39:33.591530, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.592068, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.592220, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/private/netlogon_creds_cli.tdb [2015/02/12 14:39:33.592338, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/private/netlogon_creds_cli.tdb [2015/02/12 14:39:33.620330, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.621333, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 20 [2015/02/12 14:39:33.642374, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.646700, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 28 [2015/02/12 14:39:33.647214, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.648062, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 20 [2015/02/12 14:39:33.649200, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.652951, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 28 [2015/02/12 14:39:33.653421, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/private/netlogon_creds_cli.tdb [2015/02/12 14:39:33.653931, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/private/netlogon_creds_cli.tdb [2015/02/12 14:39:33.654077, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.654266, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.654537, 5] ../source3/rpc_client/cli_netlogon.c:190(rpccli_setup_netlogon_creds) rpccli_setup_netlogon_creds: using new netlogon_creds cli[BREAM$/BREAM] to STARFISH2 [2015/02/12 14:39:33.658352, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.658631, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.659883, 5] ../auth/gensec/gensec_start.c:664(gensec_start_mech) Starting GENSEC mechanism schannel [2015/02/12 14:39:33.660140, 5] ../source3/rpc_client/cli_pipe.c:1748(rpc_pipe_bind_send) Bind RPC Pipe: host STARFISH2 auth_type 68, auth_level 6 [2015/02/12 14:39:33.660214, 5] ../source3/rpc_client/cli_pipe.c:1012(create_generic_auth_rpc_bind_req) create_generic_auth_rpc_bind_req: generate first token [2015/02/12 14:39:33.660546, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.661508, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 72 [2015/02/12 14:39:33.661751, 5] ../source3/rpc_client/cli_pipe.c:1619(check_bind_response) check_bind_response: accepted! [2015/02/12 14:39:33.661967, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.662223, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.662391, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.662532, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.662707, 5] ../libcli/auth/credentials.c:146(netlogon_creds_step) seed cd87504f:e78e834e [2015/02/12 14:39:33.662779, 5] ../libcli/auth/credentials.c:151(netlogon_creds_step) seed+time 226451c6:e78e834e [2015/02/12 14:39:33.663219, 5] ../libcli/auth/credentials.c:156(netlogon_creds_step) CLIENT bf2a713a:6f5b462d [2015/02/12 14:39:33.663278, 5] ../libcli/auth/credentials.c:162(netlogon_creds_step) seed+time+1 226451c7:e78e834e [2015/02/12 14:39:33.663673, 5] ../libcli/auth/credentials.c:167(netlogon_creds_step) SERVER 7ab927a1:60a97796 [2015/02/12 14:39:33.664316, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.667504, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 80 [2015/02/12 14:39:33.668197, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.668434, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.670888, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.672090, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 80 [2015/02/12 14:39:33.672751, 0] ../source3/auth/auth_domain.c:302(domain_client_validate) domain_client_validate: unable to validate password for user schulz in domain ADI to Domain controller STARFISH2. Error was NT_STATUS_INVALID_COMPUTER_NAME. [2015/02/12 14:39:33.705572, 5] ../source3/auth/auth.c:252(auth_check_ntlm_password) check_ntlm_password: winbind authentication for user [schulz] FAILED with error NT_STATUS_INVALID_COMPUTER_NAME [2015/02/12 14:39:33.705735, 2] ../source3/auth/auth.c:315(auth_check_ntlm_password) check_ntlm_password: Authentication for user [schulz] -> [schulz] FAILED with error NT_STATUS_INVALID_COMPUTER_NAME [2015/02/12 14:39:33.705802, 5] ../source3/auth/auth_ntlmssp.c:188(auth3_check_password) Checking NTLMSSP password for ADI\schulz failed: NT_STATUS_INVALID_COMPUTER_NAME [2015/02/12 14:39:33.705887, 5] ../auth/ntlmssp/ntlmssp_server.c:455(ntlmssp_server_check_password) ../auth/ntlmssp/ntlmssp_server.c:455: Checking NTLMSSP password for ADI\schulz failed: NT_STATUS_INVALID_COMPUTER_NAME [2015/02/12 14:39:33.705974, 2] ../auth/gensec/spnego.c:746(gensec_spnego_server_negTokenTarg) SPNEGO login failed: NT_STATUS_INVALID_COMPUTER_NAME [2015/02/12 14:39:33.706157, 4] ../source3/smbd/sec_ctx.c:421(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.706236, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 1 for /opt/local/samba4/var/lock/smbXsrv_session_global.tdb [2015/02/12 14:39:33.706542, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /opt/local/samba4/var/lock/smbXsrv_session_global.tdb [2015/02/12 14:39:33.706694, 3] ../source3/smbd/error.c:82(error_packet_set) NT error packet at ../source3/smbd/sesssetup.c(267) cmd=115 (SMBsesssetupX) NT_STATUS_INVALID_COMPUTER_NAME [2015/02/12 14:39:33.706931, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.706971, 5] ../source3/lib/util.c:178(show_msg) size=35 smb_com=0x73 smb_rcls=34 smb_reh=1 smb_err=49152 smb_flg=136 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=62578 smb_mid=128 smt_wct=0 smb_bcc=0 [2015/02/12 14:39:33.718109, 3] ../source3/smbd/process.c:1879(process_smb) Transaction 3 of length 240 (0 toread) [2015/02/12 14:39:33.718191, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.718251, 5] ../source3/lib/util.c:178(show_msg) size=236 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=0 smb_mid=192 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 236 (0xEC) smb_vwv[ 2]=16644 (0x4104) smb_vwv[ 3]= 50 (0x32) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 74 (0x4A) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 212 (0xD4) smb_vwv[11]=40960 (0xA000) smb_bcc=177 [2015/02/12 14:39:33.718670, 3] ../source3/smbd/process.c:1489(switch_message) switch message SMBsesssetupX (pid 6754) conn 0x0 [2015/02/12 14:39:33.718736, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.718794, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.718849, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.718951, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.719012, 3] ../source3/smbd/sesssetup.c:609(reply_sesssetup_and_X) wct=12 flg2=0xc807 [2015/02/12 14:39:33.719104, 2] ../source3/smbd/sesssetup.c:551(setup_new_vc_session) setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources. [2015/02/12 14:39:33.719161, 3] ../source3/smbd/sesssetup.c:142(reply_sesssetup_and_X_spnego) Doing spnego session setup [2015/02/12 14:39:33.719238, 3] ../source3/smbd/sesssetup.c:183(reply_sesssetup_and_X_spnego) NativeOS=[Windows 2002 Service Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[] [2015/02/12 14:39:33.719333, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 1 for /opt/local/samba4/var/lock/smbXsrv_session_global.tdb [2015/02/12 14:39:33.719646, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /opt/local/samba4/var/lock/smbXsrv_session_global.tdb [2015/02/12 14:39:33.719727, 5] ../source3/auth/auth.c:477(make_auth_context_subsystem) Making default auth method list for server role = 'domain member' [2015/02/12 14:39:33.719809, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match guest [2015/02/12 14:39:33.719873, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method guest has a valid init [2015/02/12 14:39:33.719929, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match sam [2015/02/12 14:39:33.719990, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method sam has a valid init [2015/02/12 14:39:33.720078, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match winbind:ntdomain [2015/02/12 14:39:33.720138, 5] ../source3/auth/auth.c:378(load_auth_module) load_auth_module: Attempting to find an auth method to match ntdomain [2015/02/12 14:39:33.720278, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method ntdomain has a valid init [2015/02/12 14:39:33.720332, 5] ../source3/auth/auth.c:403(load_auth_module) load_auth_module: auth method winbind has a valid init [2015/02/12 14:39:33.720548, 5] ../auth/gensec/gensec_start.c:664(gensec_start_mech) Starting GENSEC mechanism spnego [2015/02/12 14:39:33.720619, 4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.720683, 4] ../source3/smbd/uid.c:491(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2015/02/12 14:39:33.720741, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.720795, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.720847, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.721113, 5] ../auth/gensec/gensec_start.c:664(gensec_start_mech) Starting GENSEC submechanism ntlmssp [2015/02/12 14:39:33.721223, 3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0xa2088207 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_NEGOTIATE_OEM NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_NEGOTIATE_VERSION NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_56 [2015/02/12 14:39:33.721553, 4] ../source3/smbd/sec_ctx.c:421(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.721664, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.721703, 5] ../source3/lib/util.c:178(show_msg) size=274 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=21942 smb_mid=192 smt_wct=4 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 169 (0xA9) smb_bcc=231 [2015/02/12 14:39:33.723962, 3] ../source3/smbd/process.c:1879(process_smb) Transaction 4 of length 326 (0 toread) [2015/02/12 14:39:33.724067, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.724106, 5] ../source3/lib/util.c:178(show_msg) size=322 smb_com=0x73 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=24 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=21942 smb_mid=256 smt_wct=12 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 322 (0x142) smb_vwv[ 2]=16644 (0x4104) smb_vwv[ 3]= 50 (0x32) smb_vwv[ 4]= 0 (0x0) smb_vwv[ 5]= 0 (0x0) smb_vwv[ 6]= 0 (0x0) smb_vwv[ 7]= 160 (0xA0) smb_vwv[ 8]= 0 (0x0) smb_vwv[ 9]= 0 (0x0) smb_vwv[10]= 212 (0xD4) smb_vwv[11]=40960 (0xA000) smb_bcc=263 [2015/02/12 14:39:33.724519, 3] ../source3/smbd/process.c:1489(switch_message) switch message SMBsesssetupX (pid 6754) conn 0x0 [2015/02/12 14:39:33.724593, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.724651, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.724704, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.724793, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.724854, 3] ../source3/smbd/sesssetup.c:609(reply_sesssetup_and_X) wct=12 flg2=0xc807 [2015/02/12 14:39:33.724910, 2] ../source3/smbd/sesssetup.c:551(setup_new_vc_session) setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources. [2015/02/12 14:39:33.724966, 3] ../source3/smbd/sesssetup.c:142(reply_sesssetup_and_X_spnego) Doing spnego session setup [2015/02/12 14:39:33.725060, 3] ../source3/smbd/sesssetup.c:183(reply_sesssetup_and_X_spnego) NativeOS=[Windows 2002 Service Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[] [2015/02/12 14:39:33.725189, 4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.725251, 4] ../source3/smbd/uid.c:491(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2015/02/12 14:39:33.725306, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.725359, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.725409, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.725563, 3] ../auth/ntlmssp/ntlmssp_server.c:359(ntlmssp_server_preauth) Got user=[schulz] domain=[ADI] workstation=[TETRA] len1=24 len2=24 [2015/02/12 14:39:33.725643, 5] ../source3/auth/auth_ntlmssp.c:124(auth3_set_challenge) auth_context challenge set by NTLMSSP callback (NTLM2) [2015/02/12 14:39:33.725697, 5] ../source3/auth/auth_ntlmssp.c:125(auth3_set_challenge) challenge is: [2015/02/12 14:39:33.725748, 5] ../lib/util/util.c:559(dump_data) [0000] 92 C5 5E 9F C8 ED CD 8D ..^..... [2015/02/12 14:39:33.725863, 3] ../source3/param/loadparm.c:3647(lp_load_ex) lp_load_ex: refreshing parameters [2015/02/12 14:39:33.725932, 5] ../source3/param/loadparm.c:1234(free_param_opts) Freeing parametrics: [2015/02/12 14:39:33.726309, 3] ../source3/param/loadparm.c:564(init_globals) Initialising global parameters [2015/02/12 14:39:33.726655, 5] ../lib/util/debug.c:334(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 [2015/02/12 14:39:33.727392, 3] ../source3/param/loadparm.c:2597(lp_do_section) Processing section "[global]" doing parameter max protocol = NT1 doing parameter workgroup = adi doing parameter server string = doing parameter security = domain doing parameter load printers = yes doing parameter printcap name = /etc/printers.samba doing parameter printing = sysv doing parameter guest account = nobody2 doing parameter log file = /opt/local/samba4/var/logs/%h/log.%m doing parameter max log size = 150 doing parameter password server = starfish2 doing parameter passdb backend = tdbsam doing parameter socket options = TCP_NODELAY doing parameter dns proxy = no doing parameter dos filemode = yes doing parameter delete readonly = yes doing parameter name resolve order = bcast host doing parameter host msdfs = yes doing parameter msdfs root = yes doing parameter unix extensions = no doing parameter wide links = yes doing parameter include = /opt/local/samba4/etc/smb.conf.%h [2015/02/12 14:39:33.729224, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[homes]" doing parameter comment = Home Directories doing parameter browseable = no doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.729545, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[users]" doing parameter comment = User Directories doing parameter path = /home/users doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.729874, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[projects]" doing parameter comment = Project Directories doing parameter path = /home/projects doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.730229, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[zacltest1]" doing parameter comment = Acl test doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.730558, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[zacltest2]" doing parameter comment = Acl test doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.730880, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[zacltest3]" doing parameter comment = Acl test doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes doing parameter inherit acls = yes [2015/02/12 14:39:33.731228, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[zacltest4]" doing parameter comment = Acl test doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes doing parameter inherit acls = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.731602, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[root]" doing parameter comment = All Directories doing parameter path = / doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.731877, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[ftp]" doing parameter comment = Ftp Directories doing parameter path = /home/users/ftp doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.732233, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[adi-rel]" doing parameter comment = Adi Software Directories doing parameter path = /home/projects/adirel/release/winnt doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.732512, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[adi-projects]" doing parameter comment = ADI Project Directories doing parameter path = /home/projects/adirel/projects doing parameter browseable = yes doing parameter writable = yes doing parameter force create mode = 0666 doing parameter force directory mode = 0666 [2015/02/12 14:39:33.732915, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[production_test_results]" doing parameter comment = sqa production test results doing parameter path = /home/projects/sqa/production_test_results doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.733275, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[system_configuration_info]" doing parameter comment = sqa system configuration info doing parameter path = /home/projects/sqa/system_configuration_info doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.733609, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[production_board_test_results]" doing parameter comment = sqa production board test results doing parameter path = /home/projects/sqa/production_board_test_results doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.733941, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[marketing]" doing parameter comment = Marketing Project Directories doing parameter path = /home/projects/marketing doing parameter browseable = yes doing parameter writable = yes doing parameter force create mode = 0664 doing parameter force directory mode = 0664 [2015/02/12 14:39:33.734351, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[CustomerSupport]" doing parameter comment = CustomerSupport Project Directories doing parameter path = /home/projects/CustomerSupport doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.734719, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[foom]" doing parameter comment = Foom Project Directories doing parameter path = /home/foom doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.735068, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[proposals]" doing parameter comment = proposals doing parameter path = /home/projects/proposals doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.735398, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[bms]" doing parameter comment = bms doing parameter path = /home/projects/bms doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.735721, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[timecards]" doing parameter comment = Timecard Submission doing parameter path = /home/projects/timecards doing parameter browseable = yes doing parameter writable = yes doing parameter force create mode = 0666 doing parameter force directory mode = 0666 [2015/02/12 14:39:33.736131, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[ProjectManagement]" doing parameter comment = Project Management Directories doing parameter path = /home/projects/ProjectManagement doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.736486, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[adi]" doing parameter comment = Adi Software Directories doing parameter path = /home/projects/adirel doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.736762, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[ariel]" doing parameter comment = Software Development Directories doing parameter path = /home/projects/ariel doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.737059, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[proteus]" doing parameter comment = Software Development Directories doing parameter path = /home/projects/proteus doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.737350, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[support]" doing parameter comment = Customer Support Directories doing parameter path = /home/users/support doing parameter browseable = yes doing parameter writable = yes doing parameter create mask = 0777 doing parameter force create mode = 0666 [2015/02/12 14:39:33.737729, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[scanner]" doing parameter comment = Customer Support Directories doing parameter path = /home/users/scanner doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.738006, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[hwdesign]" doing parameter comment = Hardware Design Directories doing parameter path = /home/projects/hwdesign doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.738365, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[HR Recruitment]" doing parameter comment = HR Recruitment doing parameter path = /home/projects/HR_Recruitment doing parameter browseable = yes doing parameter writable = yes doing parameter inherit permissions = yes [2015/02/12 14:39:33.738696, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[www]" doing parameter comment = Hardware Design Directories doing parameter path = /home/projects/www doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.739006, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[hr]" doing parameter comment = Human Resources doing parameter path = /home/projects/hr doing parameter browseable = yes doing parameter writable = yes [2015/02/12 14:39:33.739306, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[W000Axxxx]" doing parameter comment = Hardware Design W000Axxxx Directory doing parameter path = /home/projects/hwdesign/Release/W_Software/W000Axxxx doing parameter browseable = yes doing parameter writable = no [2015/02/12 14:39:33.739590, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[W_Software]" doing parameter comment = Hardware Design W_Software Directory doing parameter path = /home/projects/hwdesign/Release/W_Software doing parameter browseable = yes doing parameter writable = no [2015/02/12 14:39:33.739892, 2] ../source3/param/loadparm.c:2614(lp_do_section) Processing section "[printers]" doing parameter comment = All Printers doing parameter path = /var/tmp/samba doing parameter browseable = no doing parameter writable = no doing parameter printable = yes [2015/02/12 14:39:33.740439, 4] ../source3/param/loadparm.c:3688(lp_load_ex) pm_process() returned Yes [2015/02/12 14:39:33.740629, 3] ../source3/param/loadparm.c:1495(lp_add_ipc) adding IPC service [2015/02/12 14:39:33.740756, 5] ../source3/auth/auth_util.c:116(make_user_info_map) Mapping user [ADI]\[schulz] from workstation [TETRA] [2015/02/12 14:39:33.741113, 5] ../source3/libsmb/trustdom_cache.c:195(trustdom_cache_fetch_timestamp) no timestamp for trusted domain cache located. [2015/02/12 14:39:33.741391, 5] ../source3/libsmb/namequery.c:211(saf_fetch) saf_fetch: Returning "STARFISH2" for "ADI" domain [2015/02/12 14:39:33.741459, 3] ../source3/libsmb/namequery.c:3103(get_dc_list) get_dc_list: preferred server list: "STARFISH2, starfish2" [2015/02/12 14:39:33.741574, 5] ../source3/libads/sitename_cache.c:102(sitename_fetch) sitename_fetch: No stored sitename for [2015/02/12 14:39:33.741664, 5] ../source3/libsmb/namecache.c:165(namecache_fetch) name STARFISH2#20 found. [2015/02/12 14:39:33.741981, 5] ../source3/libads/sitename_cache.c:102(sitename_fetch) sitename_fetch: No stored sitename for [2015/02/12 14:39:33.742114, 5] ../source3/libsmb/namecache.c:165(namecache_fetch) name starfish2#20 found. [2015/02/12 14:39:33.742300, 4] ../source3/libsmb/namequery.c:3240(get_dc_list) get_dc_list: returning 1 ip addresses in an ordered list [2015/02/12 14:39:33.742366, 4] ../source3/libsmb/namequery.c:3241(get_dc_list) get_dc_list: 192.168.2.178:0 [2015/02/12 14:39:33.742470, 5] ../source3/libsmb/namecache.c:299(namecache_status_fetch) namecache_status_fetch: no entry for NBT/ADI#1C.20.192.168.2.178 found. [2015/02/12 14:39:33.745247, 4] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) nmb packet from 192.168.2.178(35072) header: id=27121 opcode=Query(0) response=Yes header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=Yes header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 answers: nmb_name=ADI<1c> rr_type=33 rr_class=1 ttl=0 answers 0 char .STARFISH2 hex 10535441524649534832202020202020 answers 10 char .D.STARFISH2 hex 00440053544152464953483220202020 answers 20 char D.ADI hex 20202044004144492020202020202020 answers 30 char ...ADI hex 2020202000C400414449202020202020 answers 40 char ...ADI hex 2020202020201CC40041444920202020 answers 50 char .D.ADI hex 20202020202020201B44004144492020 answers 60 char ...ADI hex 202020202020202020201EC400414449 answers 70 char .D.. hex 2020202020202020202020201D440001 answers 80 char .__MSBROWSE__... hex 025F5F4D5342524F5753455F5F0201C4 answers 90 char .STARFISH2 hex 00535441524649534832202020202020 answers a0 char .D.STARFISH2$ hex 03440053544152464953483224202020 answers b0 char .D.ADMINISTRAT hex 202003440041444D494E495354524154 answers c0 char OR .D.JFCENTRAL hex 4F5220200344004A4643454E5452414C answers d0 char .D.INet~Se hex 202020202020034400494E65747E5365 answers e0 char rvices ...IS~ST hex 72766963657320201CC40049537E5354 answers f0 char ARFISH2....D.STA hex 41524649534832000000004400535441 answers 100 char RFISH2 BD.S hex 52464953483220202020202042440053 answers 110 char TARFISH2 .D hex 5441524649534832202020202020BE44 answers 120 char ...[8.......... hex 0000065B38177F000000000000000000 answers 130 char ................ hex 00000000000000000000000000000000 answers 140 char ............... hex 000000000000000000000000000000 [2015/02/12 14:39:33.747005, 3] ../source3/libsmb/namequery_dc.c:207(rpc_dc_name) rpc_dc_name: Returning DC STARFISH2 (192.168.2.178) for domain ADI [2015/02/12 14:39:33.747245, 3] ../source3/lib/util_sock.c:617(open_socket_out_send) Connecting to 192.168.2.178 at port 445 [2015/02/12 14:39:33.747824, 5] ../lib/util/util_net.c:890(print_socket_options) Socket options: SO_KEEPALIVE = 0 SO_REUSEADDR = 0 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 16384 SO_RCVBUF = 87380 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2015/02/12 14:39:33.757010, 5] ../source3/rpc_client/cli_pipe.c:1748(rpc_pipe_bind_send) Bind RPC Pipe: host STARFISH2 auth_type 0, auth_level 1 [2015/02/12 14:39:33.757272, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.759186, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 52 [2015/02/12 14:39:33.759428, 5] ../source3/rpc_client/cli_pipe.c:1619(check_bind_response) check_bind_response: accepted! [2015/02/12 14:39:33.759597, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.761612, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 32 [2015/02/12 14:39:33.761892, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.763825, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 24 [2015/02/12 14:39:33.766383, 5] ../source3/libsmb/trustdom_cache.c:164(trustdom_cache_fetch) no entry for trusted domain ADI found. [2015/02/12 14:39:33.766573, 5] ../source3/auth/user_info.c:62(make_user_info) attempting to make a user_info for schulz (schulz) [2015/02/12 14:39:33.766640, 5] ../source3/auth/user_info.c:70(make_user_info) making strings for schulz's user_info struct [2015/02/12 14:39:33.766709, 5] ../source3/auth/user_info.c:108(make_user_info) making blobs for schulz's user_info struct [2015/02/12 14:39:33.766772, 3] ../source3/auth/auth.c:178(auth_check_ntlm_password) check_ntlm_password: Checking password for unmapped user [ADI]\[schulz]@[TETRA] with the new password interface [2015/02/12 14:39:33.766831, 3] ../source3/auth/auth.c:181(auth_check_ntlm_password) check_ntlm_password: mapped user is: [ADI]\[schulz]@[TETRA] [2015/02/12 14:39:33.766886, 5] ../lib/util/util.c:559(dump_data) [0000] 92 C5 5E 9F C8 ED CD 8D ..^..... [2015/02/12 14:39:33.767019, 4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2015/02/12 14:39:33.767120, 4] ../source3/smbd/uid.c:491(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2015/02/12 14:39:33.767180, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2015/02/12 14:39:33.767236, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.767353, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.767525, 4] ../source3/smbd/sec_ctx.c:421(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2015/02/12 14:39:33.767685, 5] ../source3/libsmb/namequery.c:211(saf_fetch) saf_fetch: Returning "STARFISH2" for "ADI" domain [2015/02/12 14:39:33.767753, 3] ../source3/libsmb/namequery.c:3103(get_dc_list) get_dc_list: preferred server list: "STARFISH2, starfish2" [2015/02/12 14:39:33.767872, 5] ../source3/libads/sitename_cache.c:102(sitename_fetch) sitename_fetch: No stored sitename for [2015/02/12 14:39:33.767972, 5] ../source3/libsmb/namecache.c:165(namecache_fetch) name STARFISH2#20 found. [2015/02/12 14:39:33.768314, 5] ../source3/libads/sitename_cache.c:102(sitename_fetch) sitename_fetch: No stored sitename for [2015/02/12 14:39:33.768390, 5] ../source3/libsmb/namecache.c:165(namecache_fetch) name starfish2#20 found. [2015/02/12 14:39:33.768556, 4] ../source3/libsmb/namequery.c:3240(get_dc_list) get_dc_list: returning 1 ip addresses in an ordered list [2015/02/12 14:39:33.768619, 4] ../source3/libsmb/namequery.c:3241(get_dc_list) get_dc_list: 192.168.2.178:0 [2015/02/12 14:39:33.768722, 5] ../source3/libsmb/namecache.c:299(namecache_status_fetch) namecache_status_fetch: no entry for NBT/ADI#1C.20.192.168.2.178 found. [2015/02/12 14:39:33.771659, 4] ../source3/libsmb/nmblib.c:108(debug_nmb_packet) nmb packet from 192.168.2.178(35072) header: id=15693 opcode=Query(0) response=Yes header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=Yes header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 answers: nmb_name=ADI<1c> rr_type=33 rr_class=1 ttl=0 answers 0 char .STARFISH2 hex 10535441524649534832202020202020 answers 10 char .D.STARFISH2 hex 00440053544152464953483220202020 answers 20 char D.ADI hex 20202044004144492020202020202020 answers 30 char ...ADI hex 2020202000C400414449202020202020 answers 40 char ...ADI hex 2020202020201CC40041444920202020 answers 50 char .D.ADI hex 20202020202020201B44004144492020 answers 60 char ...ADI hex 202020202020202020201EC400414449 answers 70 char .D.. hex 2020202020202020202020201D440001 answers 80 char .__MSBROWSE__... hex 025F5F4D5342524F5753455F5F0201C4 answers 90 char .STARFISH2 hex 00535441524649534832202020202020 answers a0 char .D.STARFISH2$ hex 03440053544152464953483224202020 answers b0 char .D.ADMINISTRAT hex 202003440041444D494E495354524154 answers c0 char OR .D.JFCENTRAL hex 4F5220200344004A4643454E5452414C answers d0 char .D.INet~Se hex 202020202020034400494E65747E5365 answers e0 char rvices ...IS~ST hex 72766963657320201CC40049537E5354 answers f0 char ARFISH2....D.STA hex 41524649534832000000004400535441 answers 100 char RFISH2 BD.S hex 52464953483220202020202042440053 answers 110 char TARFISH2 .D hex 5441524649534832202020202020BE44 answers 120 char ...[8.......... hex 0000065B38177F000000000000000000 answers 130 char ................ hex 00000000000000000000000000000000 answers 140 char ............... hex 000000000000000000000000000000 [2015/02/12 14:39:33.773344, 3] ../source3/libsmb/namequery_dc.c:207(rpc_dc_name) rpc_dc_name: Returning DC STARFISH2 (192.168.2.178) for domain ADI [2015/02/12 14:39:33.773983, 3] ../source3/lib/util_sock.c:617(open_socket_out_send) Connecting to 192.168.2.178 at port 445 [2015/02/12 14:39:33.774614, 5] ../lib/util/util_net.c:890(print_socket_options) Socket options: SO_KEEPALIVE = 0 SO_REUSEADDR = 0 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 16384 SO_RCVBUF = 87380 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2015/02/12 14:39:33.781750, 5] ../source3/rpc_client/cli_netlogon.c:150(rpccli_setup_netlogon_creds) rpccli_setup_netlogon_creds: using cached netlogon_creds cli[BREAM$/BREAM] to STARFISH2 [2015/02/12 14:39:33.782761, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.783293, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.784304, 5] ../auth/gensec/gensec_start.c:664(gensec_start_mech) Starting GENSEC mechanism schannel [2015/02/12 14:39:33.784441, 5] ../source3/rpc_client/cli_pipe.c:1748(rpc_pipe_bind_send) Bind RPC Pipe: host STARFISH2 auth_type 68, auth_level 6 [2015/02/12 14:39:33.784512, 5] ../source3/rpc_client/cli_pipe.c:1012(create_generic_auth_rpc_bind_req) create_generic_auth_rpc_bind_req: generate first token [2015/02/12 14:39:33.784697, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.787732, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 72 [2015/02/12 14:39:33.787976, 5] ../source3/rpc_client/cli_pipe.c:1619(check_bind_response) check_bind_response: accepted! [2015/02/12 14:39:33.788129, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.788333, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.788431, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.788561, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.788709, 5] ../libcli/auth/credentials.c:146(netlogon_creds_step) seed cd87504f:e78e834e [2015/02/12 14:39:33.788774, 5] ../libcli/auth/credentials.c:151(netlogon_creds_step) seed+time 226451c6:e78e834e [2015/02/12 14:39:33.789207, 5] ../libcli/auth/credentials.c:156(netlogon_creds_step) CLIENT bf2a713a:6f5b462d [2015/02/12 14:39:33.789265, 5] ../libcli/auth/credentials.c:162(netlogon_creds_step) seed+time+1 226451c7:e78e834e [2015/02/12 14:39:33.789660, 5] ../libcli/auth/credentials.c:167(netlogon_creds_step) SERVER 7ab927a1:60a97796 [2015/02/12 14:39:33.790072, 5] ../source3/rpc_client/cli_pipe.c:756(rpc_api_pipe_send) rpc_api_pipe: host STARFISH2 [2015/02/12 14:39:33.791081, 5] ../source3/rpc_client/cli_pipe.c:98(rpc_read_send) rpc_read_send: data_to_read: 80 [2015/02/12 14:39:33.791504, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.791703, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 2 for /opt/local/samba4/var/lock/g_lock.tdb [2015/02/12 14:39:33.792103, 0] ../source3/auth/auth_domain.c:302(domain_client_validate) domain_client_validate: unable to validate password for user schulz in domain ADI to Domain controller STARFISH2. Error was NT_STATUS_ACCESS_DENIED. [2015/02/12 14:39:33.793831, 5] ../source3/auth/auth.c:252(auth_check_ntlm_password) check_ntlm_password: winbind authentication for user [schulz] FAILED with error NT_STATUS_ACCESS_DENIED [2015/02/12 14:39:33.793983, 2] ../source3/auth/auth.c:315(auth_check_ntlm_password) check_ntlm_password: Authentication for user [schulz] -> [schulz] FAILED with error NT_STATUS_ACCESS_DENIED [2015/02/12 14:39:33.794081, 5] ../source3/auth/auth_ntlmssp.c:188(auth3_check_password) Checking NTLMSSP password for ADI\schulz failed: NT_STATUS_ACCESS_DENIED [2015/02/12 14:39:33.794151, 5] ../auth/ntlmssp/ntlmssp_server.c:455(ntlmssp_server_check_password) ../auth/ntlmssp/ntlmssp_server.c:455: Checking NTLMSSP password for ADI\schulz failed: NT_STATUS_ACCESS_DENIED [2015/02/12 14:39:33.794294, 2] ../auth/gensec/spnego.c:746(gensec_spnego_server_negTokenTarg) SPNEGO login failed: NT_STATUS_ACCESS_DENIED [2015/02/12 14:39:33.794428, 4] ../source3/smbd/sec_ctx.c:421(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.794501, 5] ../lib/dbwrap/dbwrap.c:178(dbwrap_check_lock_order) check lock order 1 for /opt/local/samba4/var/lock/smbXsrv_session_global.tdb [2015/02/12 14:39:33.794687, 5] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) release lock order 1 for /opt/local/samba4/var/lock/smbXsrv_session_global.tdb [2015/02/12 14:39:33.794805, 3] ../source3/smbd/error.c:82(error_packet_set) NT error packet at ../source3/smbd/sesssetup.c(267) cmd=115 (SMBsesssetupX) NT_STATUS_ACCESS_DENIED [2015/02/12 14:39:33.794875, 5] ../source3/lib/util.c:168(show_msg) [2015/02/12 14:39:33.794912, 5] ../source3/lib/util.c:178(show_msg) size=35 smb_com=0x73 smb_rcls=34 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51207 smb_tid=0 smb_pid=65279 smb_uid=21942 smb_mid=256 smt_wct=0 smb_bcc=0 [2015/02/12 14:39:33.797177, 5] ../source3/lib/util_sock.c:132(read_fd_with_timeout) read_fd_with_timeout: blocking read. EOF from client. [2015/02/12 14:39:33.797257, 5] ../source3/smbd/process.c:553(receive_smb_talloc) receive_smb_raw_talloc failed for client ipv4:192.168.2.23:2400 read error = NT_STATUS_END_OF_FILE. [2015/02/12 14:39:33.797415, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.797478, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.797533, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.797631, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.797691, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.797744, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.797795, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.797873, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.797940, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.797996, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.798076, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.798157, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.798266, 4] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2015/02/12 14:39:33.798321, 5] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2015/02/12 14:39:33.798373, 5] ../source3/auth/token_util.c:639(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2015/02/12 14:39:33.798451, 5] ../source3/smbd/uid.c:425(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2015/02/12 14:39:33.798987, 3] ../source3/smbd/server_exit.c:246(exit_server_common) Server exit (failed to receive smb request)