[deviantflux]

we have a network at school running OS X 10.3.9 client and server, and there is one specific classroom where the computers are logging in extremely slowly as compared to the other rooms. The clients are set to login and mount the user home directory using LDAP authentication and are all imaged from one main master image, so the settings should all be the same on the clients.

From the computers that are logging in slowly, the ping times to the server appear to be normal, <.4 ms, and the internet connection is functioning normally. Connecting to the server that appears to be the problem, as it takes roughly 2 minutes to login from the screen where it lists the user names. The computers are on the same subnet and the network connections appear to be fine. Any suggestions as to how to approach the problem or any potential solutions? thanks in advance.

[Jellytussle]

Where is the pause - while you still see the loginwindow progress bar, or on a blank screen before the Finder draws your desktop?

[deviantflux]

the login seems to stall during the display of the progress bar

[Jellytussle]

Bump up the logging on a client to see what loginwindow is waiting for. Often it's DNS related. Edit /etc/syslog.conf on a test client to include the line:

*.* <TAB><TAB>/var/log/system.log

then HUP syslogd or reboot.

Enable ssh (System Preferences:Sharing, check the 'remote login' box) and ssh to your test box as a local admin.

tail the log with

tail -f /var/log/system.log

and then have a network user log in. loginwindow is pretty verbose at this point so you should see where it's waiting.

If it seems directory related, then you can enable DirectoryService logging by sending a -USR1 signal to DirectoryService, as per the Server documentation.

[Big Mac]

Impressive.

[Jellytussle]

Why, thank you

[deviantflux]

//here is the output from tail when a userlogs in, hopefully someone can spot something that can lead to a resolution to the problems. Oh, and Jellytussle, thanks for the help so far. Your console-fu is strong.

