[Samba] Mounting Linux Samba Shares on Windows when Active Directory Server is down

Andy Liebman andyliebman at aol.com
Sat Oct 26 09:38:54 MDT 2013


Hi Jeremy!  Long time since we last spoke.  Thanks for replying to this 
question.  Please see below.

>> Is there any way for Windows and Linux to do a "caching dance" so
>> that Windows users are able to mount the Linux shares at least for
>> some amount of time via cached credentials if the Active Directory
>> server is down?  Setting the smb.conf option "winbind offline logon
>> = true" doesn't seem to help here.
>>
>
>Whilst you have a valid kerberos ticket we should still
>authenticate you against a member server.
>What do your smbd logs say when the client is trying to
>attach to the Samba share ?
>
>Jeremy.

I just ran another test here.  With the AD server accessible, the user 
"andyl" logged into the Linux (Ubuntu) server at 11:08 and successfully 
browsed and connected to some Linux shares.  Then I disconnected the AD 
server.  At 11:12, you can see in the Linux logs that an attempt was 
made by andyl to connect.  I think the relevant lines are:

[2013/10/26 11:12:29.712417,  2] auth/auth.c:319(check_ntlm_password)
   check_ntlm_password:  Authentication for user [andyl] -> [andyl] 
FAILED with error NT_STATUS_NO_LOGON_SERVERS
[2013/10/26 11:12:29.712475,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/sesssetup.c(124) cmd=115 (SMBsesssetupX) 
NT_STATUS_NO_LOGON_SERVERS
[2013/10/26 11:12:42.348928,  1] smbd/process.c:457(receive_smb_talloc)
   receive_smb_raw_talloc failed for client 172.16.3.129 read error = 
NT_STATUS_CONNECTION_RESET.

The entire logs are below (not too long I hope)

-- Andy


log.win-vnekof9be96


[2013/10/26 11:08:06.030737,  3] smbd/process.c:1467(switch_message)
  switch message SMBtconX (pid 2305) conn 0x0
[2013/10/26 11:08:06.030836,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:06.030878,  4] smbd/reply.c:794(reply_tcon_and_X)
  Client requested device type [?????] for share [SMB FILE EXCHANGE]
[2013/10/26 11:08:06.030918,  3] lib/access.c:338(allow_access)
  Allowed connection from 172.16.3.129 (172.16.3.129)
[2013/10/26 11:08:06.030964,  4] smbd/sec_ctx.c:214(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2013/10/26 11:08:06.030990,  4] smbd/uid.c:460(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2013/10/26 11:08:06.031013,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2013/10/26 11:08:06.031065,  4] smbd/sec_ctx.c:422(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:06.031463,  3] smbd/service.c:581(find_forced_group)
  Forced group editors
[2013/10/26 11:08:06.031496,  3] 
smbd/service.c:837(make_connection_snum)
   Connect path is '/home/editshare/FileExchange/SMB' for service [SMB 
File Exchange]
[2013/10/26 11:08:06.031534,  3] smbd/vfs.c:102(vfs_init_default)
  Initialising default vfs hooks
[2013/10/26 11:08:06.031557,  3] smbd/vfs.c:128(vfs_init_custom)
  Initialising custom vfs hooks from [/[Default VFS]/]
[2013/10/26 11:08:06.031623,  3] 
../libcli/security/dom_sid.c:208(dom_sid_parse_endp)
  string_to_sid: SID @editors is not in a valid format
[2013/10/26 11:08:06.031735,  4] smbd/sec_ctx.c:214(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2013/10/26 11:08:06.031763,  4] smbd/uid.c:460(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2013/10/26 11:08:06.031786,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2013/10/26 11:08:06.031829,  4] smbd/sec_ctx.c:422(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:06.032091,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (16777227, 500) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:06.032129,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:06.032170,  1] 
smbd/service.c:1081(make_connection_snum)
   win-vnekof9be96 (172.16.3.129) connect to service SMB File Exchange 
initially as user MYDOMAIN\andyl (uid=16777227, gid=500) (pid 2305)
[2013/10/26 11:08:06.032219,  3] smbd/reply.c:871(reply_tcon_and_X)
  tconX service=SMB FILE EXCHANGE
[2013/10/26 11:08:08.017632,  3] smbd/process.c:1662(process_smb)
  Transaction 26 of length 80 (0 toread)
[2013/10/26 11:08:08.017721,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans2 (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.017754,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (16777227, 1007) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:08.017807,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
[2013/10/26 11:08:08.017857,  3] 
smbd/trans2.c:5117(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2013/10/26 11:08:08.017920,  3] smbd/vfs.c:905(check_reduced_name)
  check_reduced_name [.] [/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content]
[2013/10/26 11:08:08.017947,  3] smbd/vfs.c:1039(check_reduced_name)
   check_reduced_name: . reduced to 
/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
[2013/10/26 11:08:08.017992,  3] 
smbd/trans2.c:5261(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo . (fnum = -1) level=1004 call=5 total_data=0
[2013/10/26 11:08:08.047746,  3] smbd/process.c:1662(process_smb)
  Transaction 27 of length 80 (0 toread)
[2013/10/26 11:08:08.047844,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans2 (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.047869,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.047895,  3] 
smbd/trans2.c:5117(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1005
[2013/10/26 11:08:08.047929,  3] smbd/vfs.c:905(check_reduced_name)
  check_reduced_name [.] [/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content]
[2013/10/26 11:08:08.047964,  3] smbd/vfs.c:1039(check_reduced_name)
   check_reduced_name: . reduced to 
/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
[2013/10/26 11:08:08.048021,  3] 
smbd/trans2.c:5261(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo . (fnum = -1) level=1005 call=5 total_data=0
[2013/10/26 11:08:08.197438,  3] smbd/process.c:1662(process_smb)
  Transaction 28 of length 90 (0 toread)
[2013/10/26 11:08:08.197529,  3] smbd/process.c:1467(switch_message)
  switch message SMBntcreateX (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.197571,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.197610,  3] smbd/vfs.c:905(check_reduced_name)
  check_reduced_name [.] [/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content]
[2013/10/26 11:08:08.197638,  3] smbd/vfs.c:1039(check_reduced_name)
   check_reduced_name: . reduced to 
/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
[2013/10/26 11:08:08.198014,  4] smbd/sec_ctx.c:214(push_sec_ctx)
  push_sec_ctx(16777227, 1007) : sec_ctx_stack_ndx = 1
[2013/10/26 11:08:08.198049,  4] smbd/uid.c:460(push_conn_ctx)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2013/10/26 11:08:08.198073,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2013/10/26 11:08:08.198151,  4] smbd/sec_ctx.c:214(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2013/10/26 11:08:08.198176,  4] smbd/uid.c:460(push_conn_ctx)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 1
[2013/10/26 11:08:08.198199,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2013/10/26 11:08:08.198233,  4] smbd/sec_ctx.c:422(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2013/10/26 11:08:08.198262,  4] smbd/sec_ctx.c:422(pop_sec_ctx)
  pop_sec_ctx (16777227, 1007) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:08.199296,  3] smbd/process.c:1662(process_smb)
  Transaction 29 of length 76 (0 toread)
[2013/10/26 11:08:08.199344,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans2 (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.199365,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.199388,  3] 
smbd/trans2.c:5032(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1006
[2013/10/26 11:08:08.199416,  3] 
smbd/trans2.c:5261(call_trans2qfilepathinfo)
   call_trans2qfilepathinfo . (fnum = 25559) level=1006 call=7 
total_data=0
[2013/10/26 11:08:08.199876,  3] smbd/process.c:1662(process_smb)
  Transaction 30 of length 88 (0 toread)
[2013/10/26 11:08:08.199913,  3] smbd/process.c:1467(switch_message)
  switch message SMBnttrans (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.199935,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.199959,  3] 
smbd/nttrans.c:1703(call_nt_transact_notify_change)
  call_nt_transact_notify_change
[2013/10/26 11:08:08.199985,  3] 
smbd/nttrans.c:1720(call_nt_transact_notify_change)
   call_nt_transact_notify_change: notify change called on ., filter = 
FILE_NAME|DIR_NAME|ATTRIBUTES|LAST_WRITE, recursive = 0
[2013/10/26 11:08:08.200751,  3] smbd/process.c:1662(process_smb)
  Transaction 31 of length 114 (0 toread)
[2013/10/26 11:08:08.200799,  3] smbd/process.c:1467(switch_message)
  switch message SMBntcreateX (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.200822,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.200884,  3] smbd/vfs.c:905(check_reduced_name)
   check_reduced_name [desktop.ini] 
[/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content]
[2013/10/26 11:08:08.200922,  3] smbd/vfs.c:1039(check_reduced_name)
   check_reduced_name: desktop.ini reduced to 
/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content/desktop.ini
[2013/10/26 11:08:08.200950,  3] smbd/dosmode.c:159(unix_mode)
  unix_mode(desktop.ini) returning 02664
[2013/10/26 11:08:08.200982,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/error.c(161) cmd=162 (SMBntcreateX) 
NT_STATUS_OBJECT_NAME_NOT_FOUND
[2013/10/26 11:08:08.201449,  3] smbd/process.c:1662(process_smb)
  Transaction 32 of length 90 (0 toread)
[2013/10/26 11:08:08.201497,  3] smbd/process.c:1467(switch_message)
  switch message SMBntcreateX (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.201521,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.201560,  3] smbd/vfs.c:905(check_reduced_name)
  check_reduced_name [.] [/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content]
[2013/10/26 11:08:08.201597,  3] smbd/vfs.c:1039(check_reduced_name)
   check_reduced_name: . reduced to 
/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
[2013/10/26 11:08:08.201626,  3] smbd/dosmode.c:159(unix_mode)
  unix_mode(.) returning 02664
[2013/10/26 11:08:08.201669,  4] smbd/open.c:2069(open_file_ntcreate)
   calling open_file with flags=0x0 flags2=0x0 mode=02664, access_mask = 
0x81, open_access_mask = 0x81
[2013/10/26 11:08:08.202306,  3] smbd/process.c:1662(process_smb)
  Transaction 33 of length 76 (0 toread)
[2013/10/26 11:08:08.202345,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans2 (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.202367,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.202388,  3] 
smbd/trans2.c:5032(call_trans2qfilepathinfo)
  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1005
[2013/10/26 11:08:08.202416,  3] 
smbd/trans2.c:5261(call_trans2qfilepathinfo)
   call_trans2qfilepathinfo . (fnum = 25562) level=1005 call=7 
total_data=0
[2013/10/26 11:08:08.203304,  3] smbd/process.c:1662(process_smb)
  Transaction 34 of length 90 (0 toread)
[2013/10/26 11:08:08.203343,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans2 (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.203365,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.203388,  3] 
smbd/trans2.c:2292(call_trans2findfirst)
   call_trans2findfirst: dirtype = 16, maxentries = 1366, 
close_after_first=0, close_if_end = 1 requires_resume_key = 1 level = 
0x104, max_data_bytes = 16384
[2013/10/26 11:08:08.203418,  3] smbd/vfs.c:905(check_reduced_name)
  check_reduced_name [*] [/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content]
[2013/10/26 11:08:08.203450,  3] smbd/vfs.c:1039(check_reduced_name)
   check_reduced_name: * reduced to 
/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content/*
[2013/10/26 11:08:08.203474,  3] smbd/vfs.c:905(check_reduced_name)
  check_reduced_name [.] [/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content]
[2013/10/26 11:08:08.203497,  3] smbd/vfs.c:1039(check_reduced_name)
   check_reduced_name: . reduced to 
/RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
[2013/10/26 11:08:08.203524,  3] smbd/dir.c:578(dptr_create)
  creating new dirptr 256 for path ., expect_close = 1
[2013/10/26 11:08:08.203545,  4] 
smbd/trans2.c:2445(call_trans2findfirst)
  dptr_num is 256, wcard = *, attr = 22
[2013/10/26 11:08:08.203584,  3] smbd/dir.c:1041(smbd_dirptr_get_entry)
  smbd_dirptr_get_entry mask=[*] found ./. fname=. (.)
[2013/10/26 11:08:08.203623,  3] smbd/dir.c:1041(smbd_dirptr_get_entry)
  smbd_dirptr_get_entry mask=[*] found ./.. fname=.. (..)
[2013/10/26 11:08:08.203676,  3] smbd/dir.c:1041(smbd_dirptr_get_entry)
   smbd_dirptr_get_entry mask=[*] found ./helloworld_renamed.rtf 
fname=helloworld_renamed.rtf (helloworld_renamed.rtf)
[2013/10/26 11:08:08.203725,  3] smbd/dir.c:1041(smbd_dirptr_get_entry)
   smbd_dirptr_get_entry mask=[*] found ./andymade.txt 
fname=andymade.txt (andymade.txt)
[2013/10/26 11:08:08.203755,  4] smbd/dir.c:257(dptr_close_internal)
  closing dptr key 256
[2013/10/26 11:08:08.203820,  4] 
smbd/trans2.c:2555(call_trans2findfirst)
  SMBtrans2 mask=* directory=. dirtype=22 numentries=4
[2013/10/26 11:08:08.205971,  3] smbd/process.c:1662(process_smb)
  Transaction 35 of length 45 (0 toread)
[2013/10/26 11:08:08.206022,  3] smbd/process.c:1467(switch_message)
  switch message SMBclose (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:08.206046,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.206075,  3] smbd/reply.c:4836(reply_close)
  close directory fnum=25562
[2013/10/26 11:08:08.277431,  3] smbd/process.c:1662(process_smb)
  Transaction 36 of length 76 (0 toread)
[2013/10/26 11:08:08.277526,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans2 (pid 2305) conn 0x7fe5b7d7d880
[2013/10/26 11:08:08.277566,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (16777227, 16777222) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:08.277615,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /tmp
[2013/10/26 11:08:08.277647,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/trans2.c(4149) cmd=50 (SMBtrans2) 
NT_STATUS_INVALID_HANDLE
[2013/10/26 11:08:08.278352,  3] smbd/process.c:1662(process_smb)
  Transaction 37 of length 76 (0 toread)
[2013/10/26 11:08:08.278393,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans2 (pid 2305) conn 0x7fe5b7d7d880
[2013/10/26 11:08:08.278416,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.278439,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/trans2.c(4149) cmd=50 (SMBtrans2) 
NT_STATUS_INVALID_HANDLE
[2013/10/26 11:08:08.279284,  3] smbd/process.c:1662(process_smb)
  Transaction 38 of length 104 (0 toread)
[2013/10/26 11:08:08.279325,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2305) conn 0x7fe5b7d7d880
[2013/10/26 11:08:08.279348,  4] smbd/uid.c:351(change_to_user)
  Skipping user change - already user
[2013/10/26 11:08:08.279375,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\MsFteWds> data=0 params=0 setup=2
[2013/10/26 11:08:08.279399,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <MsFteWds> name
[2013/10/26 11:08:08.279420,  3] smbd/ipc.c:546(named_pipe)
  unknown named pipe: setup 0x53 setup1=0
[2013/10/26 11:08:08.279440,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/ipc.c(548) cmd=37 (SMBtrans) 
NT_STATUS_NOT_SUPPORTED
[2013/10/26 11:08:10.456788,  3] smbd/process.c:1662(process_smb)
  Transaction 39 of length 40 (0 toread)
[2013/10/26 11:08:10.456898,  3] smbd/process.c:1467(switch_message)
  switch message SMBntcancel (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:10.456939,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:10.457022,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/nttrans.c(83) cmd=160 (SMBnttrans) 
NT_STATUS_CANCELLED
[2013/10/26 11:08:10.457129,  3] smbd/nttrans.c:1336(reply_ntcancel)
  reply_ntcancel: cancel called on mid = 1922.
[2013/10/26 11:08:10.458081,  3] smbd/process.c:1662(process_smb)
  Transaction 40 of length 45 (0 toread)
[2013/10/26 11:08:10.458135,  3] smbd/process.c:1467(switch_message)
  switch message SMBclose (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:10.458184,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (16777227, 1007) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:10.458236,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
[2013/10/26 11:08:10.458292,  3] smbd/reply.c:4836(reply_close)
  close directory fnum=25559
[2013/10/26 11:08:18.357012,  3] smbd/process.c:1662(process_smb)
  Transaction 41 of length 39 (0 toread)
[2013/10/26 11:08:18.357278,  3] smbd/process.c:1467(switch_message)
  switch message SMBtdis (pid 2305) conn 0x7fe5b7d8e630
[2013/10/26 11:08:18.357375,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.357495,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /home/andyl/Projects
[2013/10/26 11:08:18.357677,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.357789,  1] smbd/service.c:1345(close_cnum)
   win-vnekof9be96 (172.16.3.129) closed connection to service andyl's 
Private Files
[2013/10/26 11:08:18.357898,  3] smbd/connection.c:35(yield_connection)
  Yielding connection to andyl's Private Files
[2013/10/26 11:08:18.358042,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /
[2013/10/26 11:08:18.358144,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.359091,  3] smbd/process.c:1662(process_smb)
  Transaction 42 of length 39 (0 toread)
[2013/10/26 11:08:18.359249,  3] smbd/process.c:1467(switch_message)
  switch message SMBtdis (pid 2305) conn 0x7fe5b7d87920
[2013/10/26 11:08:18.359342,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.359443,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /RAIDS/RAID_1/AvidStyle/AvidTest_1/Content
[2013/10/26 11:08:18.359547,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.359713,  1] smbd/service.c:1345(close_cnum)
  win-vnekof9be96 (172.16.3.129) closed connection to service AvidTest_1
[2013/10/26 11:08:18.359820,  3] smbd/connection.c:35(yield_connection)
  Yielding connection to AvidTest_1
[2013/10/26 11:08:18.359948,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /
[2013/10/26 11:08:18.360050,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.360908,  3] smbd/process.c:1662(process_smb)
  Transaction 43 of length 39 (0 toread)
[2013/10/26 11:08:18.361068,  3] smbd/process.c:1467(switch_message)
  switch message SMBtdis (pid 2305) conn 0x7fe5b7d91d20
[2013/10/26 11:08:18.361162,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.361263,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /home/editshare/FileExchange/SMB
[2013/10/26 11:08:18.361365,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.361459,  1] smbd/service.c:1345(close_cnum)
   win-vnekof9be96 (172.16.3.129) closed connection to service SMB File 
Exchange
[2013/10/26 11:08:18.361560,  3] smbd/connection.c:35(yield_connection)
  Yielding connection to SMB File Exchange
[2013/10/26 11:08:18.361755,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /
[2013/10/26 11:08:18.361860,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.362719,  3] smbd/process.c:1662(process_smb)
  Transaction 44 of length 39 (0 toread)
[2013/10/26 11:08:18.362879,  3] smbd/process.c:1467(switch_message)
  switch message SMBtdis (pid 2305) conn 0x7fe5b7d7d880
[2013/10/26 11:08:18.362972,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.363074,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /tmp
[2013/10/26 11:08:18.363170,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:18.363267,  3] smbd/service.c:1345(close_cnum)
  win-vnekof9be96 (172.16.3.129) closed connection to service IPC$
[2013/10/26 11:08:18.363367,  3] smbd/connection.c:35(yield_connection)
  Yielding connection to IPC$
[2013/10/26 11:08:18.363494,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /
[2013/10/26 11:08:18.363694,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:24.346740,  3] smbd/process.c:1662(process_smb)
  Transaction 45 of length 39 (0 toread)
[2013/10/26 11:08:24.346831,  3] smbd/process.c:1467(switch_message)
  switch message SMBtdis (pid 2305) conn 0x7fe5b7d8f670
[2013/10/26 11:08:24.346863,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:24.346903,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
[2013/10/26 11:08:24.346937,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:24.346960,  1] smbd/service.c:1345(close_cnum)
   win-vnekof9be96 (172.16.3.129) closed connection to service 
JustinDemo_1
[2013/10/26 11:08:24.346988,  3] smbd/connection.c:35(yield_connection)
  Yielding connection to JustinDemo_1
[2013/10/26 11:08:24.347031,  4] smbd/vfs.c:780(vfs_ChDir)
  vfs_ChDir to /
[2013/10/26 11:08:24.347056,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:24.347518,  3] smbd/process.c:1662(process_smb)
  Transaction 46 of length 43 (0 toread)
[2013/10/26 11:08:24.347552,  3] smbd/process.c:1467(switch_message)
  switch message SMBulogoffX (pid 2305) conn 0x0
[2013/10/26 11:08:24.347617,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:24.347726,  3] smbd/reply.c:2096(reply_ulogoffX)
  ulogoffX vuid=100
[2013/10/26 11:08:54.342137,  1] smbd/process.c:457(receive_smb_talloc)
   receive_smb_raw_talloc failed for client 172.16.3.129 read error = 
NT_STATUS_CONNECTION_RESET.
[2013/10/26 11:08:54.342418,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:54.342759,  3] 
smbd/server_exit.c:180(exit_server_common)
  Server exit (failed to receive smb request)
[2013/10/26 11:12:29.677877,  3] param/loadparm.c:9572(lp_load_ex)
  lp_load_ex: refreshing parameters
[2013/10/26 11:12:29.677942,  3] param/loadparm.c:5192(init_globals)
  Initialising global parameters
[2013/10/26 11:12:29.678039,  3] ../lib/util/params.c:550(pm_process)
   params.c:pm_process() - Processing configuration file 
"/etc/samba/smb.conf"
[2013/10/26 11:12:29.678070,  3] param/loadparm.c:8310(do_section)
  Processing section "[global]"
  doing parameter dns proxy = No
  doing parameter load printers = no
  doing parameter log file = /var/log/samba/log.%m
  doing parameter map to guest = Bad User
  doing parameter log level = 4
  doing parameter max log size = 50
  doing parameter netbios name = es-master
[2013/10/26 11:12:29.678583,  4] 
param/loadparm.c:7561(handle_netbios_name)
  handle_netbios_name: set global_myname to: ES-MASTER
  doing parameter os level = 65
  doing parameter printcap name = /etc/printcap
  doing parameter printing = bsd
  doing parameter server string = es-master
  doing parameter unix extensions = No
  doing parameter use sendfile = yes
  doing parameter wins support = Yes
  doing parameter workgroup = MYDOMAIN
  doing parameter realm = MYDOMAIN.ES1
  doing parameter security = ads
  doing parameter idmap uid = 16777216-33554431
[2013/10/26 11:12:29.679147,  1] param/loadparm.c:7992(lp_do_parameter)
  WARNING: The "idmap uid" option is deprecated
  doing parameter idmap gid = 16777216-33554431
[2013/10/26 11:12:29.679197,  1] param/loadparm.c:7992(lp_do_parameter)
  WARNING: The "idmap gid" option is deprecated
  doing parameter template shell = /sbin/nologin
  doing parameter winbind use default domain = true
  doing parameter winbind offline logon = true
  doing parameter winbind enum users = yes
  doing parameter winbind enum groups = yes
  doing parameter client ntlmv2 auth = yes
  doing parameter client use spnego principal = no
  doing parameter max open files = 32768
  doing parameter include = /etc/samba/smb_era.conf
[2013/10/26 11:12:29.679975,  3] ../lib/util/params.c:550(pm_process)
   params.c:pm_process() - Processing configuration file 
"/etc/samba/smb_era.conf"
[2013/10/26 11:12:29.680099,  3] param/loadparm.c:8310(do_section)
  Processing section "[global]"
  doing parameter netbios name = es-vmware
[2013/10/26 11:12:29.680305,  4] 
param/loadparm.c:7561(handle_netbios_name)
  handle_netbios_name: set global_myname to: ES-VMWARE
  doing parameter server string = es-vmware
  doing parameter os level = 65
  doing parameter include = /etc/samba/smb_custom.conf
[2013/10/26 11:12:29.680375,  2] param/loadparm.c:7690(handle_include)
  Can't find include file /etc/samba/smb_custom.conf
  doing parameter include = /etc/samba/smb.%U.conf
[2013/10/26 11:12:29.680426,  3] ../lib/util/params.c:550(pm_process)
   params.c:pm_process() - Processing configuration file 
"/etc/samba/smb.andyl.conf"
[2013/10/26 11:12:29.680452,  2] param/loadparm.c:8327(do_section)
  Processing section "[andyl's Private Files]"
   doing parameter comment = Private project metadata only.  No media 
here!
  doing parameter create mask = 0770
  doing parameter directory mask = 0770
  doing parameter force create mode = 2040
  doing parameter force directory mode = 2060
  doing parameter force group = editors
  doing parameter force user = andyl
  doing parameter guest ok = No
  doing parameter path = /home/andyl/Projects
  doing parameter read only = No
   doing parameter veto files = /*OMFI MediaFiles*/*Avid 
MediaFiles*/*Capture Scratch*/*Audio Capture 
Scratch*/*.omf/*.mxf/*.wav/*.avi/*.mpg/*.mov/*.aif*/*.mp3/*Render 
Files*/
[2013/10/26 11:12:29.680958,  2] param/loadparm.c:8327(do_section)
  Processing section "[AvidTest_1]"
  doing parameter comment = Shared Media Files
  doing parameter create mask = 2664
  doing parameter directory mask = 2775
  doing parameter force create mode = 2040
  doing parameter force directory mode = 2060
  doing parameter force group = avidtest
  doing parameter force user = andyl
  doing parameter guest ok = No
  doing parameter nt acl support = no
  doing parameter path = /RAIDS/RAID_1/AvidStyle/AvidTest_1/Content
  doing parameter read only = No
[2013/10/26 11:12:29.681168,  2] param/loadparm.c:8327(do_section)
  Processing section "[JustinDemo_1]"
  doing parameter comment = Shared Media Files
  doing parameter create mask = 2664
  doing parameter directory mask = 2775
  doing parameter force create mode = 2040
  doing parameter force directory mode = 2060
  doing parameter force group = justindemo
  doing parameter force user = andyl
  doing parameter guest ok = No
  doing parameter nt acl support = no
  doing parameter path = /RAIDS/RAID_1/AvidStyle/JustinDemo_1/Content
  doing parameter read only = No
[2013/10/26 11:12:29.681342,  2] param/loadparm.c:8327(do_section)
  Processing section "[SMB File Exchange]"
  doing parameter comment = Do Not Put Media Files Here
  doing parameter create mask = 0775
  doing parameter directory mask = 0775
  doing parameter guest ok = No
  doing parameter path = "/home/editshare/FileExchange/SMB"
  doing parameter read only = No
   doing parameter veto files = /*OMFI MediaFiles*/*Avid 
MediaFiles*/*Capture Scratch*/*Render Files*/
  doing parameter write list = @editors
  doing parameter force group = editors
[2013/10/26 11:12:29.681493,  2] param/loadparm.c:8327(do_section)
  Processing section "[es_objects$]"
  doing parameter force group = editshare
  doing parameter force user = editshare
  doing parameter path = "/tmp/.editshare/objects"
  doing parameter read only = Yes
  doing parameter write ok = No
[2013/10/26 11:12:29.681589,  4] param/loadparm.c:9608(lp_load_ex)
  pm_process() returned Yes
[2013/10/26 11:12:29.681639,  3] param/loadparm.c:6630(lp_add_ipc)
  adding IPC service
[2013/10/26 11:12:29.681829,  2] lib/interface.c:341(add_interface)
   added interface eth0 ip=fe80::20c:29ff:fec7:bfdd%eth0 
bcast=fe80::ffff:ffff:ffff:ffff%eth0 netmask=ffff:ffff:ffff:ffff::
[2013/10/26 11:12:29.681881,  2] lib/interface.c:341(add_interface)
   added interface eth0 ip=172.16.3.10 bcast=172.16.3.255 
netmask=255.255.255.0
[2013/10/26 11:12:29.682196,  3] auth/auth.c:219(check_ntlm_password)
   check_ntlm_password:  Checking password for unmapped user 
[MYDOMAIN]\[andyl]@[WIN-VNEKOF9BE96] with the new password interface
[2013/10/26 11:12:29.682229,  3] auth/auth.c:222(check_ntlm_password)
   check_ntlm_password:  mapped user is: 
[MYDOMAIN]\[andyl]@[WIN-VNEKOF9BE96]
[2013/10/26 11:12:29.682258,  4] smbd/sec_ctx.c:214(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2013/10/26 11:12:29.682285,  4] smbd/uid.c:460(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2013/10/26 11:12:29.682312,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2013/10/26 11:12:29.682468,  4] smbd/sec_ctx.c:422(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.682498,  2] auth/auth.c:319(check_ntlm_password)
   check_ntlm_password:  Authentication for user [andyl] -> [andyl] 
FAILED with error NT_STATUS_NO_LOGON_SERVERS
[2013/10/26 11:12:29.682530,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/sesssetup.c(124) cmd=115 (SMBsesssetupX) 
NT_STATUS_NO_LOGON_SERVERS
[2013/10/26 11:12:29.698572,  3] smbd/process.c:1662(process_smb)
  Transaction 3 of length 142 (0 toread)
[2013/10/26 11:12:29.698689,  3] smbd/process.c:1467(switch_message)
  switch message SMBsesssetupX (pid 2341) conn 0x0
[2013/10/26 11:12:29.698740,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.698822,  3] 
smbd/sesssetup.c:1333(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2013/10/26 11:12:29.698871,  2] 
smbd/sesssetup.c:1279(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would close 
all old resources.
[2013/10/26 11:12:29.698948,  3] 
smbd/sesssetup.c:1065(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/10/26 11:12:29.699007,  3] 
smbd/sesssetup.c:1107(reply_sesssetup_and_X_spnego)
  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2013/10/26 11:12:29.699070,  3] 
smbd/sesssetup.c:660(reply_spnego_negotiate)
  reply_spnego_negotiate: Got secblob of size 40
[2013/10/26 11:12:29.699138,  3] 
../libcli/auth/ntlmssp.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0xe2088297
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_NEGOTIATE_OEM
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_SIGN
    NTLMSSP_NEGOTIATE_LM_KEY
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_NEGOTIATE_VERSION
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_KEY_EXCH
    NTLMSSP_NEGOTIATE_56
[2013/10/26 11:12:29.700729,  3] smbd/process.c:1662(process_smb)
  Transaction 4 of length 496 (0 toread)
[2013/10/26 11:12:29.700792,  3] smbd/process.c:1467(switch_message)
  switch message SMBsesssetupX (pid 2341) conn 0x0
[2013/10/26 11:12:29.700843,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.700899,  3] 
smbd/sesssetup.c:1333(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2013/10/26 11:12:29.700979,  2] 
smbd/sesssetup.c:1279(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would close 
all old resources.
[2013/10/26 11:12:29.701031,  3] 
smbd/sesssetup.c:1065(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/10/26 11:12:29.701079,  3] 
smbd/sesssetup.c:1107(reply_sesssetup_and_X_spnego)
  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2013/10/26 11:12:29.701145,  3] 
../libcli/auth/ntlmssp_server.c:348(ntlmssp_server_preauth)
   Got user=[andyl] domain=[MYDOMAIN] workstation=[WIN-VNEKOF9BE96] 
len1=24 len2=228
[2013/10/26 11:12:29.701344,  3] auth/auth.c:219(check_ntlm_password)
   check_ntlm_password:  Checking password for unmapped user 
[MYDOMAIN]\[andyl]@[WIN-VNEKOF9BE96] with the new password interface
[2013/10/26 11:12:29.701394,  3] auth/auth.c:222(check_ntlm_password)
   check_ntlm_password:  mapped user is: 
[MYDOMAIN]\[andyl]@[WIN-VNEKOF9BE96]
[2013/10/26 11:12:29.701444,  4] smbd/sec_ctx.c:214(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2013/10/26 11:12:29.701485,  4] smbd/uid.c:460(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2013/10/26 11:12:29.701525,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2013/10/26 11:12:29.701721,  4] smbd/sec_ctx.c:422(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.701767,  2] auth/auth.c:319(check_ntlm_password)
   check_ntlm_password:  Authentication for user [andyl] -> [andyl] 
FAILED with error NT_STATUS_NO_LOGON_SERVERS
[2013/10/26 11:12:29.701815,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/sesssetup.c(124) cmd=115 (SMBsesssetupX) 
NT_STATUS_NO_LOGON_SERVERS
[2013/10/26 11:12:29.709336,  3] smbd/process.c:1662(process_smb)
  Transaction 5 of length 142 (0 toread)
[2013/10/26 11:12:29.709445,  3] smbd/process.c:1467(switch_message)
  switch message SMBsesssetupX (pid 2341) conn 0x0
[2013/10/26 11:12:29.709498,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.709559,  3] 
smbd/sesssetup.c:1333(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2013/10/26 11:12:29.709621,  2] 
smbd/sesssetup.c:1279(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would close 
all old resources.
[2013/10/26 11:12:29.709671,  3] 
smbd/sesssetup.c:1065(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/10/26 11:12:29.709722,  3] 
smbd/sesssetup.c:1107(reply_sesssetup_and_X_spnego)
  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2013/10/26 11:12:29.709791,  3] 
smbd/sesssetup.c:660(reply_spnego_negotiate)
  reply_spnego_negotiate: Got secblob of size 40
[2013/10/26 11:12:29.709865,  3] 
../libcli/auth/ntlmssp.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0xe2088297
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_NEGOTIATE_OEM
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_SIGN
    NTLMSSP_NEGOTIATE_LM_KEY
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_NEGOTIATE_VERSION
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_KEY_EXCH
    NTLMSSP_NEGOTIATE_56
[2013/10/26 11:12:29.711324,  3] smbd/process.c:1662(process_smb)
  Transaction 6 of length 496 (0 toread)
[2013/10/26 11:12:29.711383,  3] smbd/process.c:1467(switch_message)
  switch message SMBsesssetupX (pid 2341) conn 0x0
[2013/10/26 11:12:29.711434,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.711487,  3] 
smbd/sesssetup.c:1333(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2013/10/26 11:12:29.711535,  2] 
smbd/sesssetup.c:1279(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would close 
all old resources.
[2013/10/26 11:12:29.711583,  3] 
smbd/sesssetup.c:1065(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/10/26 11:12:29.711631,  3] 
smbd/sesssetup.c:1107(reply_sesssetup_and_X_spnego)
  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2013/10/26 11:12:29.711690,  3] 
../libcli/auth/ntlmssp_server.c:348(ntlmssp_server_preauth)
   Got user=[andyl] domain=[MYDOMAIN] workstation=[WIN-VNEKOF9BE96] 
len1=24 len2=228
[2013/10/26 11:12:29.711901,  3] auth/auth.c:219(check_ntlm_password)
   check_ntlm_password:  Checking password for unmapped user 
[MYDOMAIN]\[andyl]@[WIN-VNEKOF9BE96] with the new password interface
[2013/10/26 11:12:29.711988,  3] auth/auth.c:222(check_ntlm_password)
   check_ntlm_password:  mapped user is: 
[MYDOMAIN]\[andyl]@[WIN-VNEKOF9BE96]
[2013/10/26 11:12:29.712045,  4] smbd/sec_ctx.c:214(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2013/10/26 11:12:29.712096,  4] smbd/uid.c:460(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2013/10/26 11:12:29.712144,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2013/10/26 11:12:29.712360,  4] smbd/sec_ctx.c:422(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.712417,  2] auth/auth.c:319(check_ntlm_password)
   check_ntlm_password:  Authentication for user [andyl] -> [andyl] 
FAILED with error NT_STATUS_NO_LOGON_SERVERS
[2013/10/26 11:12:29.712475,  3] smbd/error.c:81(error_packet_set)
   error packet at smbd/sesssetup.c(124) cmd=115 (SMBsesssetupX) 
NT_STATUS_NO_LOGON_SERVERS
[2013/10/26 11:12:42.348928,  1] smbd/process.c:457(receive_smb_talloc)
   receive_smb_raw_talloc failed for client 172.16.3.129 read error = 
NT_STATUS_CONNECTION_RESET.
[2013/10/26 11:12:42.349289,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:42.349572,  3] 
smbd/server_exit.c:180(exit_server_common)
  Server exit (failed to receive smb request)



log.172.16.3.129

[2013/10/26 11:08:05.895054,  3] lib/access.c:338(allow_access)
  Allowed connection from 172.16.3.129 (172.16.3.129)
[2013/10/26 11:08:05.895524,  3] smbd/oplock.c:922(init_oplocks)
  init_oplocks: initializing messages.
[2013/10/26 11:08:05.895865,  3] 
smbd/oplock_linux.c:226(linux_init_kernel_oplocks)
  Linux kernel oplocks enabled
[2013/10/26 11:08:05.896130,  3] smbd/process.c:1662(process_smb)
  Transaction 0 of length 159 (0 toread)
[2013/10/26 11:08:05.896339,  3] smbd/process.c:1467(switch_message)
  switch message SMBnegprot (pid 2305) conn 0x0
[2013/10/26 11:08:05.896393,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:05.896636,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [PC NETWORK PROGRAM 1.0]
[2013/10/26 11:08:05.896666,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [LANMAN1.0]
[2013/10/26 11:08:05.896689,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [Windows for Workgroups 3.1a]
[2013/10/26 11:08:05.896713,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [LM1.2X002]
[2013/10/26 11:08:05.896735,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [LANMAN2.1]
[2013/10/26 11:08:05.896757,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [NT LM 0.12]
[2013/10/26 11:08:05.896790,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [SMB 2.002]
[2013/10/26 11:08:05.896826,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [SMB 2.???]
[2013/10/26 11:08:05.896910,  3] smbd/negprot.c:419(reply_nt1)
  using SPNEGO
[2013/10/26 11:08:05.896935,  3] smbd/negprot.c:704(reply_negprot)
  Selected protocol NT LM 0.12
[2013/10/26 11:08:05.897600,  3] smbd/process.c:1662(process_smb)
  Transaction 1 of length 142 (0 toread)
[2013/10/26 11:08:05.897723,  3] smbd/process.c:1467(switch_message)
  switch message SMBsesssetupX (pid 2305) conn 0x0
[2013/10/26 11:08:05.897757,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:05.897787,  3] 
smbd/sesssetup.c:1333(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2013/10/26 11:08:05.897812,  2] 
smbd/sesssetup.c:1279(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would close 
all old resources.
[2013/10/26 11:08:05.897835,  3] 
smbd/sesssetup.c:1065(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/10/26 11:08:05.897859,  3] 
smbd/sesssetup.c:1107(reply_sesssetup_and_X_spnego)
  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2013/10/26 11:08:05.897896,  3] 
smbd/sesssetup.c:660(reply_spnego_negotiate)
  reply_spnego_negotiate: Got secblob of size 40
[2013/10/26 11:08:05.898074,  3] 
../libcli/auth/ntlmssp.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0xe2088297
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_NEGOTIATE_OEM
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_SIGN
    NTLMSSP_NEGOTIATE_LM_KEY
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_NEGOTIATE_VERSION
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_KEY_EXCH
    NTLMSSP_NEGOTIATE_56
[2013/10/26 11:08:05.898598,  3] smbd/process.c:1662(process_smb)
  Transaction 2 of length 496 (0 toread)
[2013/10/26 11:08:05.898642,  3] smbd/process.c:1467(switch_message)
  switch message SMBsesssetupX (pid 2305) conn 0x0
[2013/10/26 11:08:05.898667,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:08:05.898694,  3] 
smbd/sesssetup.c:1333(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2013/10/26 11:08:05.898718,  2] 
smbd/sesssetup.c:1279(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would close 
all old resources.
[2013/10/26 11:08:05.898741,  3] 
smbd/sesssetup.c:1065(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/10/26 11:08:05.898765,  3] 
smbd/sesssetup.c:1107(reply_sesssetup_and_X_spnego)
  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2013/10/26 11:08:05.898800,  3] 
../libcli/auth/ntlmssp_server.c:348(ntlmssp_server_preauth)
   Got user=[andyl] domain=[MYDOMAIN] workstation=[WIN-VNEKOF9BE96] 
len1=24 len2=228
[2013/10/26 11:12:29.669487,  3] lib/access.c:338(allow_access)
  Allowed connection from 172.16.3.129 (172.16.3.129)
[2013/10/26 11:12:29.670863,  3] smbd/oplock.c:922(init_oplocks)
  init_oplocks: initializing messages.
[2013/10/26 11:12:29.671658,  3] 
smbd/oplock_linux.c:226(linux_init_kernel_oplocks)
  Linux kernel oplocks enabled
[2013/10/26 11:12:29.672146,  3] smbd/process.c:1662(process_smb)
  Transaction 0 of length 159 (0 toread)
[2013/10/26 11:12:29.672279,  3] smbd/process.c:1467(switch_message)
  switch message SMBnegprot (pid 2341) conn 0x0
[2013/10/26 11:12:29.672396,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.673297,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [PC NETWORK PROGRAM 1.0]
[2013/10/26 11:12:29.673437,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [LANMAN1.0]
[2013/10/26 11:12:29.673543,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [Windows for Workgroups 3.1a]
[2013/10/26 11:12:29.673646,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [LM1.2X002]
[2013/10/26 11:12:29.673746,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [LANMAN2.1]
[2013/10/26 11:12:29.673847,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [NT LM 0.12]
[2013/10/26 11:12:29.674029,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [SMB 2.002]
[2013/10/26 11:12:29.674056,  3] smbd/negprot.c:598(reply_negprot)
  Requested protocol [SMB 2.???]
[2013/10/26 11:12:29.674147,  3] smbd/negprot.c:419(reply_nt1)
  using SPNEGO
[2013/10/26 11:12:29.674174,  3] smbd/negprot.c:704(reply_negprot)
  Selected protocol NT LM 0.12
[2013/10/26 11:12:29.675751,  3] smbd/process.c:1662(process_smb)
  Transaction 1 of length 142 (0 toread)
[2013/10/26 11:12:29.675904,  3] smbd/process.c:1467(switch_message)
  switch message SMBsesssetupX (pid 2341) conn 0x0
[2013/10/26 11:12:29.675995,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.676030,  3] 
smbd/sesssetup.c:1333(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2013/10/26 11:12:29.676059,  2] 
smbd/sesssetup.c:1279(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would close 
all old resources.
[2013/10/26 11:12:29.676082,  3] 
smbd/sesssetup.c:1065(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/10/26 11:12:29.676173,  3] 
smbd/sesssetup.c:1107(reply_sesssetup_and_X_spnego)
  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2013/10/26 11:12:29.676230,  3] 
smbd/sesssetup.c:660(reply_spnego_negotiate)
  reply_spnego_negotiate: Got secblob of size 40
[2013/10/26 11:12:29.676585,  3] 
../libcli/auth/ntlmssp.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0xe2088297
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_NEGOTIATE_OEM
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_SIGN
    NTLMSSP_NEGOTIATE_LM_KEY
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_NEGOTIATE_VERSION
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_KEY_EXCH
    NTLMSSP_NEGOTIATE_56
[2013/10/26 11:12:29.677310,  3] smbd/process.c:1662(process_smb)
  Transaction 2 of length 496 (0 toread)
[2013/10/26 11:12:29.677352,  3] smbd/process.c:1467(switch_message)
  switch message SMBsesssetupX (pid 2341) conn 0x0
[2013/10/26 11:12:29.677416,  4] smbd/sec_ctx.c:314(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2013/10/26 11:12:29.677532,  3] 
smbd/sesssetup.c:1333(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2013/10/26 11:12:29.677632,  2] 
smbd/sesssetup.c:1279(setup_new_vc_session)
   setup_new_vc_session: New VC == 0, if NT4.x compatible we would close 
all old resources.
[2013/10/26 11:12:29.677725,  3] 
smbd/sesssetup.c:1065(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/10/26 11:12:29.677750,  3] 
smbd/sesssetup.c:1107(reply_sesssetup_and_X_spnego)
  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2013/10/26 11:12:29.677786,  3] 
../libcli/auth/ntlmssp_server.c:348(ntlmssp_server_preauth)
   Got user=[andyl] domain=[MYDOMAIN] workstation=[WIN-VNEKOF9BE96] 
len1=24 len2=228


More information about the samba mailing list