[OpenAFS] AFS service may not have started
Michal Rybarik
michal.rybarik@ecce.sk
Wed, 02 Jul 2008 21:25:20 +0200
Hello,
>>
>> Tokens held by the Cache Manager:
>>
>> pioctl CreateFile(\\afs\all\_._AFS_IOCTL_._) failed: 0x7B
>> [The filename, directory name, or volume label syntax is incorr
>> ]
>> pioctl Explorer logon user: [pichal]
>> pioctl WNetAddConnection2(\\afs,pichal) failed: 0x52E
>> pioctl WNetAddConnection2(\\afs\all,pichal) failed: 0x52E
>> pioctl SamCompatible logon user: [MONEY\pichal]
>> pioctl WNetAddConnection2(\\afs,MONEY\pichal) failed: 0x52E
>> pioctl WNetAddConnection2(\\afs\all,MONEY\pichal) failed: 0x52E
>> AFS device may not have started
>>
> Has the service started? What are the contents of the afsd_init.log
> file?
>
> Is "AFS" registered as a service name according to "nbtstat -n"?
>
> Note that 0x52E is Logon Failure. unknown user name or bad password.
> How are you logging on to the workstation? Are you using a method that
> does not make use of a password? Is NTLM disabled on the machine?
Contents of afsd_init.log and "nbtstat -n" is below, I think everything
seems to be OK. Service is started and seems to be running normally. We
also tried to start/stop service, uninstall & reinstall AFS, but nothing
helped. We had the same issue on another XP machine few months ago,
after a weeks of fixing, we reinstalled the whole machine and then it
started work... On this machine it is the same, AFS has been working
well on it before, but one day it stopped suddenly and we can't find a
way to bring it back. There are also around 5 other WinXP clients on the
same network and also some clients running on Linux (server is Linux
also), and they are all working well. Login & password is 100% OK, we
tried also another accounts which works on other AFS clients, I think
that the problem is with this
pioctl CreateFile(\\afs\all\_._AFS_IOCTL_._) failed: 0x7B
and the login problem is shown because this one was unsuccessful, but
maybe I'm not correct at this point.
Login to the workstation is local XP account, the same username &
password as on AFS server.
The funny/strange thing is, that the same thing happened also few weeks
ago on the other WinXP - AFS client suddenly stopped to work, exactly
the same way. But after a week or so, it started to work again, as if
nothing happend! We are 100% sure that it wasn't broken by the user,
because only my colleague and I have access to this computer, and we
both are admins and we both know what we do :o)
Now afsd_init.log:
17:11:13: Create log file
17:11:13: Created log file
PATH=C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program
Files\OpenAFS\Common;C:\Program Files\OpenAFS\Client\Program
29. 6. 2008 17:11:13: OEM Code Page = 852
29. 6. 2008 17:11:13: locale = C
29. 6. 2008 17:11:13: running on 2000+ - using RegisterServiceCtrlHandlerEx
29. 6. 2008 17:11:13: C:\Program
Files\OpenAFS\Client\Program\afsd_service.exe version 1.5.3900
29. 6. 2008 17:11:13: VerifyTrust failed: Unsigned executable
29. 6. 2008 17:11:13: Num of Process Modules: 39
29. 6. 2008 17:11:13: C:\Program Files\OpenAFS\Client\Program\libosi.dll
version 1.5.3900
29. 6. 2008 17:11:13: C:\Program Files\OpenAFS\Common\afsrpc.dll version
1.5.3900
29. 6. 2008 17:11:13: C:\Program Files\OpenAFS\Common\afspthread.dll
version 1.5.3900
29. 6. 2008 17:11:13: C:\Program Files\OpenAFS\Common\afsauthent.dll
version 1.5.3900
29. 6. 2008 17:11:13: C:\Program
Files\OpenAFS\Client\Program\libafsconf.dll version 1.5.3900
29. 6. 2008 17:11:13: osi_InitDebug code 0
29. 6. 2008 17:11:13: gethostname money
29. 6. 2008 17:11:13: Trace Options = 0
29. 6. 2008 17:11:13: Default trace buffer size 10000
29. 6. 2008 17:11:13: osi_LogCreate log addr 995650
29. 6. 2008 17:11:13: Default cache size 98304
29. 6. 2008 17:11:13: Chunk size 262144 (18)
29. 6. 2008 17:11:13: Block size 4096
29. 6. 2008 17:11:13: Defaulting to 4 background daemons
29. 6. 2008 17:11:13: Defaulting to 25 server threads
29. 6. 2008 17:11:13: Default status cache entries: 10000
29. 6. 2008 17:11:13: Default volume cache entries: 3333
29. 6. 2008 17:11:13: Default cell cache entries: 1024
29. 6. 2008 17:11:13: Logoff token transfer on
29. 6. 2008 17:11:13: Logoff token transfer timeout 120 seconds
29. 6. 2008 17:11:13: Default root volume name root.afs
29. 6. 2008 17:11:13: Mount root /afs
29. 6. 2008 17:11:13: Default cache path C:\WINDOWS\TEMP\AFSCache
29. 6. 2008 17:11:13: Cache type is FILE
29. 6. 2008 17:11:13: Cache Validation on Startup
29. 6. 2008 17:11:13: Set to trap on panic
29. 6. 2008 17:11:13: Session startups will be recorded in the Event Log
29. 6. 2008 17:11:13: Sys name x86_win32 i386_w2k i386_nt40
29. 6. 2008 17:11:13: SecurityLevel is crypt
29. 6. 2008 17:11:13: CM ForceAnonVLDB is off
29. 6. 2008 17:11:13: DNS will be used to find AFS cell servers
29. 6. 2008 17:11:13: Freelance client feature is activated
29. 6. 2008 17:11:13: Dot files/dirs will be marked hidden
29. 6. 2008 17:11:13: Maximum number of multiplexed sessions is 50
29. 6. 2008 17:11:13: Maximum number of VCs per server is 100
29. 6. 2008 17:11:13: SMB authentication type is EXTENDED
29. 6. 2008 17:11:13: RX extraPackets is 120
29. 6. 2008 17:11:13: RX Peer Statistics gathering is enabled
29. 6. 2008 17:11:13: RX Process Statistics gathering is enabled
29. 6. 2008 17:11:13: RX Hot Thread is enabled
29. 6. 2008 17:11:13: CM CallBackPort is 7001
29. 6. 2008 17:11:13: EnableServerLocks: server requested
29. 6. 2008 17:11:13: CM DeleteReadOnly is 0
29. 6. 2008 17:11:13: CM BPlusTrees is 1
29. 6. 2008 17:11:13: No PrefetchExecutableExtensions
29. 6. 2008 17:11:13: CM OfflineReadOnlyIsValid is 0
29. 6. 2008 17:11:13: CM GiveUpAllCallBacks is 0
29. 6. 2008 17:11:13: CM FollowBackupPath is 0
29. 6. 2008 17:11:14: First Network address c0a80263 SubnetMask ffffff00
29. 6. 2008 17:11:14: HardDeadTimeout is 0
29. 6. 2008 17:11:14: IdleDeadTimeout is 30
29. 6. 2008 17:11:14: lanmanworkstation : SessTimeout 45
29. 6. 2008 17:11:14: ConnDeadTimeout is 22
29. 6. 2008 17:11:14: HardDeadTimeout is 45
29. 6. 2008 17:11:14: Cache File "C:\WINDOWS\TEMP\AFSCache" already exists
29. 6. 2008 17:11:14: Existing File Size: 00000000:07283DA8
29. 6. 2008 17:11:14: Granularity - 10000
29. 6. 2008 17:11:14: Configuration changed or Not a persistent cache file
29. 6. 2008 17:11:14: Building AFS Cache from scratch
29. 6. 2008 17:11:14: Volume Serial Number: 0x5807b0e9
29. 6. 2008 17:11:14: Machine SID: S-1-5-21-1644491937-2025429265-725345543
29. 6. 2008 17:11:14: Initializing Uuid to
33d47a7b-cd7c-47ae-9c7a-da81be98d5c2
29. 6. 2008 17:11:14: Initializing Volume Data
29. 6. 2008 17:11:14: Initializing Cell Data
29. 6. 2008 17:11:14: Initializing ACL Data
29. 6. 2008 17:11:14: Initializing Stat Data
29. 6. 2008 17:11:14: CM UseDNLC = 1
29. 6. 2008 17:11:14: CM DebugDNLC = 0
29. 6. 2008 17:11:14: Initializing Data Buffers
29. 6. 2008 17:11:14: Cache Initialization Complete
29. 6. 2008 17:11:14: cm_InitMappedMemory code 0
29. 6. 2008 17:11:14: rx_Init code 0
29. 6. 2008 17:11:14: rx_NewService addr 14a4eb0
29. 6. 2008 17:11:14: rx_NewService addr 14a5118
29. 6. 2008 17:11:14: rx_StartServer
29. 6. 2008 17:11:14: cm_GetRootCellName code 0, cm_freelanceEnabled= 1,
rcn= ecce.sk
29. 6. 2008 17:11:14: Mountpoint[0] = ecce.sk#ecce.sk:root.cell.
29. 6. 2008 17:11:14: Mountpoint[1] = .ecce.sk%ecce.sk:root.cell.
29. 6. 2008 17:11:14: Mountpoint[2] = .root%ecce.sk:root.afs.
29. 6. 2008 17:11:14: VLDB Server Preference: 192.168.2.10 = 32
29. 6. 2008 17:11:14: RPC server listening
29. 6. 2008 17:11:14: File Server Preference: 192.168.2.10 = 32
29. 6. 2008 17:11:14: cm_GetSCache code 0 scache 1936bd0
29. 6. 2008 17:11:14: cm_InitDaemon complete
29. 6. 2008 17:11:14: MpsSvc Service could not be opened for query: 0x424
29. 6. 2008 17:11:14: AutoStart 0x2
29. 6. 2008 17:11:14: daemonCheckDownInterval is 180
29. 6. 2008 17:11:14: daemonCheckUpInterval is 240
29. 6. 2008 17:11:14: daemonCheckVolInterval is 3600
29. 6. 2008 17:11:14: daemonCheckCBInterval is 60
29. 6. 2008 17:11:14: daemonCheckVolCBInterval is 0
29. 6. 2008 17:11:14: daemonCheckLockInterval is 60
29. 6. 2008 17:11:14: daemonCheckTokenInterval is 180
29. 6. 2008 17:11:14: daemonCheckOfflineVolInterval is 600
29. 6. 2008 17:11:14: daemonPerformanceTuningInterval is 0
29. 6. 2008 17:11:14: StoreAnsiFilenames = 0
29. 6. 2008 17:11:14: EnableSMBAsyncStore = 1
29. 6. 2008 17:11:14: SMBAsyncStoreSize = 131072
29. 6. 2008 17:11:14: LAN adapter number 3
29. 6. 2008 17:11:14: Using >AFS< as SMB server name
29. 6. 2008 17:11:14: smb_localNamep is >AFS<
29. 6. 2008 17:11:14: Netbios NCBRESET lana 3 succeeded
29. 6. 2008 17:11:14: lana_list.length 1
29. 6. 2008 17:11:14: Unable to open Windows Firewall Profile
29. 6. 2008 17:11:17: Netbios NCBADDNAME lana=3 code=0 retcode=0 complete=0
29. 6. 2008 17:11:17: Netbios NCBADDNAME added new name >AFS <
29. 6. 2008 17:11:17: Netbios NCBADDNAME succeeded on lana 3
29. 6. 2008 17:11:17: smb_NetbiosInit smb_LANadapter=3
29. 6. 2008 17:11:17: MsV1_0SetProcessOption success
29. 6. 2008 17:11:17: Setting SMB server domain name to [MONEY]
29. 6. 2008 17:11:17: smb_StartListeners
29. 6. 2008 17:11:17: smb_Init complete
29. 6. 2008 17:11:17: GlobalAutoMap thread completed
29. 6. 2008 17:11:24: Windows Firewall Configuration succeeded
29. 6. 2008 17:12:31: Mountpoint[0] = ecce.sk#ecce.sk:root.cell.
29. 6. 2008 17:12:31: Mountpoint[1] = .ecce.sk%ecce.sk:root.cell.
29. 6. 2008 17:12:31: Mountpoint[2] = .root%ecce.sk:root.afs.
And also "nbtstat -n":
AFS:
Node IpAddress: [10.254.254.253] Scope Id: []
NetBIOS Local Name Table
Name Type Status
---------------------------------------------
MONEY <00> UNIQUE Registered
ECCE <00> GROUP Registered
AFS <20> UNIQUE Registered
ECCE <1E> GROUP Registered
ECCE <1D> UNIQUE Registered
..__MSBROWSE__.<01> GROUP Registered
Local Area Connection:
Node IpAddress: [192.168.2.99] Scope Id: []
NetBIOS Local Name Table
Name Type Status
---------------------------------------------
MONEY <00> UNIQUE Registered
ECCE <00> GROUP Registered
MONEY <20> UNIQUE Registered
ECCE <1E> GROUP Registered
ECCE <1D> UNIQUE Registered
..__MSBROWSE__.<01> GROUP Registered
Thanks,
Michal