Dec 16 14:20:21 localhost /System/Library/CoreServices/RemoteManagement/AppleVNCServer.bundle/Contents/MacOS/AppleVNCServer: ***START RFBSERVER
Dec 16 14:20:33 localhost DirectoryService[314]: DIGEST-MD5 client step 2
Dec 16 14:20:33 localhost DirectoryService[314]: DIGEST-MD5 client step 3
Dec 16 14:20:37 localhost automount[307]: Checking for mounts on /private/Network/Servers/andersen-x-srv.nmusd.us...
Dec 16 14:20:37 localhost automount[307]: Checking path /private/Network/Servers/andersen-x-srv.nmusd.us
Dec 16 14:20:37 localhost automount[307]: Checking for mounts on /private/Network/Servers/andersen-x-srv.nmusd.us...
Dec 16 14:20:37 localhost automount[307]: Checking path /private/Network/Servers/andersen-x-srv.nmusd.us
Dec 16 14:20:37 localhost automount[307]: Mount triggered at /automount/Servers/andersen-x-srv.nmusd.us
Dec 16 14:20:37 localhost automount[307]: Mounting /automount/Servers/andersen-x-srv.nmusd.us...
Dec 16 14:20:37 localhost automount[307]: Checking for mounts on /private/Network/Servers/andersen-x-srv.nmusd.us...
Dec 16 14:20:37 localhost automount[307]: Checking path /private/Network/Servers/andersen-x-srv.nmusd.us
Dec 16 14:20:37 localhost automount[307]: Mount of /automount/Servers/andersen-x-srv.nmusd.us completed successfully.
Dec 16 14:20:37 localhost automount[307]: Mounting offspring at /automount/Servers/andersen-x-srv.nmusd.us/Apps
Dec 16 14:20:37 localhost automount[307]: Mount triggered at /automount/Servers/andersen-x-srv.nmusd.us/Apps
Dec 16 14:20:37 localhost automount[307]: Mounting /automount/Servers/andersen-x-srv.nmusd.us/Apps...
Dec 16 14:20:37 localhost automount[307]: Checking for mounts on /private/Network/Servers/andersen-x-srv.nmusd.us/Apps...
Dec 16 14:20:37 localhost automount[307]: Checking path /private/Network/Servers/andersen-x-srv.nmusd.us/Apps
Dec 16 14:20:37 localhost automount[307]: Creating intermediate directory private...
Dec 16 14:20:37 localhost automount[307]: Creating intermediate directory Network...
Dec 16 14:20:37 localhost automount[307]: Creating intermediate directory Servers...
Dec 16 14:20:37 localhost automount[307]: Creating intermediate directory andersen-x-srv.nmusd.us...
Dec 16 14:20:37 localhost automount[307]: Creating intermediate directory Apps...
Dec 16 14:20:37 localhost automount[307]: Mounting andersen-x-srv.nmusd.us:/Apps on /private/Network/Servers/andersen-x-srv.nmusd.us/Apps
Dec 16 14:20:37 localhost automount[307]: Mount of /automount/Servers/andersen-x-srv.nmusd.us/Apps completed successfully.
Dec 16 14:20:37 localhost automount[307]: Abandoning mount sequence from parent process 307 [outside]...
Dec 16 14:20:37 localhost automount[307]: Mount at /automount/Servers/andersen-x-srv.nmusd.us/Apps returning status = 0
Dec 16 14:20:37 localhost automount[307]: Mount at /automount/Servers/andersen-x-srv.nmusd.us/Apps returning final status = 0
Dec 16 14:20:37 localhost automount[307]: Abandoning mount sequence from parent process 307 [inside]...
Dec 16 14:20:37 localhost automount[307]: Mount at /automount/Servers/andersen-x-srv.nmusd.us returning status = 0
Dec 16 14:20:37 localhost automount[307]: Mount at /automount/Servers/andersen-x-srv.nmusd.us returning final status = 0
Dec 16 14:20:37 localhost automount[460]: Attempting to quietly automount url: URL = afp://;AUTH=NO%20USER%20AUTHENT@andersen-x-srv.nmusd.us.nmusd.us/Apps, server = andersen-x-srv.nmusd.us, mountdir = /private/Network/Servers/andersen-x-srv.nmusd.us/Apps options = 0x00000054 uid = 1052
Dec 16 14:20:37 localhost automount[460]: afp_LLMount: inOptions = 54, mntFlags = 500000, sveFlags = 110
Dec 16 14:20:37 localhost automount[460]: Creating a SharedVolumeEnumerator with TUAMHandler
Dec 16 14:20:37 localhost automount[460]: SharedVolumeEnumerator::Init
Dec 16 14:20:38 localhost automount[460]: DNSAddressResolver: DNS address andersen-x-srv.nmusd.us.nmusd.us
Dec 16 14:20:38 localhost automount[460]: DNSAddressResolver:Resolve DNS address andersen-x-srv.nmusd.us.nmusd.us
Dec 16 14:20:38 localhost automount[307]: handle_deferred_requests: completing forked mount.
Dec 16 14:20:38 localhost automount[460]: DNSAddressResolver:Resolve getaddrinfo failed for andersen-x-srv.nmusd.us.nmusd.us error = 7 (No address associated with nodename) mapped to -1069
Dec 16 14:20:38 localhost automount[460]: SharedVolumeEnumerator::Init Connect returns -1069
Dec 16 14:20:38 localhost automount[460]: Deleting a TUAMHandler
Dec 16 14:20:38 localhost automount[460]: Recieved status = 65 from forked MountServerURL
Dec 16 14:20:38 localhost automount[460]: completeMount: v = 0x00304670, status = 0x00000041
Dec 16 14:20:38 localhost automount[460]: Can't mount andersen-x-srv.nmusd.us:/Apps on /private/Network/Servers/andersen-x-srv.nmusd.us/Apps: No route to host (65)
Dec 16 14:20:38 localhost automount[460]: Can't mount andersen-x-srv.nmusd.us:/Apps on /private/Network/Servers/andersen-x-srv.nmusd.us/Apps: No route to host (65)
Dec 16 14:20:38 localhost automount[460]: Attempt to mount /automount/Servers/andersen-x-srv.nmusd.us/Apps returned 65 (No route to host)
Dec 16 14:20:38 localhost automount[460]: Attempt to mount /automount/Servers/andersen-x-srv.nmusd.us/Apps returned 65 (No route to host)
Dec 16 14:20:38 localhost automount[460]: Mount at /automount/Servers/andersen-x-srv.nmusd.us/Apps returning final status = 65
Dec 16 14:20:38 localhost automount[460]: Mount at /automount/Servers/andersen-x-srv.nmusd.us returning final status = 0
Dec 16 14:20:38 localhost automount[307]: handle_deferred_requests: completing forked mount.
Dec 16 14:20:38 localhost automount[307]: completeMountInProgressBy:460, status 0x00004100
Dec 16 14:20:38 localhost automount[307]: completeMountInProgressBy:460, transaction id = 0x33d5b02c
Dec 16 14:20:38 localhost automount[307]: completeMount: v = 0x00304670, status = 0x00000041
Dec 16 14:20:38 localhost automount[307]: Can't mount andersen-x-srv.nmusd.us:/Apps on /private/Network/Servers/andersen-x-srv.nmusd.us/Apps: No route to host (65)
Dec 16 14:20:38 localhost automount[307]: Can't mount andersen-x-srv.nmusd.us:/Apps on /private/Network/Servers/andersen-x-srv.nmusd.us/Apps: No route to host (65)
Dec 16 14:20:38 localhost DirectoryService[314]: DIGEST-MD5 client step 2
Dec 16 14:20:38 localhost DirectoryService[314]: DIGEST-MD5 client step 3
Dec 16 14:20:38 localhost com.apple.SecurityServer: authinternal authenticated user christensennancy (uid 1052) for right system.login.console.
Dec 16 14:20:39 localhost /System/Library/Frameworks/Kerberos.framework/Servers/CCacheServer.app/Contents/MacOS/CCacheServer: Starting up.
Dec 16 14:20:39 localhost /System/Library/Frameworks/Kerberos.framework/Servers/CCacheServer.app/Contents/MacOS/CCacheServer: Starting up.
Dec 16 14:20:39 localhost com.apple.SecurityServer: Succeeded authorizing right system.login.console by process /System/Library/CoreServices/loginwindow.app for authorization created by /System/Library/CoreServices/loginwindow.app.
Dec 16 14:20:39 localhost loginwindow[448]: afp_LLMountAuth: args are: inTargetURL = afp://andersen-x-srv.nmusd.us/HOME inMountDir = /private/Network/Servers/andersen-x-srv.nmusd.us/HOME/ inMaxPath = 1024 outMountPoint = ??? ?
Dec 16 14:20:39 localhost loginwindow[448]: Creating a SharedVolumeEnumerator with TUAMHandler
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::Init
Dec 16 14:20:39 localhost loginwindow[448]: DNSAddressResolver: DNS address andersen-x-srv.nmusd.us
Dec 16 14:20:39 localhost loginwindow[448]: DNSAddressResolver:Resolve DNS address andersen-x-srv.nmusd.us
Dec 16 14:20:39 localhost loginwindow[448]: DNSAddressResolver:Resolve found IPv4 0xc0a80412, port 548
Dec 16 14:20:39 localhost loginwindow[448]: DNSAddressResolver:Resolve address verify succeeded
Dec 16 14:20:39 localhost loginwindow[448]: GetStatus: afp_socket worked
Dec 16 14:20:39 localhost loginwindow[448]: GetStatus: Connecting to IPv4 port 548, on c0a80412
Dec 16 14:20:39 localhost loginwindow[448]: GetStatus worked: afpResult 0x0
Dec 16 14:20:39 localhost loginwindow[448]: TUAMHandler::Initalize
Dec 16 14:20:39 localhost loginwindow[448]: TUAMHandler::Initalize TUAMHandler = 49e2c0 fCurrentHandler = 49e2c0
Dec 16 14:20:39 localhost loginwindow[448]: TUAMHandler Username is 16:^Pchristensennancy
Dec 16 14:20:39 localhost loginwindow[448]: TUAMHandler::BuildUAMList
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: couldn't find the localized string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: Using the default string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: couldn't find the localized string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: Using the default string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: couldn't find the localized string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: Using the default string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: couldn't find the localized string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: Using the default string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: couldn't find the localized string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: Using the default string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: couldn't find the localized string
Dec 16 14:20:39 localhost loginwindow[448]: GetPascalCFString: Using the default string
Dec 16 14:20:39 localhost loginwindow[448]: Looking at ^MClient Krb v2
Dec 16 14:20:39 localhost loginwindow[448]: Looking at ^DDHX2
Dec 16 14:20:39 localhost loginwindow[448]: Adding ^DDHX2
Dec 16 14:20:39 localhost loginwindow[448]: Looking at DHCAST128
Dec 16 14:20:39 localhost loginwindow[448]: Adding DHCAST128
Dec 16 14:20:39 localhost loginwindow[448]: Looking at ^V2-Way Randnum exchange
Dec 16 14:20:39 localhost loginwindow[448]: Looking at ^PCleartxt Passwrd
Dec 16 14:20:39 localhost loginwindow[448]: Adding ^PCleartxt Passwrd
Dec 16 14:20:39 localhost loginwindow[448]: Looking at ^ONo User Authent
Dec 16 14:20:39 localhost loginwindow[448]: Adding ^ONo User Authent
Dec 16 14:20:39 localhost loginwindow[448]: ^DDHX2
Dec 16 14:20:39 localhost loginwindow[448]: DHCAST128
Dec 16 14:20:39 localhost loginwindow[448]: ^PCleartxt Passwrd
Dec 16 14:20:39 localhost loginwindow[448]: ^ONo User Authent
Dec 16 14:20:39 localhost loginwindow[448]: TUAMHandler::ChooseBestUAM
Dec 16 14:20:39 localhost loginwindow[448]: Choosing ^DDHX2
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::Count
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::FetchVolumeList Fetching the volume list fSessionRef = 0
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::FetchVolumeList checking to see if we can upgrade
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::FetchVolumeList automounted is True
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::FetchVolumeList uam type is 8
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::FetchVolumeList server supports Kerberos is false
Dec 16 14:20:39 localhost loginwindow[448]: KerberosTGTPresent: UpdateCredentialsCache returns 0
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::FetchVolumeList TGT present is True
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::FetchVolumeList Username/Password present is True
Dec 16 14:20:39 localhost loginwindow[448]: SharedVolumeEnumerator::FetchVolumeList HI Allowed = 0 UAM says 0
Dec 16 14:20:39 localhost loginwindow[448]: TUAMHandler::Login
Dec 16 14:20:39 localhost loginwindow[448]: TUAMProxy::OpenUAMCode: ^DDHX2
Dec 16 14:20:39 localhost loginwindow[448]: UAMGetClientInfo theHandler = 49e2c0
Dec 16 14:20:39 localhost loginwindow[448]: Login: ^DDHX2
Dec 16 14:20:39 localhost loginwindow[448]: SetupLoginPacket: inAFPVersion = 7
Dec 16 14:20:39 localhost loginwindow[448]: SetupLoginPacket: using login extended
Dec 16 14:20:39 localhost loginwindow[448]: UAMOpenSession: theHandler = 49e2c0
Dec 16 14:20:39 localhost loginwindow[448]: OpenSession worked: afpResult 0x0
Dec 16 14:20:39 localhost loginwindow[448]: Option Type: 0x0
Dec 16 14:20:39 localhost loginwindow[448]: Option Length: 0x4
Dec 16 14:20:39 localhost loginwindow[448]: Option Value: 0x40000
Dec 16 14:20:39 localhost loginwindow[448]: TUAMProxy::CloseUAMCode: ^DDHX2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: 9 volumes total
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on Groups volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=03 owner=03 group=07 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on Public volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=03 owner=03 group=07 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on Users volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=03 owner=03 group=07 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on Images volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=03 owner=03 group=03 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on NetBootSP0 volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=03 owner=03 group=07 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on NetBootClients0 volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=03 owner=03 group=07 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on Apps volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=07 owner=07 group=07 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on HOME volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=03 owner=03 group=07 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: inMaxPath = 0 outMounPath = (null) mntFlags = 0000
Dec 16 14:20:40 localhost loginwindow[448]: IsVolMounted: error = 2
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: Checking Access on christensennancy volflags = 00
Dec 16 14:20:40 localhost loginwindow[448]: Privs are user=87 owner=03 group=07 world=07
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: User has access
Dec 16 14:20:40 localhost loginwindow[448]: FetchVolumeList: 9 Volumes Available
Dec 16 14:20:40 localhost loginwindow[448]: outMountPoint = /private/Network/Servers/andersen-x-srv.nmusd.us/HOME

