Beta1 S3FS "The parameter is incorrect" on Win7
brendan powers
brendan0powers at gmail.com
Tue Jun 5 15:14:38 MDT 2012
I was able to get smbd started with more debugging info. The command I
used was "smbd -D --configfile
/usr/local/var/lib/samba/private/smbd.tmp/fileserver.conf -d6". I
Initially tried with --foreground, but it would end the process after
a connection failed.
Here is the output when trying to connect from a windows 7 computer
that is not joined to the domain. The windows 7 computer receives the
error "The paramter is incorrect"
[2012/06/05 17:05:22.862073, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:05:22.864062, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:05:23.131296, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:05:23.153535, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:05:23.157871, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:05:23.161065, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:05:23.163268, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:05:23.166431, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:05:23.168276, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:05:23.168547, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:05:23.168699, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:05:23.169007, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:05:23.169302, 3] ../source3/lib/access.c:338(allow_access)
Allowed connection from 10.0.1.81 (10.0.1.81)
[2012/06/05 17:05:23.169783, 3]
../source3/smbd/service.c:631(make_connection_snum)
Connect path is
'/usr/local/var/lib/samba/sysvol/testdom.lan/scripts' for service
[netlogon]
[2012/06/05 17:05:23.170265, 3] ../source3/smbd/vfs.c:102(vfs_init_default)
Initialising default vfs hooks
[2012/06/05 17:05:23.170452, 3] ../source3/smbd/vfs.c:128(vfs_init_custom)
Initialising custom vfs hooks from [/[Default VFS]/]
Successfully loaded vfs module [/[Default VFS]/] with the new modules system
[2012/06/05 17:05:23.170721, 3] ../source3/smbd/vfs.c:128(vfs_init_custom)
Initialising custom vfs hooks from [acl_xattr]
[2012/06/05 17:05:23.170919, 5] ../source3/smbd/vfs.c:168(vfs_init_custom)
vfs module [acl_xattr] not loaded - trying to load...
[2012/06/05 17:05:23.172003, 5] ../lib/util/modules.c:174(do_smb_load_module)
Loading module 'acl_xattr'
[2012/06/05 17:05:23.172172, 5] ../lib/util/modules.c:188(do_smb_load_module)
Loading module 'acl_xattr': Trying to load from
/usr/local/lib/samba/vfs/acl_xattr.so
[2012/06/05 17:05:23.181741, 2] ../lib/util/modules.c:198(do_smb_load_module)
Module 'acl_xattr' loaded
[2012/06/05 17:05:23.182123, 5] ../source3/smbd/vfs.c:92(smb_register_vfs)
Successfully added vfs backend 'acl_xattr'
Successfully loaded vfs module [acl_xattr] with the new modules system
[2012/06/05 17:05:23.182592, 5]
../source3/smbd/connection.c:158(claim_connection)
claiming [netlogon]
[2012/06/05 17:05:23.182664, 5]
../lib/dbwrap/dbwrap.c:156(dbwrap_check_lock_order)
used=0, lock_order=1, idx=7
[2012/06/05 17:05:23.182809, 2]
../source3/modules/vfs_acl_xattr.c:193(connect_acl_xattr)
connect_acl_xattr: setting 'inherit acls = true' 'dos filemode =
true' and 'force unknown acl user = true' for service netlogon
[2012/06/05 17:05:23.183585, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 784 - private_data=0x9cc0b30
[2012/06/05 17:05:23.184011, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 100) - sec_ctx_stack_ndx = 0
[2012/06/05 17:05:23.184084, 5]
../libcli/security/security_token.c:63(security_token_debug)
Security token SIDs (12):
SID[ 0]: S-1-5-21-97711526-666453636-1641050529-500
SID[ 1]: S-1-5-21-97711526-666453636-1641050529-513
SID[ 2]: S-1-5-21-97711526-666453636-1641050529-520
SID[ 3]: S-1-5-21-97711526-666453636-1641050529-572
SID[ 4]: S-1-5-21-97711526-666453636-1641050529-519
SID[ 5]: S-1-5-21-97711526-666453636-1641050529-518
SID[ 6]: S-1-5-21-97711526-666453636-1641050529-512
SID[ 7]: S-1-5-32-544
SID[ 8]: S-1-5-32-545
SID[ 9]: S-1-1-0
SID[ 10]: S-1-5-2
SID[ 11]: S-1-5-11
Privileges (0x 1FFFFF00):
Privilege[ 0]: SeTakeOwnershipPrivilege
Privilege[ 1]: SeBackupPrivilege
Privilege[ 2]: SeRestorePrivilege
Privilege[ 3]: SeRemoteShutdownPrivilege
Privilege[ 4]: SeSecurityPrivilege
Privilege[ 5]: SeSystemtimePrivilege
Privilege[ 6]: SeShutdownPrivilege
Privilege[ 7]: SeDebugPrivilege
Privilege[ 8]: SeSystemEnvironmentPrivilege
Privilege[ 9]: SeSystemProfilePrivilege
Privilege[ 10]: SeProfileSingleProcessPrivilege
Privilege[ 11]: SeIncreaseBasePriorityPrivilege
Privilege[ 12]: SeLoadDriverPrivilege
Privilege[ 13]: SeCreatePagefilePrivilege
Privilege[ 14]: SeIncreaseQuotaPrivilege
Privilege[ 15]: SeChangeNotifyPrivilege
Privilege[ 16]: SeUndockPrivilege
Privilege[ 17]: SeManageVolumePrivilege
Privilege[ 18]: SeImpersonatePrivilege
Privilege[ 19]: SeCreateGlobalPrivilege
Privilege[ 20]: SeEnableDelegationPrivilege
Rights (0x 403):
Right[ 0]: SeInteractiveLogonRight
Right[ 1]: SeNetworkLogonRight
Right[ 2]: SeRemoteInteractiveLogonRight
[2012/06/05 17:05:23.184723, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 100 and contains 11 supplementary groups
Group[ 0]: 100
Group[ 1]: 3000008
Group[ 2]: 3000009
Group[ 3]: 3000010
Group[ 4]: 3000011
Group[ 5]: 3000012
Group[ 6]: 4
Group[ 7]: 3000000
Group[ 8]: 3000001
Group[ 9]: 3000002
Group[ 10]: 3000003
[2012/06/05 17:05:23.184994, 5]
../source3/smbd/uid.c:273(change_to_user_internal)
Impersonated user: uid=(0,0), gid=(0,100)
[2012/06/05 17:05:23.185048, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:05:23.185093, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:05:23.185135, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:05:23.185202, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:05:23.185477, 1]
../source3/smbd/service.c:880(make_connection_snum)
10.0.1.81 (ipv4:10.0.1.81:49209) connect to service netlogon
initially as user TESTDOM\Administrator (uid=0, gid=100) (pid 26123)
[2012/06/05 17:05:23.186803, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 100) - sec_ctx_stack_ndx = 0
[2012/06/05 17:05:23.186856, 5]
../libcli/security/security_token.c:63(security_token_debug)
Security token SIDs (12):
SID[ 0]: S-1-5-21-97711526-666453636-1641050529-500
SID[ 1]: S-1-5-21-97711526-666453636-1641050529-513
SID[ 2]: S-1-5-21-97711526-666453636-1641050529-520
SID[ 3]: S-1-5-21-97711526-666453636-1641050529-572
SID[ 4]: S-1-5-21-97711526-666453636-1641050529-519
SID[ 5]: S-1-5-21-97711526-666453636-1641050529-518
SID[ 6]: S-1-5-21-97711526-666453636-1641050529-512
SID[ 7]: S-1-5-32-544
SID[ 8]: S-1-5-32-545
SID[ 9]: S-1-1-0
SID[ 10]: S-1-5-2
SID[ 11]: S-1-5-11
Privileges (0x 1FFFFF00):
Privilege[ 0]: SeTakeOwnershipPrivilege
Privilege[ 1]: SeBackupPrivilege
Privilege[ 2]: SeRestorePrivilege
Privilege[ 3]: SeRemoteShutdownPrivilege
Privilege[ 4]: SeSecurityPrivilege
Privilege[ 5]: SeSystemtimePrivilege
Privilege[ 6]: SeShutdownPrivilege
Privilege[ 7]: SeDebugPrivilege
Privilege[ 8]: SeSystemEnvironmentPrivilege
Privilege[ 9]: SeSystemProfilePrivilege
Privilege[ 10]: SeProfileSingleProcessPrivilege
Privilege[ 11]: SeIncreaseBasePriorityPrivilege
Privilege[ 12]: SeLoadDriverPrivilege
Privilege[ 13]: SeCreatePagefilePrivilege
Privilege[ 14]: SeIncreaseQuotaPrivilege
Privilege[ 15]: SeChangeNotifyPrivilege
Privilege[ 16]: SeUndockPrivilege
Privilege[ 17]: SeManageVolumePrivilege
Privilege[ 18]: SeImpersonatePrivilege
Privilege[ 19]: SeCreateGlobalPrivilege
Privilege[ 20]: SeEnableDelegationPrivilege
Rights (0x 403):
Right[ 0]: SeInteractiveLogonRight
Right[ 1]: SeNetworkLogonRight
Right[ 2]: SeRemoteInteractiveLogonRight
[2012/06/05 17:05:23.187410, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 100 and contains 11 supplementary groups
Group[ 0]: 100
Group[ 1]: 3000008
Group[ 2]: 3000009
Group[ 3]: 3000010
Group[ 4]: 3000011
Group[ 5]: 3000012
Group[ 6]: 4
Group[ 7]: 3000000
Group[ 8]: 3000001
Group[ 9]: 3000002
Group[ 10]: 3000003
[2012/06/05 17:05:23.187618, 5]
../source3/smbd/uid.c:273(change_to_user_internal)
Impersonated user: uid=(0,0), gid=(0,100)
[2012/06/05 17:05:23.188608, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
This doesn't seem incredibly helpful. I can provide a packet trace if needed.
I also tried connecting to a printer, and got his output. The lines
that stood out to me were
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:25.984562, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
It seems it's trying to find a spoolss rpc module, but the module
doesn't exist. Like I said before, I'm not sure if this is supposed to
work. As far as I know, the module doesn't exist because no one has
written it yet...
The full output is below.
[2012/06/05 17:10:17.826308, 2] ../source3/smbd/server.c:433(remove_child_pid)
Could not find child 26131 -- ignoring
[2012/06/05 17:10:25.979007, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 100) - sec_ctx_stack_ndx = 0
[2012/06/05 17:10:25.979138, 5]
../libcli/security/security_token.c:63(security_token_debug)
Security token SIDs (12):
SID[ 0]: S-1-5-21-97711526-666453636-1641050529-500
SID[ 1]: S-1-5-21-97711526-666453636-1641050529-513
SID[ 2]: S-1-5-21-97711526-666453636-1641050529-520
SID[ 3]: S-1-5-21-97711526-666453636-1641050529-572
SID[ 4]: S-1-5-21-97711526-666453636-1641050529-519
SID[ 5]: S-1-5-21-97711526-666453636-1641050529-518
SID[ 6]: S-1-5-21-97711526-666453636-1641050529-512
SID[ 7]: S-1-5-32-544
SID[ 8]: S-1-5-32-545
SID[ 9]: S-1-1-0
SID[ 10]: S-1-5-2
SID[ 11]: S-1-5-11
Privileges (0x 1FFFFF00):
Privilege[ 0]: SeTakeOwnershipPrivilege
Privilege[ 1]: SeBackupPrivilege
Privilege[ 2]: SeRestorePrivilege
Privilege[ 3]: SeRemoteShutdownPrivilege
Privilege[ 4]: SeSecurityPrivilege
Privilege[ 5]: SeSystemtimePrivilege
Privilege[ 6]: SeShutdownPrivilege
Privilege[ 7]: SeDebugPrivilege
Privilege[ 8]: SeSystemEnvironmentPrivilege
Privilege[ 9]: SeSystemProfilePrivilege
Privilege[ 10]: SeProfileSingleProcessPrivilege
Privilege[ 11]: SeIncreaseBasePriorityPrivilege
Privilege[ 12]: SeLoadDriverPrivilege
Privilege[ 13]: SeCreatePagefilePrivilege
Privilege[ 14]: SeIncreaseQuotaPrivilege
Privilege[ 15]: SeChangeNotifyPrivilege
Privilege[ 16]: SeUndockPrivilege
Privilege[ 17]: SeManageVolumePrivilege
Privilege[ 18]: SeImpersonatePrivilege
Privilege[ 19]: SeCreateGlobalPrivilege
Privilege[ 20]: SeEnableDelegationPrivilege
Rights (0x 403):
Right[ 0]: SeInteractiveLogonRight
Right[ 1]: SeNetworkLogonRight
Right[ 2]: SeRemoteInteractiveLogonRight
[2012/06/05 17:10:25.981847, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 100 and contains 11 supplementary groups
Group[ 0]: 100
Group[ 1]: 3000008
Group[ 2]: 3000009
Group[ 3]: 3000010
Group[ 4]: 3000011
Group[ 5]: 3000012
Group[ 6]: 4
Group[ 7]: 3000000
Group[ 8]: 3000001
Group[ 9]: 3000002
Group[ 10]: 3000003
[2012/06/05 17:10:25.983636, 5]
../source3/smbd/uid.c:273(change_to_user_internal)
Impersonated user: uid=(0,0), gid=(0,100)
[2012/06/05 17:10:25.983864, 5] ../source3/smbd/files.c:138(file_new)
allocated file structure 4764, fnum = 8860 (1 used)
[2012/06/05 17:10:25.984054, 5] ../lib/util/modules.c:174(do_smb_load_module)
Probing module 'spoolss'
[2012/06/05 17:10:25.984192, 5] ../lib/util/modules.c:188(do_smb_load_module)
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:25.984562, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
[2012/06/05 17:10:25.984745, 5] ../source3/smbd/files.c:492(file_free)
freed files structure 8860 (0 used)
[2012/06/05 17:10:25.986536, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:10:25.987084, 5] ../source3/smbd/files.c:138(file_new)
allocated file structure 4765, fnum = 8861 (1 used)
[2012/06/05 17:10:25.987345, 5] ../lib/util/modules.c:174(do_smb_load_module)
Probing module 'spoolss'
[2012/06/05 17:10:25.987513, 5] ../lib/util/modules.c:188(do_smb_load_module)
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:25.987894, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
[2012/06/05 17:10:25.988123, 5] ../source3/smbd/files.c:492(file_free)
freed files structure 8861 (0 used)
[2012/06/05 17:10:25.995144, 5]
../lib/dbwrap/dbwrap.c:156(dbwrap_check_lock_order)
used=0, lock_order=2, idx=6
[2012/06/05 17:10:25.995473, 5]
../lib/util/util_net.c:848(print_socket_options)
Socket options:
SO_KEEPALIVE = 1
SO_REUSEADDR = 1
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
[2012/06/05 17:10:25.995818, 5]
../lib/util/util_net.c:848(print_socket_options)
Socket options:
SO_KEEPALIVE = 1
SO_REUSEADDR = 1
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
[2012/06/05 17:10:25.996339, 6]
../source3/param/loadparm.c:6618(lp_file_list_changed)
lp_file_list_changed()
file /usr/local/etc/samba/smb.conf -> /usr/local/etc/samba/smb.conf
last mod_time: Tue Jun 5 15:34:28 2012
file /usr/local/var/lib/samba/private/smbd.tmp/fileserver.conf ->
/usr/local/var/lib/samba/private/smbd.tmp/fileserver.conf last
mod_time: Tue Jun 5 15:34:36 2012
[2012/06/05 17:10:25.996641, 3] ../source3/lib/access.c:338(allow_access)
Allowed connection from 10.0.1.81 (10.0.1.81)
[2012/06/05 17:10:25.996872, 3] ../source3/smbd/oplock.c:984(init_oplocks)
init_oplocks: initializing messages.
[2012/06/05 17:10:25.996920, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 774 - private_data=0xa31afa8
[2012/06/05 17:10:25.996961, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 776 - private_data=0xa31afa8
[2012/06/05 17:10:25.996994, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 775 - private_data=0xa31afa8
[2012/06/05 17:10:25.997026, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 778 - private_data=0xa31afa8
[2012/06/05 17:10:25.997059, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 777 - private_data=0xa31afa8
[2012/06/05 17:10:25.997092, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 770 - private_data=0xa31afa8
[2012/06/05 17:10:25.997126, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 787 - private_data=0xa31afa8
[2012/06/05 17:10:25.997159, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 779 - private_data=0xa31afa8
[2012/06/05 17:10:25.997198, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 15 - private_data=(nil)
[2012/06/05 17:10:25.997248, 5]
../source3/lib/messages.c:308(messaging_register)
Overriding messaging pointer for type 15 - private_data=(nil)
[2012/06/05 17:10:25.997291, 5]
../source3/lib/messages.c:340(messaging_deregister)
Deregistering messaging pointer for type 16 - private_data=(nil)
[2012/06/05 17:10:25.997328, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 16 - private_data=0xa31afa8
[2012/06/05 17:10:25.997361, 5]
../source3/lib/messages.c:340(messaging_deregister)
Deregistering messaging pointer for type 33 - private_data=0x99dbba0
[2012/06/05 17:10:25.997404, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 33 - private_data=0xa31afa8
[2012/06/05 17:10:25.997439, 5]
../source3/lib/messages.c:340(messaging_deregister)
Deregistering messaging pointer for type 1 - private_data=(nil)
[2012/06/05 17:10:25.997472, 5]
../source3/lib/messages.c:293(messaging_register)
Registering messaging pointer for type 1 - private_data=(nil)
[2012/06/05 17:10:25.997799, 6] ../source3/smbd/process.c:1736(process_smb)
got message type 0x0 of len 0x85
[2012/06/05 17:10:25.997871, 3] ../source3/smbd/process.c:1738(process_smb)
Transaction 0 of length 137 (0 toread)
[2012/06/05 17:10:25.997926, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:25.997947, 5] ../source3/lib/util.c:148(show_msg)
size=133
smb_com=0x72
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51267
smb_tid=0
smb_pid=65279
smb_uid=0
smb_mid=0
smt_wct=0
smb_bcc=98
[2012/06/05 17:10:25.998131, 3] ../source3/smbd/process.c:1377(switch_message)
switch message SMBnegprot (pid 26132) conn 0x0
[2012/06/05 17:10:25.998173, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:10:25.998214, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:10:25.998251, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:10:25.998315, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:10:25.999067, 3] ../source3/smbd/negprot.c:572(reply_negprot)
Requested protocol [PC NETWORK PROGRAM 1.0]
[2012/06/05 17:10:25.999129, 3] ../source3/smbd/negprot.c:572(reply_negprot)
Requested protocol [LANMAN1.0]
[2012/06/05 17:10:25.999180, 3] ../source3/smbd/negprot.c:572(reply_negprot)
Requested protocol [Windows for Workgroups 3.1a]
[2012/06/05 17:10:25.999229, 3] ../source3/smbd/negprot.c:572(reply_negprot)
Requested protocol [LM1.2X002]
[2012/06/05 17:10:25.999276, 3] ../source3/smbd/negprot.c:572(reply_negprot)
Requested protocol [LANMAN2.1]
[2012/06/05 17:10:25.999319, 3] ../source3/smbd/negprot.c:572(reply_negprot)
Requested protocol [NT LM 0.12]
[2012/06/05 17:10:25.999387, 6]
../source3/param/loadparm.c:6618(lp_file_list_changed)
lp_file_list_changed()
file /usr/local/etc/samba/smb.conf -> /usr/local/etc/samba/smb.conf
last mod_time: Tue Jun 5 15:34:28 2012
file /usr/local/var/lib/samba/private/smbd.tmp/fileserver.conf ->
/usr/local/var/lib/samba/private/smbd.tmp/fileserver.conf last
mod_time: Tue Jun 5 15:34:36 2012
[2012/06/05 17:10:25.999519, 5]
../lib/dbwrap/dbwrap.c:156(dbwrap_check_lock_order)
used=0, lock_order=2, idx=6
[2012/06/05 17:10:25.999596, 6]
../source3/param/loadparm.c:6618(lp_file_list_changed)
lp_file_list_changed()
file /usr/local/etc/samba/smb.conf -> /usr/local/etc/samba/smb.conf
last mod_time: Tue Jun 5 15:34:28 2012
file /usr/local/var/lib/samba/private/smbd.tmp/fileserver.conf ->
/usr/local/var/lib/samba/private/smbd.tmp/fileserver.conf last
mod_time: Tue Jun 5 15:34:36 2012
[2012/06/05 17:10:25.999794, 5]
../source3/auth/auth.c:528(make_auth_context_subsystem)
Using specified auth order
[2012/06/05 17:10:25.999853, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend sam
[2012/06/05 17:10:25.999895, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'sam'
[2012/06/05 17:10:25.999932, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend sam_ignoredomain
[2012/06/05 17:10:25.999977, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'sam_ignoredomain'
[2012/06/05 17:10:26.000015, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend unix
[2012/06/05 17:10:26.000053, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'unix'
[2012/06/05 17:10:26.000090, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend winbind
[2012/06/05 17:10:26.000130, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'winbind'
[2012/06/05 17:10:26.000166, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend wbc
[2012/06/05 17:10:26.000203, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'wbc'
[2012/06/05 17:10:26.000241, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend trustdomain
[2012/06/05 17:10:26.000459, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'trustdomain'
[2012/06/05 17:10:26.000496, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend ntdomain
[2012/06/05 17:10:26.000533, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'ntdomain'
[2012/06/05 17:10:26.000568, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend guest
[2012/06/05 17:10:26.000604, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'guest'
[2012/06/05 17:10:26.000635, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend fixed_challenge
[2012/06/05 17:10:26.000667, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'fixed_challenge'
[2012/06/05 17:10:26.000697, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend name_to_ntstatus
[2012/06/05 17:10:26.000732, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'name_to_ntstatus'
[2012/06/05 17:10:26.000764, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend netlogond
[2012/06/05 17:10:26.000798, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'netlogond'
[2012/06/05 17:10:26.000829, 5] ../source3/auth/auth.c:48(smb_register_auth)
Attempting to register auth backend samba4
[2012/06/05 17:10:26.000862, 5] ../source3/auth/auth.c:60(smb_register_auth)
Successfully added auth method 'samba4'
[2012/06/05 17:10:26.000893, 5] ../source3/auth/auth.c:393(load_auth_module)
load_auth_module: Attempting to find an auth method to match guest
[2012/06/05 17:10:26.000931, 5] ../source3/auth/auth.c:418(load_auth_module)
load_auth_module: auth method guest has a valid init
[2012/06/05 17:10:26.000979, 5] ../source3/auth/auth.c:393(load_auth_module)
load_auth_module: Attempting to find an auth method to match samba4
[2012/06/05 17:10:26.002141, 3]
../auth/gensec/gensec_start.c:868(gensec_register)
GENSEC backend 'gssapi_spnego' registered
[2012/06/05 17:10:26.002196, 3]
../auth/gensec/gensec_start.c:868(gensec_register)
GENSEC backend 'gssapi_krb5' registered
[2012/06/05 17:10:26.002231, 3]
../auth/gensec/gensec_start.c:868(gensec_register)
GENSEC backend 'gssapi_krb5_sasl' registered
[2012/06/05 17:10:26.002272, 3]
../auth/gensec/gensec_start.c:868(gensec_register)
GENSEC backend 'schannel' registered
[2012/06/05 17:10:26.002313, 3]
../auth/gensec/gensec_start.c:868(gensec_register)
GENSEC backend 'spnego' registered
[2012/06/05 17:10:26.002360, 3]
../auth/gensec/gensec_start.c:868(gensec_register)
GENSEC backend 'ntlmssp' registered
[2012/06/05 17:10:26.002407, 3]
../auth/gensec/gensec_start.c:868(gensec_register)
GENSEC backend 'krb5' registered
[2012/06/05 17:10:26.002446, 3]
../auth/gensec/gensec_start.c:868(gensec_register)
GENSEC backend 'fake_gssapi_krb5' registered
[2012/06/05 17:10:26.002489, 5] ../source3/auth/auth.c:418(load_auth_module)
load_auth_module: auth method samba4 has a valid init
[2012/06/05 17:10:26.003267, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa393108
[2012/06/05 17:10:26.003364, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0xa5f3b80
[2012/06/05 17:10:26.003489, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa5f3b80 "ltdb_timeout"
[2012/06/05 17:10:26.003573, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa393108 "ltdb_callback"
[2012/06/05 17:10:26.003743, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa5f3b80
[2012/06/05 17:10:26.003834, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0x9ba5948
[2012/06/05 17:10:26.003935, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0x9ba5948 "ltdb_timeout"
[2012/06/05 17:10:26.004028, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa5f3b80 "ltdb_callback"
[2012/06/05 17:10:26.004124, 3]
../lib/ldb-samba/ldb_wrap.c:316(ldb_wrap_connect)
ldb_wrap open of secrets.ldb
[2012/06/05 17:10:26.004277, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0x9bfcc58
[2012/06/05 17:10:26.004392, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0xa2f1360
[2012/06/05 17:10:26.004743, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa2f1360 "ltdb_timeout"
[2012/06/05 17:10:26.004799, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0x9bfcc58 "ltdb_callback"
[2012/06/05 17:10:26.005731, 3] ../source4/auth/ntlm/auth.c:702(auth_register)
AUTH backend 'sam' registered
[2012/06/05 17:10:26.005782, 3] ../source4/auth/ntlm/auth.c:702(auth_register)
AUTH backend 'sam_ignoredomain' registered
[2012/06/05 17:10:26.005820, 3] ../source4/auth/ntlm/auth.c:702(auth_register)
AUTH backend 'anonymous' registered
[2012/06/05 17:10:26.005855, 3] ../source4/auth/ntlm/auth.c:702(auth_register)
AUTH backend 'winbind' registered
[2012/06/05 17:10:26.005889, 3] ../source4/auth/ntlm/auth.c:702(auth_register)
AUTH backend 'winbind_wbclient' registered
[2012/06/05 17:10:26.005922, 3] ../source4/auth/ntlm/auth.c:702(auth_register)
AUTH backend 'name_to_ntstatus' registered
[2012/06/05 17:10:26.005953, 3] ../source4/auth/ntlm/auth.c:702(auth_register)
AUTH backend 'fixed_challenge' registered
[2012/06/05 17:10:26.005987, 3] ../source4/auth/ntlm/auth.c:702(auth_register)
AUTH backend 'unix' registered
[2012/06/05 17:10:26.014886, 5]
../auth/gensec/gensec_start.c:647(gensec_start_mech)
Starting GENSEC mechanism spnego
[2012/06/05 17:10:26.015041, 5]
../auth/gensec/gensec_start.c:647(gensec_start_mech)
Starting GENSEC submechanism gssapi_krb5
[2012/06/05 17:10:26.015873, 5]
../source4/lib/messaging/messaging.c:554(imessaging_cleanup)
imessaging: cleaning up
/usr/local/var/lib/samba/private/smbd.tmp/msg/msg.26132.1
[2012/06/05 17:10:26.016151, 3] ../source3/smbd/negprot.c:394(reply_nt1)
using SPNEGO
[2012/06/05 17:10:26.016193, 3] ../source3/smbd/negprot.c:678(reply_negprot)
Selected protocol NT LM 0.12
[2012/06/05 17:10:26.016224, 5] ../source3/smbd/negprot.c:685(reply_negprot)
negprot index=5
[2012/06/05 17:10:26.016258, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.016276, 5] ../source3/lib/util.c:148(show_msg)
size=181
smb_com=0x72
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=51267
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]= 5120 (0x1400)
smb_vwv[ 8]= 102 (0x66)
smb_vwv[ 9]=64768 (0xFD00)
smb_vwv[10]=33011 (0x80F3)
smb_vwv[11]=43392 (0xA980)
smb_vwv[12]=43267 (0xA903)
smb_vwv[13]=24480 (0x5FA0)
smb_vwv[14]=52547 (0xCD43)
smb_vwv[15]=61441 (0xF001)
smb_vwv[16]= 0 (0x0)
smb_bcc=112
[2012/06/05 17:10:26.017981, 6] ../source3/smbd/process.c:1736(process_smb)
got message type 0x0 of len 0x8a
[2012/06/05 17:10:26.018061, 3] ../source3/smbd/process.c:1738(process_smb)
Transaction 1 of length 142 (0 toread)
[2012/06/05 17:10:26.018098, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.018117, 5] ../source3/lib/util.c:148(show_msg)
size=138
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=0
smb_mid=64
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
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=79
[2012/06/05 17:10:26.018512, 3] ../source3/smbd/process.c:1377(switch_message)
switch message SMBsesssetupX (pid 26132) conn 0x0
[2012/06/05 17:10:26.018555, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:10:26.018590, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:10:26.018643, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:10:26.018709, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:10:26.018754, 3]
../source3/smbd/sesssetup.c:423(reply_sesssetup_and_X)
wct=12 flg2=0xc807
[2012/06/05 17:10:26.018824, 2]
../source3/smbd/sesssetup.c:366(setup_new_vc_session)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would
close all old resources.
[2012/06/05 17:10:26.018867, 3]
../source3/smbd/sesssetup.c:134(reply_sesssetup_and_X_spnego)
Doing spnego session setup
[2012/06/05 17:10:26.018922, 3]
../source3/smbd/sesssetup.c:175(reply_sesssetup_and_X_spnego)
NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2012/06/05 17:10:26.018968, 5]
../source3/auth/auth.c:528(make_auth_context_subsystem)
Using specified auth order
[2012/06/05 17:10:26.018968, 5] ../source3/auth/auth.c:393(load_auth_module)
load_auth_module: Attempting to find an auth method to match guest
[2012/06/05 17:10:26.018968, 5] ../source3/auth/auth.c:418(load_auth_module)
load_auth_module: auth method guest has a valid init
[2012/06/05 17:10:26.018968, 5] ../source3/auth/auth.c:393(load_auth_module)
load_auth_module: Attempting to find an auth method to match samba4
[2012/06/05 17:10:26.018968, 5] ../source3/auth/auth.c:418(load_auth_module)
load_auth_module: auth method samba4 has a valid init
[2012/06/05 17:10:26.019346, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa7880e0
[2012/06/05 17:10:26.019426, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0xa390a78
[2012/06/05 17:10:26.019527, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa390a78 "ltdb_timeout"
[2012/06/05 17:10:26.019587, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa7880e0 "ltdb_callback"
[2012/06/05 17:10:26.019689, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa3ce1f8
[2012/06/05 17:10:26.019760, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0x9fd3fd0
[2012/06/05 17:10:26.019824, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0x9fd3fd0 "ltdb_timeout"
[2012/06/05 17:10:26.019885, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa3ce1f8 "ltdb_callback"
[2012/06/05 17:10:26.019940, 3]
../lib/ldb-samba/ldb_wrap.c:316(ldb_wrap_connect)
ldb_wrap open of secrets.ldb
[2012/06/05 17:10:26.020005, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa176668
[2012/06/05 17:10:26.020059, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0x9c587d0
[2012/06/05 17:10:26.020260, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0x9c587d0 "ltdb_timeout"
[2012/06/05 17:10:26.020331, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa176668 "ltdb_callback"
[2012/06/05 17:10:26.028248, 5]
../auth/gensec/gensec_start.c:647(gensec_start_mech)
Starting GENSEC mechanism spnego
[2012/06/05 17:10:26.028438, 5]
../auth/gensec/gensec_start.c:647(gensec_start_mech)
Starting GENSEC submechanism ntlmssp
[2012/06/05 17:10:26.028540, 3]
../auth/ntlmssp/ntlmssp_util.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
[2012/06/05 17:10:26.028856, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.028884, 5] ../source3/lib/util.c:148(show_msg)
size=308
smb_com=0x73
smb_rcls=22
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=100
smb_mid=64
smt_wct=4
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 0 (0x0)
smb_vwv[ 3]= 205 (0xCD)
smb_bcc=265
[2012/06/05 17:10:26.030234, 6] ../source3/smbd/process.c:1736(process_smb)
got message type 0x0 of len 0x20e
[2012/06/05 17:10:26.030285, 3] ../source3/smbd/process.c:1738(process_smb)
Transaction 2 of length 530 (0 toread)
[2012/06/05 17:10:26.030318, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.030337, 5] ../source3/lib/util.c:148(show_msg)
size=526
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=100
smb_mid=128
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
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]= 462 (0x1CE)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 0 (0x0)
smb_vwv[10]= 212 (0xD4)
smb_vwv[11]=40960 (0xA000)
smb_bcc=467
[2012/06/05 17:10:26.030655, 3] ../source3/smbd/process.c:1377(switch_message)
switch message SMBsesssetupX (pid 26132) conn 0x0
[2012/06/05 17:10:26.030691, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:10:26.030730, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:10:26.030769, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:10:26.030835, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:10:26.030878, 3]
../source3/smbd/sesssetup.c:423(reply_sesssetup_and_X)
wct=12 flg2=0xc807
[2012/06/05 17:10:26.030916, 2]
../source3/smbd/sesssetup.c:366(setup_new_vc_session)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would
close all old resources.
[2012/06/05 17:10:26.030963, 3]
../source3/smbd/sesssetup.c:134(reply_sesssetup_and_X_spnego)
Doing spnego session setup
[2012/06/05 17:10:26.031003, 3]
../source3/smbd/sesssetup.c:175(reply_sesssetup_and_X_spnego)
NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2012/06/05 17:10:26.031069, 3]
../auth/ntlmssp/ntlmssp_server.c:365(ntlmssp_server_preauth)
Got user=[resara] domain=[win7-dom-test] workstation=[WIN7-DOM-TEST]
len1=24 len2=254
[2012/06/05 17:10:26.031111, 3]
../source4/auth/ntlm/auth.c:297(auth_check_password_send)
auth_check_password_send: Checking password for unmapped user
[win7-dom-test]\[resara]@[WIN7-DOM-TEST]
[2012/06/05 17:10:26.031157, 5]
../source4/auth/ntlm/auth_util.c:57(map_user_info_cracknames)
map_user_info_cracknames: Mapping user [win7-dom-test]\[resara] from
workstation [WIN7-DOM-TEST]
auth_check_password_send: mapped user is: [TESTDOM]\[resara]@[WIN7-DOM-TEST]
[2012/06/05 17:10:26.032069, 5]
../source4/auth/ntlm/auth.c:76(auth_get_challenge)
auth_get_challenge: returning previous challenge by module random (normal)
[2012/06/05 17:10:26.032110, 5] ../lib/util/util.c:455(dump_data)
[0000] 23 EB 6F 3E 04 0C 03 C0 #.o>....
[2012/06/05 17:10:26.032727, 3]
../source4/auth/ntlm/auth_sam.c:61(authsam_search_account)
sam_search_user: Couldn't find user [resara] in samdb, under DC=testdom,DC=lan
[2012/06/05 17:10:26.032794, 2]
../source4/auth/ntlm/auth.c:447(auth_check_password_recv)
auth_check_password_recv: sam_ignoredomain authentication for user
[TESTDOM\resara] FAILED with error NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.032846, 5]
../auth/ntlmssp/ntlmssp_server.c:462(ntlmssp_server_check_password)
../auth/ntlmssp/ntlmssp_server.c:462: Checking NTLMSSP password for
win7-dom-test\resara failed: NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.032887, 2]
../auth/gensec/spnego.c:746(gensec_spnego_server_negTokenTarg)
SPNEGO login failed: NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.032951, 5]
../source4/lib/messaging/messaging.c:554(imessaging_cleanup)
imessaging: cleaning up
/usr/local/var/lib/samba/private/smbd.tmp/msg/msg.26132.1
[2012/06/05 17:10:26.033192, 3] ../source3/smbd/error.c:82(error_packet_set)
NT error packet at ../source3/smbd/sesssetup.c(255) cmd=115
(SMBsesssetupX) NT_STATUS_LOGON_FAILURE
[2012/06/05 17:10:26.033262, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.033284, 5] ../source3/lib/util.c:148(show_msg)
size=35
smb_com=0x73
smb_rcls=109
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=100
smb_mid=128
smt_wct=0
smb_bcc=0
[2012/06/05 17:10:26.039253, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:10:26.039364, 5] ../source3/smbd/files.c:138(file_new)
allocated file structure 4766, fnum = 8862 (1 used)
[2012/06/05 17:10:26.039442, 5] ../lib/util/modules.c:174(do_smb_load_module)
Probing module 'spoolss'
[2012/06/05 17:10:26.039490, 5] ../lib/util/modules.c:188(do_smb_load_module)
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:26.039648, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
[2012/06/05 17:10:26.039699, 5] ../source3/smbd/files.c:492(file_free)
freed files structure 8862 (0 used)
[2012/06/05 17:10:26.040869, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:10:26.040950, 5] ../source3/smbd/files.c:138(file_new)
allocated file structure 4767, fnum = 8863 (1 used)
[2012/06/05 17:10:26.041026, 5] ../lib/util/modules.c:174(do_smb_load_module)
Probing module 'spoolss'
[2012/06/05 17:10:26.041090, 5] ../lib/util/modules.c:188(do_smb_load_module)
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:26.041199, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
[2012/06/05 17:10:26.041251, 5] ../source3/smbd/files.c:492(file_free)
freed files structure 8863 (0 used)
[2012/06/05 17:10:26.041971, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:10:26.042053, 5] ../source3/smbd/files.c:138(file_new)
allocated file structure 4768, fnum = 8864 (1 used)
[2012/06/05 17:10:26.042123, 5] ../lib/util/modules.c:174(do_smb_load_module)
Probing module 'spoolss'
[2012/06/05 17:10:26.042170, 5] ../lib/util/modules.c:188(do_smb_load_module)
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:26.042273, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
[2012/06/05 17:10:26.042324, 5] ../source3/smbd/files.c:492(file_free)
freed files structure 8864 (0 used)
[2012/06/05 17:10:26.042568, 6] ../source3/smbd/process.c:1736(process_smb)
got message type 0x0 of len 0x8a
[2012/06/05 17:10:26.042650, 3] ../source3/smbd/process.c:1738(process_smb)
Transaction 3 of length 142 (0 toread)
[2012/06/05 17:10:26.042695, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.042719, 5] ../source3/lib/util.c:148(show_msg)
size=138
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=0
smb_mid=192
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
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=79
[2012/06/05 17:10:26.043054, 3] ../source3/smbd/process.c:1377(switch_message)
switch message SMBsesssetupX (pid 26132) conn 0x0
[2012/06/05 17:10:26.043114, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:10:26.043170, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:10:26.043219, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:10:26.043297, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:10:26.043351, 3]
../source3/smbd/sesssetup.c:423(reply_sesssetup_and_X)
wct=12 flg2=0xc807
[2012/06/05 17:10:26.043400, 2]
../source3/smbd/sesssetup.c:366(setup_new_vc_session)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would
close all old resources.
[2012/06/05 17:10:26.043458, 3]
../source3/smbd/sesssetup.c:134(reply_sesssetup_and_X_spnego)
Doing spnego session setup
[2012/06/05 17:10:26.043509, 3]
../source3/smbd/sesssetup.c:175(reply_sesssetup_and_X_spnego)
NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2012/06/05 17:10:26.043566, 5]
../source3/auth/auth.c:528(make_auth_context_subsystem)
Using specified auth order
[2012/06/05 17:10:26.043620, 5] ../source3/auth/auth.c:393(load_auth_module)
load_auth_module: Attempting to find an auth method to match guest
[2012/06/05 17:10:26.043665, 5] ../source3/auth/auth.c:418(load_auth_module)
load_auth_module: auth method guest has a valid init
[2012/06/05 17:10:26.043706, 5] ../source3/auth/auth.c:393(load_auth_module)
load_auth_module: Attempting to find an auth method to match samba4
[2012/06/05 17:10:26.043748, 5] ../source3/auth/auth.c:418(load_auth_module)
load_auth_module: auth method samba4 has a valid init
[2012/06/05 17:10:26.044154, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa76f340
[2012/06/05 17:10:26.044273, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0xa7880e0
[2012/06/05 17:10:26.044516, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa7880e0 "ltdb_timeout"
[2012/06/05 17:10:26.044585, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa76f340 "ltdb_callback"
[2012/06/05 17:10:26.044695, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0x9c48998
[2012/06/05 17:10:26.044761, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0xa3ce1f8
[2012/06/05 17:10:26.044831, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa3ce1f8 "ltdb_timeout"
[2012/06/05 17:10:26.044890, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0x9c48998 "ltdb_callback"
[2012/06/05 17:10:26.044949, 3]
../lib/ldb-samba/ldb_wrap.c:316(ldb_wrap_connect)
ldb_wrap open of secrets.ldb
[2012/06/05 17:10:26.045014, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa5f3bd8
[2012/06/05 17:10:26.045074, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0x9c587d0
[2012/06/05 17:10:26.045224, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0x9c587d0 "ltdb_timeout"
[2012/06/05 17:10:26.045289, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa5f3bd8 "ltdb_callback"
[2012/06/05 17:10:26.046869, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:10:26.046915, 5] ../source3/smbd/files.c:138(file_new)
allocated file structure 4769, fnum = 8865 (1 used)
[2012/06/05 17:10:26.046996, 5] ../lib/util/modules.c:174(do_smb_load_module)
Probing module 'spoolss'
[2012/06/05 17:10:26.047052, 5] ../lib/util/modules.c:188(do_smb_load_module)
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:26.047193, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
[2012/06/05 17:10:26.047251, 5] ../source3/smbd/files.c:492(file_free)
freed files structure 8865 (0 used)
[2012/06/05 17:10:26.055898, 5]
../auth/gensec/gensec_start.c:647(gensec_start_mech)
Starting GENSEC mechanism spnego
[2012/06/05 17:10:26.056049, 5]
../auth/gensec/gensec_start.c:647(gensec_start_mech)
Starting GENSEC submechanism ntlmssp
[2012/06/05 17:10:26.056157, 3]
../auth/ntlmssp/ntlmssp_util.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
[2012/06/05 17:10:26.056494, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.056529, 5] ../source3/lib/util.c:148(show_msg)
size=308
smb_com=0x73
smb_rcls=22
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=101
smb_mid=192
smt_wct=4
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 0 (0x0)
smb_vwv[ 3]= 205 (0xCD)
smb_bcc=265
[2012/06/05 17:10:26.057874, 6] ../source3/smbd/process.c:1736(process_smb)
got message type 0x0 of len 0x20e
[2012/06/05 17:10:26.057940, 3] ../source3/smbd/process.c:1738(process_smb)
Transaction 4 of length 530 (0 toread)
[2012/06/05 17:10:26.057994, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.058022, 5] ../source3/lib/util.c:148(show_msg)
size=526
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=101
smb_mid=256
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
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]= 462 (0x1CE)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 0 (0x0)
smb_vwv[10]= 212 (0xD4)
smb_vwv[11]=40960 (0xA000)
smb_bcc=467
[2012/06/05 17:10:26.058485, 3] ../source3/smbd/process.c:1377(switch_message)
switch message SMBsesssetupX (pid 26132) conn 0x0
[2012/06/05 17:10:26.058535, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:10:26.058580, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:10:26.058632, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:10:26.058761, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:10:26.058834, 3]
../source3/smbd/sesssetup.c:423(reply_sesssetup_and_X)
wct=12 flg2=0xc807
[2012/06/05 17:10:26.058883, 2]
../source3/smbd/sesssetup.c:366(setup_new_vc_session)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would
close all old resources.
[2012/06/05 17:10:26.058929, 3]
../source3/smbd/sesssetup.c:134(reply_sesssetup_and_X_spnego)
Doing spnego session setup
[2012/06/05 17:10:26.058976, 3]
../source3/smbd/sesssetup.c:175(reply_sesssetup_and_X_spnego)
NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2012/06/05 17:10:26.059051, 3]
../auth/ntlmssp/ntlmssp_server.c:365(ntlmssp_server_preauth)
Got user=[resara] domain=[win7-dom-test] workstation=[WIN7-DOM-TEST]
len1=24 len2=254
[2012/06/05 17:10:26.059108, 3]
../source4/auth/ntlm/auth.c:297(auth_check_password_send)
auth_check_password_send: Checking password for unmapped user
[win7-dom-test]\[resara]@[WIN7-DOM-TEST]
[2012/06/05 17:10:26.059159, 5]
../source4/auth/ntlm/auth_util.c:57(map_user_info_cracknames)
map_user_info_cracknames: Mapping user [win7-dom-test]\[resara] from
workstation [WIN7-DOM-TEST]
auth_check_password_send: mapped user is: [TESTDOM]\[resara]@[WIN7-DOM-TEST]
[2012/06/05 17:10:26.060447, 5]
../source4/auth/ntlm/auth.c:76(auth_get_challenge)
auth_get_challenge: returning previous challenge by module random (normal)
[2012/06/05 17:10:26.060506, 5] ../lib/util/util.c:455(dump_data)
[0000] F7 96 99 A7 6C 52 85 96 ....lR..
[2012/06/05 17:10:26.061317, 3]
../source4/auth/ntlm/auth_sam.c:61(authsam_search_account)
sam_search_user: Couldn't find user [resara] in samdb, under DC=testdom,DC=lan
[2012/06/05 17:10:26.061384, 2]
../source4/auth/ntlm/auth.c:447(auth_check_password_recv)
auth_check_password_recv: sam_ignoredomain authentication for user
[TESTDOM\resara] FAILED with error NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.061442, 5]
../auth/ntlmssp/ntlmssp_server.c:462(ntlmssp_server_check_password)
../auth/ntlmssp/ntlmssp_server.c:462: Checking NTLMSSP password for
win7-dom-test\resara failed: NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.061493, 2]
../auth/gensec/spnego.c:746(gensec_spnego_server_negTokenTarg)
SPNEGO login failed: NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.061574, 5]
../source4/lib/messaging/messaging.c:554(imessaging_cleanup)
imessaging: cleaning up
/usr/local/var/lib/samba/private/smbd.tmp/msg/msg.26132.1
[2012/06/05 17:10:26.061852, 3] ../source3/smbd/error.c:82(error_packet_set)
NT error packet at ../source3/smbd/sesssetup.c(255) cmd=115
(SMBsesssetupX) NT_STATUS_LOGON_FAILURE
[2012/06/05 17:10:26.061927, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.061954, 5] ../source3/lib/util.c:148(show_msg)
size=35
smb_com=0x73
smb_rcls=109
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=101
smb_mid=256
smt_wct=0
smb_bcc=0
[2012/06/05 17:10:26.065747, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:10:26.065893, 5] ../source3/smbd/files.c:138(file_new)
allocated file structure 4770, fnum = 8866 (1 used)
[2012/06/05 17:10:26.065984, 5] ../lib/util/modules.c:174(do_smb_load_module)
Probing module 'spoolss'
[2012/06/05 17:10:26.066038, 5] ../lib/util/modules.c:188(do_smb_load_module)
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:26.066211, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
[2012/06/05 17:10:26.066270, 5] ../source3/smbd/files.c:492(file_free)
freed files structure 8866 (0 used)
[2012/06/05 17:10:26.067434, 4] ../source3/smbd/uid.c:295(change_to_user)
Skipping user change - already user
[2012/06/05 17:10:26.067541, 5] ../source3/smbd/files.c:138(file_new)
allocated file structure 4771, fnum = 8867 (1 used)
[2012/06/05 17:10:26.067632, 5] ../lib/util/modules.c:174(do_smb_load_module)
Probing module 'spoolss'
[2012/06/05 17:10:26.067685, 5] ../lib/util/modules.c:188(do_smb_load_module)
Probing module 'spoolss': Trying to load from
/usr/local/lib/samba/rpc/spoolss.so
[2012/06/05 17:10:26.067847, 5] ../lib/util/modules.c:56(load_module)
Error loading module '/usr/local/lib/samba/rpc/spoolss.so':
/usr/local/lib/samba/rpc/spoolss.so: cannot open shared object file:
No such file or directory
[2012/06/05 17:10:26.067904, 5] ../source3/smbd/files.c:492(file_free)
freed files structure 8867 (0 used)
[2012/06/05 17:10:26.069330, 6] ../source3/smbd/process.c:1736(process_smb)
got message type 0x0 of len 0x8a
[2012/06/05 17:10:26.069441, 3] ../source3/smbd/process.c:1738(process_smb)
Transaction 5 of length 142 (0 toread)
[2012/06/05 17:10:26.069528, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.069555, 5] ../source3/lib/util.c:148(show_msg)
size=138
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=0
smb_mid=320
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
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=79
[2012/06/05 17:10:26.070020, 3] ../source3/smbd/process.c:1377(switch_message)
switch message SMBsesssetupX (pid 26132) conn 0x0
[2012/06/05 17:10:26.070070, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:10:26.070116, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:10:26.070160, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:10:26.070238, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:10:26.070289, 3]
../source3/smbd/sesssetup.c:423(reply_sesssetup_and_X)
wct=12 flg2=0xc807
[2012/06/05 17:10:26.070335, 2]
../source3/smbd/sesssetup.c:366(setup_new_vc_session)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would
close all old resources.
[2012/06/05 17:10:26.070381, 3]
../source3/smbd/sesssetup.c:134(reply_sesssetup_and_X_spnego)
Doing spnego session setup
[2012/06/05 17:10:26.070430, 3]
../source3/smbd/sesssetup.c:175(reply_sesssetup_and_X_spnego)
NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2012/06/05 17:10:26.070481, 5]
../source3/auth/auth.c:528(make_auth_context_subsystem)
Using specified auth order
[2012/06/05 17:10:26.070528, 5] ../source3/auth/auth.c:393(load_auth_module)
load_auth_module: Attempting to find an auth method to match guest
[2012/06/05 17:10:26.070583, 5] ../source3/auth/auth.c:418(load_auth_module)
load_auth_module: auth method guest has a valid init
[2012/06/05 17:10:26.070642, 5] ../source3/auth/auth.c:393(load_auth_module)
load_auth_module: Attempting to find an auth method to match samba4
[2012/06/05 17:10:26.070702, 5] ../source3/auth/auth.c:418(load_auth_module)
load_auth_module: auth method samba4 has a valid init
[2012/06/05 17:10:26.071753, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa344da8
[2012/06/05 17:10:26.071838, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0x9de16e8
[2012/06/05 17:10:26.071940, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0x9de16e8 "ltdb_timeout"
[2012/06/05 17:10:26.072010, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa344da8 "ltdb_callback"
[2012/06/05 17:10:26.072114, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0x99de200
[2012/06/05 17:10:26.072182, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0xa3ce1f8
[2012/06/05 17:10:26.072255, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa3ce1f8 "ltdb_timeout"
[2012/06/05 17:10:26.072325, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0x99de200 "ltdb_callback"
[2012/06/05 17:10:26.072391, 3]
../lib/ldb-samba/ldb_wrap.c:316(ldb_wrap_connect)
ldb_wrap open of secrets.ldb
[2012/06/05 17:10:26.072463, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_callback": 0xa41e610
[2012/06/05 17:10:26.072530, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Added timed event "ltdb_timeout": 0xa76ae50
[2012/06/05 17:10:26.072989, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa76ae50 "ltdb_timeout"
[2012/06/05 17:10:26.073097, 5] ../lib/ldb-samba/ldb_wrap.c:68(ldb_wrap_debug)
ldb: tevent: Destroying timer event 0xa41e610 "ltdb_callback"
[2012/06/05 17:10:26.086628, 5]
../auth/gensec/gensec_start.c:647(gensec_start_mech)
Starting GENSEC mechanism spnego
[2012/06/05 17:10:26.086802, 5]
../auth/gensec/gensec_start.c:647(gensec_start_mech)
Starting GENSEC submechanism ntlmssp
[2012/06/05 17:10:26.086873, 3]
../auth/ntlmssp/ntlmssp_util.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
[2012/06/05 17:10:26.087108, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.087133, 5] ../source3/lib/util.c:148(show_msg)
size=308
smb_com=0x73
smb_rcls=22
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=102
smb_mid=320
smt_wct=4
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
smb_vwv[ 2]= 0 (0x0)
smb_vwv[ 3]= 205 (0xCD)
smb_bcc=265
[2012/06/05 17:10:26.087668, 6] ../source3/smbd/process.c:1736(process_smb)
got message type 0x0 of len 0x20e
[2012/06/05 17:10:26.087721, 3] ../source3/smbd/process.c:1738(process_smb)
Transaction 6 of length 530 (0 toread)
[2012/06/05 17:10:26.087754, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.087772, 5] ../source3/lib/util.c:148(show_msg)
size=526
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=24
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=102
smb_mid=384
smt_wct=12
smb_vwv[ 0]= 255 (0xFF)
smb_vwv[ 1]= 0 (0x0)
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]= 462 (0x1CE)
smb_vwv[ 8]= 0 (0x0)
smb_vwv[ 9]= 0 (0x0)
smb_vwv[10]= 212 (0xD4)
smb_vwv[11]=40960 (0xA000)
smb_bcc=467
[2012/06/05 17:10:26.088115, 3] ../source3/smbd/process.c:1377(switch_message)
switch message SMBsesssetupX (pid 26132) conn 0x0
[2012/06/05 17:10:26.088151, 4] ../source3/smbd/sec_ctx.c:315(set_sec_ctx)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2012/06/05 17:10:26.088184, 5]
../libcli/security/security_token.c:53(security_token_debug)
Security token: (NULL)
[2012/06/05 17:10:26.088215, 5]
../source3/auth/token_util.c:528(debug_unix_user_token)
UNIX token of user 0
Primary group is 0 and contains 0 supplementary groups
[2012/06/05 17:10:26.088271, 5]
../source3/smbd/uid.c:343(smbd_change_to_root_user)
change_to_root_user: now uid=(0,0) gid=(0,0)
[2012/06/05 17:10:26.088308, 3]
../source3/smbd/sesssetup.c:423(reply_sesssetup_and_X)
wct=12 flg2=0xc807
[2012/06/05 17:10:26.088340, 2]
../source3/smbd/sesssetup.c:366(setup_new_vc_session)
setup_new_vc_session: New VC == 0, if NT4.x compatible we would
close all old resources.
[2012/06/05 17:10:26.088371, 3]
../source3/smbd/sesssetup.c:134(reply_sesssetup_and_X_spnego)
Doing spnego session setup
[2012/06/05 17:10:26.088414, 3]
../source3/smbd/sesssetup.c:175(reply_sesssetup_and_X_spnego)
NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
[2012/06/05 17:10:26.088479, 3]
../auth/ntlmssp/ntlmssp_server.c:365(ntlmssp_server_preauth)
Got user=[resara] domain=[win7-dom-test] workstation=[WIN7-DOM-TEST]
len1=24 len2=254
[2012/06/05 17:10:26.088519, 3]
../source4/auth/ntlm/auth.c:297(auth_check_password_send)
auth_check_password_send: Checking password for unmapped user
[win7-dom-test]\[resara]@[WIN7-DOM-TEST]
[2012/06/05 17:10:26.088560, 5]
../source4/auth/ntlm/auth_util.c:57(map_user_info_cracknames)
map_user_info_cracknames: Mapping user [win7-dom-test]\[resara] from
workstation [WIN7-DOM-TEST]
auth_check_password_send: mapped user is: [TESTDOM]\[resara]@[WIN7-DOM-TEST]
[2012/06/05 17:10:26.089374, 5]
../source4/auth/ntlm/auth.c:76(auth_get_challenge)
auth_get_challenge: returning previous challenge by module random (normal)
[2012/06/05 17:10:26.089411, 5] ../lib/util/util.c:455(dump_data)
[0000] DF ED 97 7B F2 BE EF 36 ...{...6
[2012/06/05 17:10:26.089961, 3]
../source4/auth/ntlm/auth_sam.c:61(authsam_search_account)
sam_search_user: Couldn't find user [resara] in samdb, under DC=testdom,DC=lan
[2012/06/05 17:10:26.090006, 2]
../source4/auth/ntlm/auth.c:447(auth_check_password_recv)
auth_check_password_recv: sam_ignoredomain authentication for user
[TESTDOM\resara] FAILED with error NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.090048, 5]
../auth/ntlmssp/ntlmssp_server.c:462(ntlmssp_server_check_password)
../auth/ntlmssp/ntlmssp_server.c:462: Checking NTLMSSP password for
win7-dom-test\resara failed: NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.090083, 2]
../auth/gensec/spnego.c:746(gensec_spnego_server_negTokenTarg)
SPNEGO login failed: NT_STATUS_NO_SUCH_USER
[2012/06/05 17:10:26.090140, 5]
../source4/lib/messaging/messaging.c:554(imessaging_cleanup)
imessaging: cleaning up
/usr/local/var/lib/samba/private/smbd.tmp/msg/msg.26132.1
[2012/06/05 17:10:26.090369, 3] ../source3/smbd/error.c:82(error_packet_set)
NT error packet at ../source3/smbd/sesssetup.c(255) cmd=115
(SMBsesssetupX) NT_STATUS_LOGON_FAILURE
[2012/06/05 17:10:26.090417, 5] ../source3/lib/util.c:138(show_msg)
[2012/06/05 17:10:26.090443, 5] ../source3/lib/util.c:148(show_msg)
size=35
smb_com=0x73
smb_rcls=109
smb_reh=0
smb_err=49152
smb_flg=136
smb_flg2=51207
smb_tid=65535
smb_pid=65279
smb_uid=102
smb_mid=384
smt_wct=0
smb_bcc=0
On Tue, Jun 5, 2012 at 3:48 PM, brendan powers <brendan0powers at gmail.com> wrote:
> I have some more information about this issue. To ensure that I was
> reproducing a real issue, and not just an issue with my setup, I
> decided to create a test from scratch. Here are the steps I used to
> reproduce the issue.
>
> 1) Install Ubuntu 10.04
> 2) Check out samba4 Beta1
> 3) Configure with: ./configure.developer --enable-fhs
> --prefix=/usr/local/ --with-syslog --with-logfilebase=/var/log/samba
> --enable-cups --with-pam --with-acl-support
> 4) Provision with: provision --realm=testdom.lan --domain=TESTDOM
> --adminpass=AdminPw123 --server-role=dc
> 5) Configure bind 9.8 do use the DLZ plugin and dynamic updates.
>
> After that was all set up, I tested a XP and Win7 computer, and got
> the same results as before. After that I tried joining both machines
> to the domain. Once the machines were joined to the domain, everything
> worked as expected. I was able to browse the shares as normal, and
> edit permissions. So, this issue seems to only affect machines not
> joined to the domain, or domains with incorrect DNS configurations.
> Would it be appropriate to file a bug report for this issue?
>
> Also, I noticed that the files in sysvol are not marked to inherit
> permissions by default. I believe this is correct for the sysvol
> folder itself, but not for any of it's sub-folders.
>
>
>
> On Tue, Jun 5, 2012 at 1:22 PM, brendan powers <brendan0powers at gmail.com> wrote:
>> I've been having some issues testing the new S3FS features in the
>> latest alpha and beta releases. When I browse to a share from a
>> windows 7, or windows server 2008 machine, I get the error "The
>> parameter is incorrect". If I access the share from a windows XP
>> computer, I can browse the share, but I don't see the permissions tab.
>> At one point, I could see the permissions tab, but when I tried to add
>> a new user to the list, I got the above error. This leads me to think
>> that the error is caused by some sort of ACL issue. I have POSIX ACLs,
>> and xattibutes enabled. I tried starting samba with -i -M single -d9,
>> but didn't see anything interesting when I accessed the share.
>> However, I think that I'm just not getting debug output from the smb
>> process. Is there something I need to do to get debug logs?
>>
>> I have an unrelated issue with print shares. I am able to get CUPS
>> printer listed, however, when I try to connect to them, I get the
>> error "Windows cannot connect to the printer." The error number is
>> 0x0000002e. I'm not really sure printers are supposed to work, but I
>> thought I'd try.
More information about the samba-technical
mailing list