[Samba] Slow performance
Pat Schlehuber
pat at schlehuber.net
Sun Dec 29 17:24:00 GMT 2002
I have been fighting this problem for months now. My performance under Samba
and XP has ben umbearable. The scenario is running Samba 2.2.7a under SuSE
8.0 as a PDC. The XP machine logs onto Samba and the roaming profiles are
downloaded then stops for 15 minutes or so. The network is verified 100Meg
full-duplex, FTP transfers between the machines are robust and so no
problems in connectivity. Any idea would be greatly appreciated. I have
tried different versions of Samba to no avail and have this problem on 2
different XP machines.
I have turned loglevel 3 and see the following during this time:
The samba log for the NMBD shows (Shows a 5-minute lag with no activty):
[2002/12/28 14:11:03, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_processlogon.c:process_logon_packet(253)
process_logon_packet: SAMLOGON request from MOE(192.168.0.3) for ,
returning logon svr \\LINUXNB domain HEYWORTH code 13 token=ffff
[2002/12/28 14:11:23, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_sendannounce.c:send_local_master_announcement(175)
send_local_master_announcement: type c9b0b for name LINUXNB on subnet
192.168.0.2 for workgroup HEYWORTH
[2002/12/28 14:11:23, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_sendannounce.c:send_workgroup_announcement(194)
send_workgroup_announcement: on subnet 192.168.0.2 for workgroup HEYWORTH
[2002/12/28 14:16:23, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_sendannounce.c:send_local_master_announcement(175)
send_local_master_announcement: type c9b0b for name LINUXNB on subnet
192.168.0.2 for workgroup HEYWORTH
[2002/12/28 14:16:23, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_sendannounce.c:send_workgroup_announcement(194)
send_workgroup_announcement: on subnet 192.168.0.2 for workgroup HEYWORTH
[2002/12/28 14:16:54, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_winsserver.c:wins_process_name_query_request(1354)
wins_process_name_query: name query for name *<1b> from IP 192.168.0.2
[2002/12/28 14:17:19, 2, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_browsesync.c:announce_local_master_browser_to_domain_master_browse
r(118)
announce_local_master_browser_to_domain_master_browser:
We are both a domain and a local master browser for workgroup HEYWORTH.
Do not announce to ourselves.
[2002/12/28 14:17:19, 2, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_browsesync.c:sync_with_dmb(157)
sync_with_dmb:
Initiating sync with domain master browser LINUXNB<20> at IP 192.168.0.2
for workgroup HEYWORTH
[2002/12/28 14:18:18, 1, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_processlogon.c:process_logon_packet(69)
process_logon_packet: Logon from 192.168.0.3: code = 0x12
[2002/12/28 14:18:18, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_processlogon.c:process_logon_packet(210)
process_logon_packet: SAMLOGON sidsize 0, len = 53
[2002/12/28 14:18:18, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_processlogon.c:process_logon_packet(217)
process_logon_packet: len = 53 PTR_DIFF(q, buf) = 45
[2002/12/28 14:18:18, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_processlogon.c:process_logon_packet(237)
process_logon_packet: SAMLOGON sidsize 0 ntv 11
[2002/12/28 14:18:18, 3, pid=1570, effective(0, 0), real(0, 0)]
nmbd/nmbd_processlogon.c:process_logon_packet(246)
process_logon_packet: SAMLOGON user
The samba log for the XP machine shows:
[2002/12/28 14:08:29, 1, pid=1584, effective(0, 0), real(0, 0)]
smbd/service.c:close_cnum(677)
moe (192.168.0.3) closed connection to service netlogon
[2002/12/28 14:08:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/connection.c:yield_connection(48)
Yielding connection to netlogon
[2002/12/28 14:08:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:09:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:10:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:11:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:12:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:14:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:15:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:16:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:17:29, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/process.c:process_smb(878)
Transaction 105 of length 73
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/process.c:switch_message(685)
switch message SMBtconX (pid 1584)
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
lib/access.c:check_access(318)
check_access: no hostnames in host allow/deny list.
[2002/12/28 14:18:17, 2, pid=1584, effective(0, 0), real(0, 0)]
lib/access.c:check_access(329)
Allowed connection from (192.168.0.3)
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/password.c:authorise_login(736)
authorise_login: ACCEPTED: validated uid ok as non-guest (user=pat)
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/service.c:make_connection(474)
Forced group heysamba
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/service.c:make_connection(487)
Connect path is /home/samba/netlogon
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/sec_ctx.c:push_sec_ctx(297)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2002/12/28 14:18:17, 3, pid=1584, effective(0, 0), real(0, 0)]
smbd/uid.c:push_conn_ctx(286)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
I turned logging on the XP machine and in the USERENV file I see:
USERENV(1f4.1f8) 14:09:09:046 CheckRoamingShareOwnership: owner is admin
USERENV(1f4.1f8) 14:09:09:046 IsCentralProfileReachable: Testing
<F:\pat\ntuser.man>
USERENV(1f4.1f8) 14:09:09:062 IsCentralProfileReachable: Profile is not
reachable, error = 2
USERENV(1f4.1f8) 14:09:09:062 IsCentralProfileReachable: Testing
<F:\pat\ntuser.dat>
USERENV(1f4.1f8) 14:09:09:062 IsCentralProfileReachable: Profile is not
reachable, error = 2
USERENV(1f4.1f8) 14:09:09:062 IsCentralProfileReachable: Ok to create a
user profile.
USERENV(1f4.1f8) 14:09:09:062 RestoreUserProfile: Central Profile is
reachable
USERENV(1f4.1f8) 14:09:09:062 RestoreUserProfile: Central Profile is
roaming
USERENV(1f4.1f8) 14:09:09:062 RestoreUserProfile: Profile path = <F:\pat>
USERENV(1f4.1f8) 14:09:09:062 ExtractProfileFromBackup: Failed to open key
Software\Microsoft\Windows
NT\CurrentVersion\ProfileList\S-1-5-21-3139752284-1139265356-4206900553-2024
with error 2
USERENV(1f4.1f8) 14:09:09:062 ExtractProfileFromBackup: Couldn't open
backup profile key. Error = 2
USERENV(1f4.1f8) 14:09:09:062 GetUserGuid: Failed to get user guid with
1355.
USERENV(1f4.1f8) 14:09:09:062 PatchNewProfileIfRequred: No OldSidString
found
USERENV(1f4.1f8) 14:09:09:062 CreateLocalProfileKey: Not setting additional
Security
USERENV(1f4.1f8) 14:09:09:062 CreateLocalProfileImage: One way or another
we haven't got an existing local profile, try and create one
USERENV(1f4.1f8) 14:09:09:062 CreateSecureDirectory: Entering with
<C:\Documents and Settings\pat>
USERENV(1f4.1f8) 14:09:09:093 CreateSecureDirectory: Created the directory
<C:\Documents and Settings\pat>
USERENV(1f4.1f8) 14:09:09:093 ComputeLocalProfileName: generated the profile
directory <C:\Documents and Settings\pat>
USERENV(1f4.1f8) 14:09:09:093 Creating Local Profile
USERENV(1f4.1f8) 14:09:09:093 Local profile name is <C:\Documents and
Settings\pat>
USERENV(1f4.1f8) 14:09:09:093 RestoreUserProfile: Working with a new user.
Go straight to issuing a default profile.
USERENV(1f4.1f8) 14:09:09:093 RestoreUserProfile: Issuing default profile
USERENV(1f4.1f8) 14:09:09:093 CheckNetDefaultProfile: Entering, lpNetPath =
<\\LINUXNB\netlogon\Default User>
USERENV(428.6e0) 14:10:47:328 LibMain: Process Name:
C:\WINDOWS\system32\spoolsv.exe
USERENV(1f4.1f8) 14:20:16:906 CheckNetDefaultProfile: setting default
profile to NULL
USERENV(1f4.1f8) 14:20:16:906 CheckNetDefaultProfile: Removing local copy
of network default user profile.
USERENV(1f4.1f8) 14:20:16:906 Delnode_Recurse: Entering, lpDir =
<\\?\C:\Documents and Settings\Default User (Network)>
USERENV(1f4.1f8) 14:20:16:906 CheckNetDefaultProfile: Leaving with a value
of 0.
USERENV(1f4.1f8) 14:20:16:906 IssueDefaultProfile: Entering.
lpDefaultProfile = <C:\Documents and Settings\Default User.WINDOWS>
lpLocalProfile = <C:\Documents and Settings\pat>
USERENV(1f4.1f8) 14:20:16:906 CopyProfileDirectoryEx: Entering, lpSourceDir
= <C:\Documents and Settings\Default User.WINDOWS>, lpDestinationDir =
<C:\Documents and Settings\pat>, dwFlags = 0xc8103
USERENV(1f4.1f8) 14:20:16:921 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Application Data\Microsoft\Internet Explorer\
to the list of directories
USERENV(1f4.1f8) 14:20:16:921 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Application
Data\Microsoft\SystemCertificates\My\Certificates\ to the list of
directories
USERENV(1f4.1f8) 14:20:16:921 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Application
Data\Microsoft\SystemCertificates\My\CRLs\ to the list of directories
USERENV(1f4.1f8) 14:20:16:921 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Application
Data\Microsoft\SystemCertificates\My\CTLs\ to the list of directories
USERENV(1f4.1f8) 14:20:16:937 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Application
Data\Microsoft\SystemCertificates\My\ to the list of directories
USERENV(1f4.1f8) 14:20:16:937 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Application Data\Microsoft\SystemCertificates\
to the list of directories
USERENV(1f4.1f8) 14:20:16:937 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Application Data\Microsoft\ to the list of
directories
USERENV(1f4.1f8) 14:20:16:937 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Application Data\ to the list of directories
USERENV(1f4.1f8) 14:20:16:937 RecurseDirectory: Adding C:\Documents and
Settings\Default User.WINDOWS\Cookies\ to the list of directories
More information about the samba
mailing list