[deviantflux]

Dec 16 14:20:40 localhost loginwindow[448]: SharedVolumeEnumerator::Mount: Mount encoding is 0
Dec 16 14:20:40 localhost loginwindow[448]: SharedVolumeEnumerator::Mount: fOrigServerName is ^Wandersen-x-srv.nmusd.us
Dec 16 14:20:40 localhost loginwindow[448]: SharedVolumeEnumerator::Mount: Calling DoMount: ^DHOME on /private/Network/Servers/andersen-x-srv.nmusd.us/HOME with Flags = 500000 and altFlags = 60
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: mountflags = 500000
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: username = ::
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: Servername = :andersen-x-srv.nmusd.us:
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: dns name = :andersen-x-srv.nmusd.us:
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: struct flags = 6
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: permissions will NOT be mapped
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: uamType = 8 uid = 1052 gid = 0 volumeID = 1 server flags = 83fb
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: afpversion = 7, serversig 3139322e3136382e342e31380069672d
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: openSessionOptionsLen = 6 reconnectMaxAttempts = 12 reconnectInterval = 10
Dec 16 14:20:40 localhost loginwindow[448]: MountArgs: ASPSndBufSize = 0 ASPRcvBufSize = 0 ASPActiveTimeOut = 0 ASPIdleTimeOut = 0 ASPSendTickleInterval = 0 WANThreshold = 0 WANQuantumSize = 0
Dec 16 14:20:40 localhost loginwindow[448]: DoMount: Calling Mount() for /private/Network/Servers/andersen-x-srv.nmusd.us/HOME
Dec 16 14:20:40 localhost loginwindow[448]: CreateMarkerFile: Created the .autodiskmounted file in the mountpoint & made it immutable err = 0
Dec 16 14:20:40 localhost loginwindow[448]: DoMount: Providing the Daylight Savings Time Table
Dec 16 14:20:40 localhost kernel: AFP_VFS afpfs_mount: /private/Network/Servers/andersen-x-srv.nmusd.us/HOME, pid 448
Dec 16 14:20:40 localhost kernel: AFP_VFS afpfs_mount: /private/Network/Servers/andersen-x-srv.nmusd.us/HOME, pid 448
Dec 16 14:20:40 localhost loginwindow[448]: DoMount: resetting the immutable state of marker file
Dec 16 14:20:40 localhost loginwindow[448]: DoMount: Notifying AutoDiskMounter
Dec 16 14:20:40 localhost loginwindow[448]: CheckAFPRunning: could not open /var/run/check_afp.pid errno = 2
Dec 16 14:20:40 localhost loginwindow[448]: DoMount: CheckAFPRunning returns false
Dec 16 14:20:40 localhost loginwindow[448]: DoMount: launching check_afp.app
Dec 16 14:20:40 localhost loginwindow[448]: Mount succeeded
Dec 16 14:20:40 localhost loginwindow[448]: Deleting a TUAMHandler
Dec 16 14:20:40 localhost loginwindow[448]: TSocketAFPSession: Disposing of a TSocketAFPSession 4a0ba0
Dec 16 14:20:40 localhost loginwindow[448]: afp_LLMountAuth: outMountPoint = /private/Network/Servers/andersen-x-srv.nmusd.us/HOME returning 0
Dec 16 14:20:40 localhost automount[307]: the console user is logged in
Dec 16 14:20:40 localhost automount[302]: the console user is logged in
Dec 16 14:21:28 localhost automount[302]: NSLVnode.processAddResult: Adding neighborhood NHHS-INST...
Dec 16 14:21:28 localhost automount[302]: NSLVnode.processAddResult: Adding neighborhood N-MUSD...
Dec 16 14:21:28 localhost automount[302]: NSLVnode.processAddResult: Adding neighborhood ADMIN...
Dec 16 14:21:28 localhost automount[302]: markDirectoryChanged: new mtime for '/Network' is 1134771688.160247...
Dec 16 14:21:28 localhost automount[302]: Requesting Finder notification for changes to '/Network'...
Dec 16 14:23:05 localhost com.apple.SecurityServer: Succeeded authorizing right system.login.done by process /System/Library/CoreServices/loginwindow.app for authorization created by /System/Library/CoreServices/loginwindow.app.

