[OpenAFS] MS Word crashing when opening files, 1.5.27 client

Jeffrey Altman jaltman@secure-endpoints.com
Tue, 27 Nov 2007 15:39:29 +0100


This is a cryptographically signed message in MIME format.

--------------ms080505060206080702090008
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: 7bit

Your log shows that at 12:56.57 winword.exe attempted the following
request that was denied due to the file being in use.

Sequence:    669571
Date & Time:    11/27/2007 12:56:57 PM
Event Class:    File System
Operation:    IRP_MJ_CREATE
Result:    NOT GRANTED
Path:    P:\2006\06226-06250\06249\Rapportages\rapportage Friesland 15
november 2000-02.doc
TID:    336
Duration:    92.1418212
Desired Access:    Generic Read/Write
Disposition:    Open
Options:    Synchronous IO Non-Alert, Non-Directory File
Attributes:    N
ShareMode:    Read, Write
AllocationSize:    n/a

Winword.exe then attempts to repeat the request and you terminated the
logging before a result could be determined.

Sequence:    709074
Date & Time:    11/27/2007 12:58:29 PM
Event Class:    File System
Operation:    IRP_MJ_CREATE
Result:   
Path:    P:\2006\06226-06250\06249\Rapportages\rapportage Friesland 15
november 2000-02.doc
TID:    336
Duration:   
Desired Access:    Generic Read
Disposition:    Open
Options:    Synchronous IO Non-Alert, Non-Directory File
Attributes:    N
ShareMode:    Read
AllocationSize:    n/a

I do not see in your log any "DISCONNECTED" errors which is what would
be listed if the CIFS client timed out and terminated the connection to
the AFS Client Service.  As far as I can tell WinWord is simply trying
to open a file it can't obtain a lock on fails, and tries again
indefinitely.

You said previously that earlier versions of OpenAFS worked better for
you.  What are the differences in the requests performed between the
current and previous versions of OpenAFS?   You should be able to
perform this analysis yourself.  Use the Process Monitor Filter option
to restrict the displayed events to winword.exe, and paths beginning
with P:\ and \\AFS

Jeffrey Altman



Hans Melgers wrote:
>
> Hi Jeffrey,
>
> We just made the log, its quite big (38MB) so please download it from
> the site mentioned at the bottom of the mail.
> I hope you can find something.
>
> Kind regards,
> Hans Melgers
>
>
>
> Jeffrey Altman wrote:
>> Hans:
>>
>> Again I must point you at the debugging section of the release notes.
>> If you would gather the necessary data the problem could be examined
>> based upon the actual requests that are being issued by the software and
>> the responses that are provided by the AFS client instead of attempting
>> to guess at the behavior by looking at the entire system (Office XP,
>> Windows, AFS client, AFS server) as a black box.
>>
>> While it is possible the Netware client might be involved in some way I
>> would find that unlikely.  The application is requesting a lock and the
>> file server is unable to grant it because another client already has it.
>>    In this situation, the file server will report EAGAIN to the client
>> and the AFS client will retry in the hopes of being able to obtain
>> the lock.
>> Perhaps the application is timing out the request before the AFS client
>> returns.  I don't know for sure because you have not obtained the
>> necessary data.
>>
>> Jeffrey Altman
>>
>>
>> ENEM | Hans Melgers wrote:
>>  
>>> I dont thing the problem is in the afs client. We found this problem
>>> only occurs when opening a file that already has been opened by
>>> another user. We upgraded to office 2003 and it takes alot of time
>>> before word gives any response.
>>> This only seems to happen on the "original" pc's, when trying from
>>> our own pc everything works fine, word opens in read-only mode but
>>> that doesnt happen on these pc's ?
>>>
>>> Maybe it has something to do with the fact that they are also
>>> running the netware client. Are there any known issues with this
>>> setup ? Should we run afs before netware ? Maybe check some settings ?
>>>
>>>
>>> -----Original Message-----
>>> From: Jeffrey Altman [mailto:jaltman@secure-endpoints.com] Sent:
>>> woensdag 14 november 2007 15:43
>>> To: Hans Melgers
>>> Cc: openafs-info@openafs.org
>>> Subject: Re: [OpenAFS] MS Word crashing when opening files, 1.5.27
>>> client
>>>
>>> Hans:
>>>
>>> Please follow the debugging section of the OpenAFS for Windows
>>> Release Notes.  In particular, use the SysInternals' Process Monitor
>>> to log the requests from Word so that you can determine which
>>> requests are failing with 1.5.27 that succeed with 1.5.25.
>>>
>>> One change that went into 1.5.27 is support for retrying requests
>>> when the file server responds to the client with EWOULDBLOCK /
>>> EAGAIN.  In prior releases the client would simply fail the
>>> request.  In 1.5.27 the client retries the request every two seconds
>>> up to 45 seconds until the request completes or the timeout is
>>> reached.  In the trace log it is the
>>> cm_Analyze() messages will include the error number.  Possible error
>>> numbers to look for include:
>>>
>>>   UAEWOULDBLOCK 49733416L
>>>   UAEAGAIN      49733386L
>>>   EAGAIN              11L
>>>
>>>
>>> Jeffrey Altman
>>>
>>>
>>>
>>>
>>> Hans Melgers wrote:
>>>    
>>>> Hello,
>>>>
>>>> We just installed some XP machines with the latest 1.5.27 afs
>>>> client. On several of them Word crashes badly when trying to open a
>>>> .doc file on afs.  Most of them running office XP but also seen on
>>>> office 2003.
>>>>
>>>> I run 1.5.25 and havent seen the problem. Could this be a bug in
>>>> the latest win client ? acl's are ok. Server is 1.4.4 on freebsd
>>>> 6.2 Anyone else seeing this behaviour ?
>>>>
>>>> In Filelog i only see some callback issues:
>>>>
>>>> Wed Nov 14 10:56:32 2007 CB: ProbeUuid for 213.46.24.222:17980
>>>> failed -01 Wed Nov 14 10:57:11 2007 CB: ProbeUuid for
>>>> 213.46.24.222:17982 failed -01 Wed Nov 14 10:58:08 2007 CB:
>>>> ProbeUuid for 213.46.24.222:17987 failed -01 Wed Nov 14 10:58:18
>>>> 2007 CB: ProbeUuid for 213.46.24.222:17976 failed -01 Wed Nov 14
>>>> 11:01:25 2007 CB: ProbeUuid for 213.46.24.222:17995 failed -01
>>>>
>>>>
>>>> Any help appreciated.
>>>>
>>>> Hans
>>>>       
>>>
>>> _______________________________________________
>>> OpenAFS-info mailing list
>>> OpenAFS-info@openafs.org
>>> https://lists.openafs.org/mailman/listinfo/openafs-info
>>>     

