slow access on maped network drive - strange problem

[rudis]

hello, I've got a very strange problem....

I use samba version 3.0.20pre1-0.1-Suse /SuSE 9.3. I connect the samba network share with the usual "net use" command. The problem is when I firstly try to connect to the network drive it takes a long time to make the connect (about 10 seconds). If I am finally connected it works fine and the speed is also fine too. I've checked the DNS first and put the Computer names in etc/hosts on server and client side. But that doesn't work for me.

It looks like that isn't only a problem with samba 3.0.20 on older samba versions still the same.

Any Ideas?

Thx a LOT!

Kind regards.!

 

[rzs0502]

What type of authentication are you using on the Samba server


"If you always do what you've always done, you will always be where you've always been."

 

[rudis]

I think lokal users.

 

[rzs0502]

Hard one to trace.
I would tail -f /var/log/samba/log.nmbd (or wherever your log might be) and will a connection is being made

Do you have any socket options in smb.conf such as :
socket options = TCP_NODELAY SO_RCVBUF=8192 SO_SNDBUF=8192 IPTOS_LOWDELAY
Although I doubt that this will help much.

Also, are you using the DNS proxy or WINS support options?


"If you always do what you've always done, you will always be where you've always been."

 

[rudis]

Hi, no I have no WINS Support

Here is my smb.conf

[global]
workgroup = CAD
netbios name = CAD
map to guest = Bad User
username map = /etc/samba/smbusers
domain master = No
valid users = admin, ald, cad
write list = admin, ald, cad
hosts allow = 192.1.
security = user
add machine script = /usr/sbin/useradd -c Machine -d /var/lib/nobody -s /bin/false %m$
domain logons = No
debug level = 3

[cad]
comment = CAD Daten-Bearbeitung
path = /Daten/CAD
read only = No
force create mode = 0774
force directory mode = 02777

[org]
comment = Schreibschutz CAD Ablage
path = /Daten/ORG
read only = No
force create mode = 0774
force directory mode = 02777

 

[rzs0502]

Try adding 'socket options = TCP_NODELAY SO_RCVBUF=8192 SO_SNDBUF=8192 IPTOS_LOWDELAY' to see if it speeds up.
I've always used these settings and all our Samba servers are pretty quick...

Also, you might get more assistance in the Samba Forum forum865



"If you always do what you've always done, you will always be where you've always been."

 

[rudis]

HI, the socket option didn't resolv my problem. Thats the log.smbd file:

