Attachment 10724 Details for Bug 11098 – Log file log.tetra

Log file log.tetra

log.tetra (text/plain), 87.78 KB, created by Tom Schulz on 2015-02-13 18:59:40 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Tom Schulz

Created: 2015-02-13 18:59:40 UTC

Size: 87.78 KB

patch

obsolete

>[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)

Actions: View

Attachments on bug 11098: 10722 | 10723 | 10724 | 10760