--------------ms080505060206080702090008
Content-Type: application/x-pkcs7-signature; name="smime.p7s"
Content-Transfer-Encoding: base64
Content-Disposition: attachment; filename="smime.p7s"
Content-Description: S/MIME Cryptographic Signature

MIAGCSqGSIb3DQEHAqCAMIACAQExCzAJBgUrDgMCGgUAMIAGCSqGSIb3DQEHAQAAoIIJeTCC
AxcwggKAoAMCAQICEALr5BE3U6n+HWCoLbyhohMwDQYJKoZIhvcNAQEFBQAwYjELMAkGA1UE
BhMCWkExJTAjBgNVBAoTHFRoYXd0ZSBDb25zdWx0aW5nIChQdHkpIEx0ZC4xLDAqBgNVBAMT
I1RoYXd0ZSBQZXJzb25hbCBGcmVlbWFpbCBJc3N1aW5nIENBMB4XDTA3MDUzMTA2MTM1N1oX
DTA4MDUzMDA2MTM1N1owczEPMA0GA1UEBBMGQWx0bWFuMRUwEwYDVQQqEwxKZWZmcmV5IEVy
aWMxHDAaBgNVBAMTE0plZmZyZXkgRXJpYyBBbHRtYW4xKzApBgkqhkiG9w0BCQEWHGphbHRt
YW5Ac2VjdXJlLWVuZHBvaW50cy5jb20wggEiMA0GCSqGSIb3DQEBAQUAA4IBDwAwggEKAoIB
AQCsoz/0+s4Cn65n/3bU3shXw4y5u1uEMEsBOiqNU0PfIKGYQe95b1FKNbNAkctSdQT6GF5c
bhSnJPmb2OOb1frx64dlDgskaG561xa8XPA1aP8Cc+33dgsSLIxGEh97lyUYHEfWBC03KMCF
PKhZfcrGAXoVCrFBadnLAokQbUTFahVg/qQx2IT3wSj1sCIfV5UDuXcEKHCvRtEZIsSzu184
9Cj6I4nY5bt+r94kyDHM94MHYBJi+6tWLFRy2gkIB3HEPmxAiQrKljNpH9bOffiBLIAgmJ6d
1ZXepBXyexQbwOYvftpVlMEFHHQmdiwH3tj69hE78XvM5X9J+SbjbuNpAgMBAAGjOTA3MCcG
A1UdEQQgMB6BHGphbHRtYW5Ac2VjdXJlLWVuZHBvaW50cy5jb20wDAYDVR0TAQH/BAIwADAN
BgkqhkiG9w0BAQUFAAOBgQB8FShDN2Ig034Y5eyadiFDEtOvsIJ3Z2xV9aTL4u8xMlz1gZR1
AZAvCv+ZMMRRKWCsrG5tItV8DFPSfWAGMpInmMarA4f76JRLQEUhkRUg8GpkJM5ryk5EDakk
0oiBQcQD8A+UHwrcmaj3UWxQ9zCjDgU+1mY9nEQxZZyp4eeUfzCCAxcwggKAoAMCAQICEALr
5BE3U6n+HWCoLbyhohMwDQYJKoZIhvcNAQEFBQAwYjELMAkGA1UEBhMCWkExJTAjBgNVBAoT
HFRoYXd0ZSBDb25zdWx0aW5nIChQdHkpIEx0ZC4xLDAqBgNVBAMTI1RoYXd0ZSBQZXJzb25h
bCBGcmVlbWFpbCBJc3N1aW5nIENBMB4XDTA3MDUzMTA2MTM1N1oXDTA4MDUzMDA2MTM1N1ow
czEPMA0GA1UEBBMGQWx0bWFuMRUwEwYDVQQqEwxKZWZmcmV5IEVyaWMxHDAaBgNVBAMTE0pl
ZmZyZXkgRXJpYyBBbHRtYW4xKzApBgkqhkiG9w0BCQEWHGphbHRtYW5Ac2VjdXJlLWVuZHBv
aW50cy5jb20wggEiMA0GCSqGSIb3DQEBAQUAA4IBDwAwggEKAoIBAQCsoz/0+s4Cn65n/3bU
3shXw4y5u1uEMEsBOiqNU0PfIKGYQe95b1FKNbNAkctSdQT6GF5cbhSnJPmb2OOb1frx64dl
DgskaG561xa8XPA1aP8Cc+33dgsSLIxGEh97lyUYHEfWBC03KMCFPKhZfcrGAXoVCrFBadnL
AokQbUTFahVg/qQx2IT3wSj1sCIfV5UDuXcEKHCvRtEZIsSzu1849Cj6I4nY5bt+r94kyDHM
94MHYBJi+6tWLFRy2gkIB3HEPmxAiQrKljNpH9bOffiBLIAgmJ6d1ZXepBXyexQbwOYvftpV
lMEFHHQmdiwH3tj69hE78XvM5X9J+SbjbuNpAgMBAAGjOTA3MCcGA1UdEQQgMB6BHGphbHRt
YW5Ac2VjdXJlLWVuZHBvaW50cy5jb20wDAYDVR0TAQH/BAIwADANBgkqhkiG9w0BAQUFAAOB
gQB8FShDN2Ig034Y5eyadiFDEtOvsIJ3Z2xV9aTL4u8xMlz1gZR1AZAvCv+ZMMRRKWCsrG5t
ItV8DFPSfWAGMpInmMarA4f76JRLQEUhkRUg8GpkJM5ryk5EDakk0oiBQcQD8A+UHwrcmaj3
UWxQ9zCjDgU+1mY9nEQxZZyp4eeUfzCCAz8wggKooAMCAQICAQ0wDQYJKoZIhvcNAQEFBQAw
gdExCzAJBgNVBAYTAlpBMRUwEwYDVQQIEwxXZXN0ZXJuIENhcGUxEjAQBgNVBAcTCUNhcGUg
VG93bjEaMBgGA1UEChMRVGhhd3RlIENvbnN1bHRpbmcxKDAmBgNVBAsTH0NlcnRpZmljYXRp
b24gU2VydmljZXMgRGl2aXNpb24xJDAiBgNVBAMTG1RoYXd0ZSBQZXJzb25hbCBGcmVlbWFp
bCBDQTErMCkGCSqGSIb3DQEJARYccGVyc29uYWwtZnJlZW1haWxAdGhhd3RlLmNvbTAeFw0w
MzA3MTcwMDAwMDBaFw0xMzA3MTYyMzU5NTlaMGIxCzAJBgNVBAYTAlpBMSUwIwYDVQQKExxU
aGF3dGUgQ29uc3VsdGluZyAoUHR5KSBMdGQuMSwwKgYDVQQDEyNUaGF3dGUgUGVyc29uYWwg
RnJlZW1haWwgSXNzdWluZyBDQTCBnzANBgkqhkiG9w0BAQEFAAOBjQAwgYkCgYEAxKY8VXNV
+065yplaHmjAdQRwnd/p/6Me7L3N9VvyGna9fww6YfK/Uc4B1OVQCjDXAmNaLIkVcI7dyfAr
hVqqP3FWy688Cwfn8R+RNiQqE88r1fOCdz0Dviv+uxg+B79AgAJk16emu59l0cUqVIUPSAR/
p7bRPGEEQB5kGXJgt/sCAwEAAaOBlDCBkTASBgNVHRMBAf8ECDAGAQH/AgEAMEMGA1UdHwQ8
MDowOKA2oDSGMmh0dHA6Ly9jcmwudGhhd3RlLmNvbS9UaGF3dGVQZXJzb25hbEZyZWVtYWls
Q0EuY3JsMAsGA1UdDwQEAwIBBjApBgNVHREEIjAgpB4wHDEaMBgGA1UEAxMRUHJpdmF0ZUxh
YmVsMi0xMzgwDQYJKoZIhvcNAQEFBQADgYEASIzRUIPqCy7MDaNmrGcPf6+svsIXoUOWlJ1/
TCG4+DYfqi2fNi/A9BxQIJNwPP2t4WFiw9k6GX6EsZkbAMUaC4J0niVQlGLH2ydxVyWN3amc
OY6MIE9lX5Xa9/eH1sYITq726jTlEBpbNU1341YheILcIRk13iSx0x1G/11fZU8xggNkMIID
YAIBATB2MGIxCzAJBgNVBAYTAlpBMSUwIwYDVQQKExxUaGF3dGUgQ29uc3VsdGluZyAoUHR5
KSBMdGQuMSwwKgYDVQQDEyNUaGF3dGUgUGVyc29uYWwgRnJlZW1haWwgSXNzdWluZyBDQQIQ
AuvkETdTqf4dYKgtvKGiEzAJBgUrDgMCGgUAoIIBwzAYBgkqhkiG9w0BCQMxCwYJKoZIhvcN
AQcBMBwGCSqGSIb3DQEJBTEPFw0wNzExMjcxNDM5MjlaMCMGCSqGSIb3DQEJBDEWBBQMKPrX
eFjpiCck3yCl7cJrdsJi3TBSBgkqhkiG9w0BCQ8xRTBDMAoGCCqGSIb3DQMHMA4GCCqGSIb3
DQMCAgIAgDANBggqhkiG9w0DAgIBQDAHBgUrDgMCBzANBggqhkiG9w0DAgIBKDCBhQYJKwYB
BAGCNxAEMXgwdjBiMQswCQYDVQQGEwJaQTElMCMGA1UEChMcVGhhd3RlIENvbnN1bHRpbmcg
KFB0eSkgTHRkLjEsMCoGA1UEAxMjVGhhd3RlIFBlcnNvbmFsIEZyZWVtYWlsIElzc3Vpbmcg
Q0ECEALr5BE3U6n+HWCoLbyhohMwgYcGCyqGSIb3DQEJEAILMXigdjBiMQswCQYDVQQGEwJa
QTElMCMGA1UEChMcVGhhd3RlIENvbnN1bHRpbmcgKFB0eSkgTHRkLjEsMCoGA1UEAxMjVGhh
d3RlIFBlcnNvbmFsIEZyZWVtYWlsIElzc3VpbmcgQ0ECEALr5BE3U6n+HWCoLbyhohMwDQYJ
KoZIhvcNAQEBBQAEggEAV4CwazEx3otIekq6MxqXKwzMdC4tqr6FpsHUzFiIHk2tL3haAiGN
up/C6/vizSVYaPdMISItFg7Z2BKz2KUwdpXBG2dckObxld3Kf+8+hXF4gfKdHZKWam9czlkA
5xIPIpTAHiKYt/9p8Ko13rJtmEFA+QhGGmkuQe2zkVLXyYuGCRltrFJUnUhgjFLTLX2zemCw
TI32K+KzYmx9O5SCnqfEr9UZmRTMy8ZmDc5E/3iehd0oyT0OU/LgLe3h1RuQ0npt+lKOHlMG
x0CfqiaH+crOP7r3WIlVWDOn43gP375dG11BGiU+QljfV+IPaIxh3QQKwRXfbUqlBrVY2BVw
ygAAAAAAAA==
--------------ms080505060206080702090008--