cadlnx:/var/log/samba # cat /dev/null > log.smbd
cadlnx:/var/log/samba # tail -f /var/log/samba/log.smbd
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 677 of length 80
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:14, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (1002, 1000) - sec_ctx_stack_ndx = 0
[2005/10/11 16:01:14, 3] smbd/trans2.c:call_trans2qfilepathinfo(2418)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2005/10/11 16:01:14, 3] smbd/trans2.c:call_trans2qfilepathinfo(2452)
call_trans2qfilepathinfo . (fnum = -1) level=1004 call=5 total_data=0
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 678 of length 90
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:14, 3] smbd/trans2.c:call_trans2findfirst(1351)
call_trans2findfirst: dirtype = 22, maxentries = 1366,
close_after_first=0, close_if_end = 2 requires_resume_key = 4 level = 0x104,
max_data_bytes = 16384
[2005/10/11 16:01:14, 3] smbd/dir.c:dptr_create(476)
creating new dirptr 256 for path ./, expect_close = 1
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 679 of length 74
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:14, 3] smbd/trans2.c:call_trans2qfsinfo(1775)
call_trans2qfsinfo: level = 1007
[2005/10/11 16:01:14, 3] lib/sysquotas.c:sys_get_quota(413)
sys_get_vfs_quota() failed for mntpath[/Daten] bdev[/dev/sda3] qtype[2]
id[1002]: Invalid argument
[2005/10/11 16:01:14, 3] lib/sysquotas.c:sys_get_quota(413)
sys_get_vfs_quota() failed for mntpath[/Daten] bdev[/dev/sda3] qtype[4]
id[1000]: Invalid argument
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 680 of length 104
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:14, 3] smbd/trans2.c:call_trans2qfilepathinfo(2418)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2005/10/11 16:01:14, 3] smbd/trans2.c:call_trans2qfilepathinfo(2443)
call_trans2qfilepathinfo: SMB_VFS_STAT of desktop.ini failed (No such file
or directory)
[2005/10/11 16:01:14, 3] smbd/error.c:error_packet(105)
error string = No such file or directory
[2005/10/11 16:01:14, 3] smbd/error.c:error_packet(129)
error packet at smbd/trans2.c(2197) cmd=50 (SMBtrans2)
NT_STATUS_OBJECT_NAME_NOT_FOUND
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 681 of length 240
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBsesssetupX (pid 7566) conn 0x0
[2005/10/11 16:01:14, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/10/11 16:01:14, 3] smbd/sesssetup.c:reply_sesssetup_and_X(655)
wct=12 flg2=0xc807
[2005/10/11 16:01:14, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(535)
Doing spnego session setup
[2005/10/11 16:01:14, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(566)
NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002
5.1] PrimaryDomain=[]
[2005/10/11 16:01:14, 3] smbd/sesssetup.c:reply_spnego_negotiate(444)
Got OID 1 3 6 1 4 1 311 2 2 10
[2005/10/11 16:01:14, 3] smbd/sesssetup.c:reply_spnego_negotiate(447)
Got secblob of size 40
[2005/10/11 16:01:14, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62)
Got NTLMSSP neg_flags=0xe2088297
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 682 of length 272
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBsesssetupX (pid 7566) conn 0x0
[2005/10/11 16:01:14, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/10/11 16:01:14, 3] smbd/sesssetup.c:reply_sesssetup_and_X(655)
wct=12 flg2=0xc807
[2005/10/11 16:01:14, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(535)
Doing spnego session setup
[2005/10/11 16:01:14, 3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(566)
NativeOS=[Windows 2002 Service Pack 2 2600] NativeLanMan=[Windows 2002
5.1] PrimaryDomain=[]
[2005/10/11 16:01:14, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(606)
Got user=[] domain=[] workstation=[LAP05] len1=1 len2=0
[2005/10/11 16:01:14, 3] auth/auth.c:check_ntlm_password(219)
check_ntlm_password: Checking password for unmapped user []\[]@[LAP05]
with the new password interface
[2005/10/11 16:01:14, 3] auth/auth.c:check_ntlm_password(222)
check_ntlm_password: mapped user is: [CAD]\[]@[LAP05]
[2005/10/11 16:01:14, 3] auth/auth.c:check_ntlm_password(268)
check_ntlm_password: guest authentication for user [] succeeded
[2005/10/11 16:01:14, 3] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(319)
NTLMSSP Sign/Seal - Initialising with flags:
[2005/10/11 16:01:14, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(62)
Got NTLMSSP neg_flags=0x60088215
[2005/10/11 16:01:14, 3] smbd/password.c:register_vuid(222)
User name: nobody Real name: nobody
[2005/10/11 16:01:14, 3] smbd/password.c:register_vuid(241)
UNIX uid 65534 is UNIX user nobody, and will be vuid 102
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 683 of length 74
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:14, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (1002, 1000) - sec_ctx_stack_ndx = 0
[2005/10/11 16:01:14, 3] smbd/trans2.c:call_trans2qfsinfo(1775)
call_trans2qfsinfo: level = 1007
[2005/10/11 16:01:14, 3] lib/sysquotas.c:sys_get_quota(413)
sys_get_vfs_quota() failed for mntpath[/Daten] bdev[/dev/sda3] qtype[2]
id[1002]: Invalid argument
[2005/10/11 16:01:14, 3] lib/sysquotas.c:sys_get_quota(413)
sys_get_vfs_quota() failed for mntpath[/Daten] bdev[/dev/sda3] qtype[4]
id[1000]: Invalid argument
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 684 of length 82
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBtconX (pid 7566) conn 0x0
[2005/10/11 16:01:14, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/10/11 16:01:14, 3] lib/access.c:check_access(313)
check_access: no hostnames in host allow/deny list.
[2005/10/11 16:01:14, 2] lib/access.c:check_access(324)
Allowed connection from (192.1.1.222)
[2005/10/11 16:01:14, 3] smbd/service.c:make_connection_snum(479)
Connect path is '/var/tmp' for service [IPC$]
[2005/10/11 16:01:14, 3] lib/util_seaccess.c:se_access_check(251)
[2005/10/11 16:01:14, 3] lib/util_seaccess.c:se_access_check(252)
se_access_check: user sid is S-1-5-21-3194891354-3350294327-721757377-501
se_access_check: also S-1-5-21-3194891354-3350294327-721757377-514
se_access_check: also S-1-1-0
se_access_check: also S-1-5-2
se_access_check: also S-1-5-32-546
se_access_check: also S-1-5-21-3194891354-3350294327-721757377-132067
se_access_check: also S-1-5-21-3194891354-3350294327-721757377-132069
[2005/10/11 16:01:14, 3] smbd/vfs.c:vfs_init_default(206)
Initialising default vfs hooks
[2005/10/11 16:01:14, 2] smbd/uid.c:change_to_user(202)
change_to_user: SMB user (unix user nobody, vuid 102) not permitted
access to share IPC$.
[2005/10/11 16:01:14, 0] smbd/service.c:make_connection_snum(577)
Can't become connected user!
[2005/10/11 16:01:14, 3] smbd/error.c:error_packet(129)
error packet at smbd/reply.c(415) cmd=117 (SMBtconX)
NT_STATUS_LOGON_FAILURE
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 685 of length 43
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBulogoffX (pid 7566) conn 0x0
[2005/10/11 16:01:14, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/10/11 16:01:14, 3] smbd/reply.c:reply_ulogoffX(1261)
ulogoffX vuid=102
[2005/10/11 16:01:14, 3] smbd/process.crocess_smb(1091)
Transaction 686 of length 74
[2005/10/11 16:01:14, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:14, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (1002, 1000) - sec_ctx_stack_ndx = 0
[2005/10/11 16:01:14, 3] smbd/trans2.c:call_trans2qfsinfo(1775)
call_trans2qfsinfo: level = 1007
[2005/10/11 16:01:14, 3] lib/sysquotas.c:sys_get_quota(413)
sys_get_vfs_quota() failed for mntpath[/Daten] bdev[/dev/sda3] qtype[2]
id[1002]: Invalid argument
[2005/10/11 16:01:14, 3] lib/sysquotas.c:sys_get_quota(413)
sys_get_vfs_quota() failed for mntpath[/Daten] bdev[/dev/sda3] qtype[4]
id[1000]: Invalid argument
[2005/10/11 16:01:15, 3] smbd/process.crocess_smb(1091)
Transaction 687 of length 92
[2005/10/11 16:01:15, 3] smbd/process.c:switch_message(886)
switch message SMBntcreateX (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:15, 3] smbd/dosmode.c:unix_mode(111)
unix_mode(.) returning 0774
[2005/10/11 16:01:15, 3] smbd/process.crocess_smb(1091)
Transaction 688 of length 76
[2005/10/11 16:01:15, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:15, 3] smbd/trans2.c:call_trans2qfilepathinfo(2363)
call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1006
[2005/10/11 16:01:15, 3] smbd/trans2.c:call_trans2qfilepathinfo(2452)
call_trans2qfilepathinfo . (fnum = 6238) level=1006 call=7 total_data=0
[2005/10/11 16:01:15, 3] smbd/process.crocess_smb(1091)
Transaction 689 of length 88
[2005/10/11 16:01:15, 3] smbd/process.c:switch_message(886)
switch message SMBnttrans (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:15, 3] smbd/error.c:error_packet(105)
error string = Function not implemented
[2005/10/11 16:01:15, 3] smbd/error.c:error_packet(129)
error packet at smbd/nttrans.c(2202) cmd=160 (SMBnttrans)
NT_STATUS_NOT_SUPPORTED
[2005/10/11 16:01:15, 3] smbd/process.crocess_smb(1091)
Transaction 690 of length 45
[2005/10/11 16:01:15, 3] smbd/process.c:switch_message(886)
switch message SMBclose (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:15, 3] smbd/reply.c:reply_close(2870)
close directory fnum=6238
[2005/10/11 16:01:25, 3] smbd/sec_ctx.c:set_sec_ctx(288)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 691 of length 80
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/trans2.c:call_trans2qfilepathinfo(2418)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2005/10/11 16:01:27, 3] smbd/trans2.c:call_trans2qfilepathinfo(2452)
call_trans2qfilepathinfo . (fnum = -1) level=1004 call=5 total_data=0
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 692 of length 90
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBntcreateX (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 693 of length 80
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/trans2.c:call_trans2qfilepathinfo(2418)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1006
[2005/10/11 16:01:27, 3] smbd/trans2.c:call_trans2qfilepathinfo(2452)
call_trans2qfilepathinfo . (fnum = -1) level=1006 call=5 total_data=0
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 694 of length 88
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBnttrans (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/nttrans.c:call_nt_transact_notify_change(1840)
call_nt_transact_notify_change
[2005/10/11 16:01:27, 3] smbd/notify_kernel.c:kernel_register_notify(189)
kernel change notify on . (ntflags=0x17 flags=0x3e) fd=21
[2005/10/11 16:01:27, 3] smbd/nttrans.c:call_nt_transact_notify_change(1852)
call_nt_transact_notify_change: notify change called on directory name = .
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 695 of length 92
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBntcreateX (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 696 of length 88
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBnttrans (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/nttrans.c:call_nt_transact_notify_change(1840)
call_nt_transact_notify_change
[2005/10/11 16:01:27, 3] smbd/notify_kernel.c:kernel_register_notify(189)
kernel change notify on . (ntflags=0x17 flags=0x3e) fd=28
[2005/10/11 16:01:27, 3] smbd/nttrans.c:call_nt_transact_notify_change(1852)
call_nt_transact_notify_change: notify change called on directory name = .
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 697 of length 74
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/trans2.c:call_trans2qfsinfo(1775)
call_trans2qfsinfo: level = 258
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 698 of length 74
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/trans2.c:call_trans2qfsinfo(1775)
call_trans2qfsinfo: level = 261
[2005/10/11 16:01:27, 3] smbd/process.crocess_smb(1091)
Transaction 699 of length 74
[2005/10/11 16:01:27, 3] smbd/process.c:switch_message(886)
switch message SMBtrans2 (pid 7566) conn 0x83ae5c0
[2005/10/11 16:01:27, 3] smbd/trans2.c:call_trans2qfsinfo(1775)
call_trans2qfsinfo: level = 261

 

[rzs0502]

Seems like it basically fails authentication and maps the user to the guest account. (lots of quota and desktop.ini errors as well)
Does the user have a valid domain account?
NT_STATUS_LOGON_FAILURE could also mean his password is wrong.


"If you always do what you've always done, you will always be where you've always been."

 

[rudis]

Hi, the user auth is working now. There is no more guest account in use. Today I'found out that the Problem appears only ath the Windows XP machines, nt4 works very fine. Any ideas?

thx a lot!

 

[rudis]

Now I know the reason....... it's the novell client we use to connect to our Netware 5.1 Server, when I use the Microsoft Client all is fine! :-(