[Watson]

Are you sure that the DNS is configured properly? Maybe somebody hardcoded the IP to the server in your /etc/hosts file or somewhere else?

Do a "dig andersen-x-srv.nmusd.us.nmusd.us" and check the answer section for the IP address that the computer thinks belongs to the server.

Then do a reverse lookup on the IP address:
dig -x THE.IP.ADDRESS

You may want to do this from both "working" clients and "broken" clients to see if this is the problem.

Is there a problem with the Firewall settings on the slow machines?

[deviantflux]

thanks for the advice watson, i tried dig and looked in the hosts file.

all appears to be well, the server address isn't hard coded in the hosts file.

when performing dig, the answer section showsthe correct IP address for the server and the reverse lookup yields a proper result as well.

i am fairly certain it is not a firewall issue, the firewall is turned off on all machines.

[deviantflux]

i tailed the syslog of a normally operating computer and found that the logs were basically identical, except for a small portion at the end, where the slow computer has these additional lines:

Dec 16 14:20:40 localhost automount[302]: the console user is logged in
Dec 16 14:21:28 localhost automount[302]: NSLVnode.processAddResult: Adding neighborhood NHHS-INST...
Dec 16 14:21:28 localhost automount[302]: NSLVnode.processAddResult: Adding neighborhood N-MUSD...
Dec 16 14:21:28 localhost automount[302]: NSLVnode.processAddResult: Adding neighborhood ADMIN...
Dec 16 14:21:28 localhost automount[302]: markDirectoryChanged: new mtime for '/Network' is 1134771688.160247...
Dec 16 14:21:28 localhost automount[302]: Requesting Finder notification for changes to '/Network'...

