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.crocess_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_browser(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.crocess_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.crocess_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.crocess_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.crocess_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.crocess_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.crocess_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.cush_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.cush_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

Are the users files actually on the server?

It looks like it's not sucessfully creating the user profile on the server

I have tried all combinations. This particular attempt was for a new user so the files should have been created. When I logged the user out after waiting the 15 minutes for it to log on, it did write the profile out for thios user taking another 15 minutes to accomplish this.

The first time it takes a while if you have a lot to copy over. On mine after the users files have been created on the server it only takes a few seconds.

It really depends on how much stuff is in the users profile.

I am testing with new XP machines so not much is being copied. After I have a successful slow logon, the shares show up. but when I try to enter one, my XP machine locks up and never comes back. When I reboot and log back on it still takes over 15 minutes for the profiles to be copied.


I have turned off roaming profiles and still have the unbearable delay. No network traffic shows, and both sides just go to sleep during this delay.