Infamous error 118 is back

Question

Infamous error 118 is back

This morning a user got her laptop back from repair. Since last week she was using a borrow PMG4 (portable OD account) waiting for a new hardrive.

Alas, she cannot log in anymore. Password is OK but her laptop doesn't achieve to mount her homedir (AFP on 10.5.5 Server Xserve). Worst : she cannot log in from any computer of our system !
Even the PMG4 she was using a few minutes before !

The pop-up message is (translated from french, sorry) "Session opening failed because of an error".
No error message on server side. Client side, we found this (we hadn't see it for month) :

+Oct 10 16:19:07 hostname authorizationhost[81495]: ERROR | -[HomeDirMounter mountNetworkHomeWithURL:attributes:dirPath:username:] | PremountHomeDirectoryWithAuthentication( url=afp://serverfqdn/test, homedir=/Network/Servers/serverfqdn/Volumes/Mountpoint/test/toto, name=toto ) returned 118+

Nothing has changed on ther server ! The path in WGM is correct.

And the worst is that *we cannot add any new user*... existing users can still connect (except the one above), new user get systematically the "error 118", whatever the machine or the Mac OS X version (Tiger or Leopard). I've been adding users until yesterday without problem.

I've checked all the points in LDAP attributes, all seems to be correct. It's a nightmare. The same that has lasted for months and disappeared one day. It's back... 118

Please help if you can, before users get upset and kill me.

profile erased once more...

Posted on Oct 10, 2008 7:46 AM

Reply

Answer 1

Oct 13, 2008 5:59 AM in response to Nicolas Soriano

Well I managed to enable afp debugging on client side (btw the commands in Apple "Server Administration" p186 don't work ).

This is what I get in debug.log (if someone can find the problem in this, I will appreciate it )

the following is repeated 6 times and then ends with error 118

Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: DNSAddressResolver::DNSAddressResolver - _CFNetServiceCreateFromURL failed (maybe its a DNS name?)
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: DNSAddressResolver: DNS address serverFQDName
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: CheckPrefs: Host Prefs
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp reconnectallow: Yes
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp mountdefaultFlags: 0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp reconnectinterval: 10
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp reconnect_maxtime: 600 secs
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp debuglevel: 6
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp debugsyslog: Yes
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp host_prefsversion: 5
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp activetimeout: 0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp idletimeout: 0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp wanthreshold: 0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp wanquantum: 0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp no_volChangecaching: 1
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp nokQueues: 0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp nocaseSensitiveVols: 0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp_minFileCache: 5
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp_maxFileCache: 60
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp_minDirCache: 5
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp_maxDirCache: 60
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp softmountvols: No
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp softautomountvols: No
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp_forceKerberosOnly: No
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: afp immutabledirs: Yes
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: TUAMHandler::TUAMHandler (1) TUAMHandler = 806200 fCurrentHandler = 806200 flags = 1
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Creating a SharedVolumeEnumerator with TUAMHandler
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: DNSAddressResolver:Resolve DNS address serverFQDName
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: DNSAddressResolver:Resolve found IPv4 0x81149b19, port 548
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: DNSAddressResolver:Resolve address verify succeeded
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetStatus: afp_socket worked
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetStatus: Connecting to IPv4 port 548, on 81149b19
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetStatus worked: afpResult 0x0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: SrvrSigMatch: vfs <afp_3xMLte0003KD0000oM0000VU-1.2e00002a> does not match the server signature
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: TUAMHandler::Initalize flags are 1 username is 0:
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: TUAMHandler Username is 0:
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: TUAMHandler::BuildUAMList noPrefs = 1 low level only = 1
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: couldn't find the localized string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: Using the default string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: couldn't find the localized string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: Using the default string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: couldn't find the localized string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: Using the default string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: couldn't find the localized string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: Using the default string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: couldn't find the localized string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: Using the default string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: couldn't find the localized string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: Using the default string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: couldn't find the localized string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: Using the default string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: couldn't find the localized string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetPascalCFString: Using the default string
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: GetUAMsInDir: cannot find /Library/Filesystems/AppleShare
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Looking at ^MClient Krb v2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Adding ^MClient Krb v2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Looking at ^DDHX2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Adding ^DDHX2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Looking at DHCAST128
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Adding DHCAST128
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Looking at ^V2-Way Randnum exchange
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Looking at ^EMS3.0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Looking at ^EMS2.0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Looking at ^PCleartxt Passwrd
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Looking at ^ONo User Authent
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^MClient Krb v2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^DDHX2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: DHCAST128
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: TUAMHandler::SetUAMType by uamName ^ONO USER AUTHENT
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: TUAMHandler::SetUAMType inUAMName = ^ONO USER AUTHENT
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: did not find the UAM
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Installed UAMs
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^MClient Krb v2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^DDHX2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: DHCAST128
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^V2-Way Randnum exchange
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^EMS3.0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^EMS2.0
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^PCleartxt Passwrd
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^ONo User Authent
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Available UAMs
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^MClient Krb v2
Oct 13 14:36:46 clientname SecurityAgent[94420]: MechanismInvoke 0x101680 retainCount 1
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ^DDHX2
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: DHCAST128
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: ERROR: AFP_OpenSession - CreateSVEFromURL failed -5002
Oct 13 14:36:46 clientname /usr/libexec/mount_url[94432]: Deleting a TUAMHandler
Oct 13 14:36:46 clientname authorizationhost[94419]: ERROR | -[HomeDirMounter mountNetworkHomeWithURL:attributes:dirPath:username:] | PremountHomeDirectoryWithAuthentication( url=afp://serverFQDName/test, homedir=/Network/Servers/serverFQDName/Volumes/Projets/test/toto, name=toto ) returned 118

Reply

Answer 2

Oct 15, 2008 5:38 AM in response to Nicolas Soriano

After 4 days investigating the problem above, I have temporarily fixed it (but not solved) by using NFS automounted HomeDir instead of AFP.

I don't like it, but users don't see the difference...

Speaking of investigating, the debug log shows a problem :

+SrvrSigMatch: vfs <afp_3xMLte0003KD0000oM0000VU-1.2e00002a> does not match the server signature+

I'm unable to understand what it means...

But the following lines are not as they should :

+Oct 13 14:36:46 clientname /usr/libexec/mount_url\[98936\]: TUAMHandler::Initalize flags are 1 username is 0:+
+Oct 13 14:36:46 clientname /usr/libexec/mount_url\[98936\]: TUAMHandler Username is 0:+

when it should be something like :

+Oct 14 09:38:54 clientname authorizationhost\[98936\]: TUAMHandler::Initalize flags are 1 username is 7:nicolas+
+Oct 14 09:38:54 clientname authorizationhost\[98936\]: TUAMHandler Username is 7:nicolas+

Does anyone know what this SrvrSigMatch is ? That would be helpfull to understand the problem...

Reply

Answer 3

Dec 29, 2008 4:37 PM in response to Nicolas Soriano

I discovered that my "118" error was caused by a simple mistake I made.
In Work Group Manager, I set the home directory share point URL to
"afp://server.domain/home/student" and the path to home folder to "test1".
This is incorrect because my share point is "afp://server.domain/home".
using the correct share point and a home folder path of "students/test1"
made the "118" go away. Note that the "Full Path" is the same in both cases.

I made the mistake, but Apple is responsible for making bad design decisions
and missing documentation.
Why does WGM let me enter an invalid share point?
Why is the logon screen error message "content free"?
Why is the client log message"returning 118" instead of an explanation?
Why is "118" undocumented?
If it is documented, why can no one find the document?
...

Reply