Samba performance problems
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_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.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 |
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. |
All times are GMT -5. The time now is 02:20 AM. |