is there a way to stop the computer from attempting to add these neighborhoods, assuming that this is what is causing the delay?

[asdasd]

The difference is here

"Dec 16 14:20:40 localhost automount[302]: the console user is logged in
Dec 16 14:21:28 localhost automount[302]: NSLVnode.processAddResult: Adding neighborhood NHHS-INST..."

thats 48 seconds between messages. All the other messages are within a second of each other. The messages are not all that useful as they are coming fairly quickly, with the exception of that gap.

This is an AFP mount?

[asdasd]

Oh and there seems to be two automounts. 307 and 302.Weird

Dec 16 14:20:40 localhost automount[307]: the console user is logged in
Dec 16 14:20:40 localhost automount[302]: the console user is logged in

[Watson]

Here is another suggestion, then:
from working and nonworking computers try to get an MD5 digest of the automount utility:

md5 /usr/sbin/automount

If the digests match, then it is not a problem with automount, but with some configuration specific to the client computers. If they do not match however, check the build numbers of both working systems and non-working systems in About This Mac. You should also probably check to see if the same updates have been applied to all of the systems. Barring an issue there, there might be an issue in the way in which the clients have been updated (delta vs. combo updates).

Keep us posted.

[Jellytussle]

Originally Posted by asdasd

The difference is here

"Dec 16 14:20:40 localhost automount[302]: the console user is logged in
Dec 16 14:21:28 localhost automount[302]: NSLVnode.processAddResult: Adding neighborhood NHHS-INST..."

thats 48 seconds between messages. All the other messages are within a second of each other. The messages are not all that useful as they are coming fairly quickly, with the exception of that gap.

This is an AFP mount?

Those are Windows workgroups. Try unchecking the SMB service in Directory Access, and see if they go away.