Answered by:
Corrupt User accounts?

Question
-
I have two users who get errors running the Resource Availability report that error out (log below). I have setup a test user with the same permissions, choosing the exact same resources and it runs fine. I am trying to figure out the difference between these users. Has anyone encountered this before?
Reproduce issue
- Go to Resources
- Select users
- Click the Resource Availability button
Log - 7f3f7f1d-c334-4647-9b0a-65bca4536948
Thread change; resetting trace level override to 0; resetting correlation to 7f3f7f1d-c334-4647-9b0a-65bca4536948 170dee8a-fdd5-4e17-8f44-c27c7f7ff09e 7f3f7f1d-c334-4647-9b0a-65bca4536948
03/08/2011 15:23:51.10 w3wp.exe (0x1CF4) 0x1384 Project Server General g4y6 Verbose Setting LoadBalancer Context for PWA.svc to begun uri http://proj-hnl-2010:32843/f252b29c5433447f943f0a276a7ffd1c/PSI/PWA.svc 7f3f7f1d-c334-4647-9b0a-65bca4536948
03/08/2011 15:23:51.10 w3wp.exe (0x1CF4) 0x1384 Project Server General g4y2 Verbose Invoking business object method 7f3f7f1d-c334-4647-9b0a-65bca4536948
03/08/2011 15:23:51.10 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Topology e5mc Medium WcfSendRequest: RemoteAddress: 'http://proj-hnl-2010:32843/f252b29c5433447f943f0a276a7ffd1c/PSI/PWA.svc' Channel: 'Microsoft.Office.Project.Server.Interfaces.IPWA' Action: 'http://schemas.microsoft.com/office/project/server/webservices/PWA/SecurityCheckUserPagePermission' MessageId: 'urn:uuid:d870d25b-4c14-4f93-bb9a-aa3aafce9339' 7f3f7f1d-c334-4647-9b0a-65bca4536948
03/08/2011 15:23:51.12 w3wp.exe (0x1E00) 0x0AE4 SharePoint Foundation Logging Correlation Data 77a3 Verbose Starting correlation. 7f3f7f1d-c334-4647-9b0a-65bca4536948
03/08/2011 15:23:51.12 w3wp.exe (0x1E00) 0x0AE4 SharePoint Foundation Topology e5mb Medium WcfReceiveRequest: LocalAddress: 'http://proj-hnl-2010.phase2.local:32843/f252b29c5433447f943f0a276a7ffd1c/PSI/PWA.svc' Channel: 'System.ServiceModel.Channels.ServiceChannel' Action: 'http://schemas.microsoft.com/office/project/server/webservices/PWA/SecurityCheckUserPagePermission' MessageId: 'urn:uuid:d870d25b-4c14-4f93-bb9a-aa3aafce9339' 7f3f7f1d-c334-4647-9b0a-65bca4536948
03/08/2011 15:23:51.12 w3wp.exe (0x1E00) 0x0AE4 SharePoint Foundation Monitoring nasq Medium Entering monitored scope (ExecuteWcfServerOperation) 7f3f7f1d-c334-4647-9b0a-65bca4536948
03/08/2011 15:23:51.12 w3wp.exe (0x1E00) 0x0AE4 SharePoint Foundation Logging Correlation Data 77a3 Verbose Starting correlation. Transfer from 7f3f7f1d-c334-4647-9b0a-65bca4536948 4e6142db-bc33-4202-8dcb-25c93f54dfa9
03/08/2011 15:23:51.12 w3wp.exe (0x1E00) 0x0AE4 SharePoint Foundation Logging Correlation Data xmnv Medium Name=ExecuteWcfServerOperation 4e6142db-bc33-4202-8dcb-25c93f54dfa9
03/08/2011 15:23:51.12 w3wp.exe (0x1E00) 0x0AE4 SharePoint Foundation Monitoring b4ly Medium Leaving Monitored Scope (ExecuteWcfServerOperation). Execution Time=5.04645143447002 4e6142db-bc33-4202-8dcb-25c93f54dfa9
03/08/2011 15:23:51.12 w3wp.exe (0x1E00) 0x0AE4 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. Transfer to 7f3f7f1d-c334-4647-9b0a-65bca4536948 4e6142db-bc33-4202-8dcb-25c93f54dfa9
03/08/2011 15:23:51.12 w3wp.exe (0x1E00) 0x0AE4 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. 7f3f7f1d-c334-4647-9b0a-65bca4536948
- Moved by Mike Walsh FIN Wednesday, March 9, 2011 6:50 AM Project Server / PWA in log (From:SharePoint - Setup, Upgrade, Administration and Operation (pre-SharePoint 2010))
Wednesday, March 9, 2011 1:32 AM
Answers
-
Hi rix87,
And what is the exact SP error and error ID?
Have you tried removing one of the users from all of their groups and adding them to the administrators group? If they can access the page while in the administrators groups (and no other groups) is it a security configuration issue and you should review the security settings (both global and category permissions).
Have you set the security on group level and assigned the users to the groups in PWA?
Is there a 'Deny' setting somewhere in the security for these users (in one of the groups or on user level)?I hope this helps,
Hans
My EPM blog: Projectopolis- Proposed as answer by Christophe FiessingerMicrosoft employee Saturday, March 19, 2011 1:15 AM
- Marked as answer by Alexander.Burton Saturday, June 11, 2011 11:27 PM
Thursday, March 10, 2011 3:14 PM
All replies
-
PWA:http://mfprojectonline.phase2int.com/PWA, ServiceApp:Project Server Service Application, User:PHASE2\lou.owayni, PSI: Entering GetMyOverdueStatusReportsCount 81d355a4-ca69-4971-ae4c-9efffb8bfa53
03/08/2011 15:23:39.23 w3wp.exe (0x1E00) 0x2414 Project Server Task Statusing and Updates 74yo Verbose PWA:http://mfprojectonline.phase2int.com/PWA, ServiceApp:Project Server Service Application, User:PHASE2\lou.owayni, PSI: Entering ReadArchivePageData 81d355a4-ca69-4971-ae4c-9efffb8bfa53
03/08/2011 15:23:39.23 w3wp.exe (0x1E00) 0x2414 Project Server Task Statusing and Updates 74yp Verbose PWA:http://mfprojectonline.phase2int.com/PWA, ServiceApp:Project Server Service Application, User:PHASE2\lou.owayni, PSI: Leaving ReadArchivePageData 81d355a4-ca69-4971-ae4c-9efffb8bfa53
03/08/2011 15:23:39.23 w3wp.exe (0x1E00) 0x2414 Project Server Task Statusing and Updates 74yn Verbose PWA:http://mfprojectonline.phase2int.com/PWA, ServiceApp:Project Server Service Application, User:PHASE2\lou.owayni, PSI: Leaving GetMyOverdueStatusReportsCount 81d355a4-ca69-4971-ae4c-9efffb8bfa53
03/08/2011 15:23:39.26 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Monitoring b4ly Medium Leaving Monitored Scope (ExecuteWcfServerOperation). Execution Time=90.5947543612386 81d355a4-ca69-4971-ae4c-9efffb8bfa53
03/08/2011 15:23:39.26 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. Transfer to 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9 81d355a4-ca69-4971-ae4c-9efffb8bfa53
03/08/2011 15:23:39.26 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.26 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Monitoring b4ly High Leaving Monitored Scope (ExecuteWcfOperation:http://schemas.microsoft.com/office/project/server/webservices/PWA/AdminGetAllRemindersCount). Execution Time=102.525041590481 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.26 w3wp.exe (0x1CF4) 0x1384 Project Server General g4y3 Verbose Setting LoadBalancer result to completed for PWA.svc 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.26 w3wp.exe (0x1CF4) 0x1384 Project Server General g4y6 Verbose Setting LoadBalancer Context for PWA.svc to begun uri http://proj-hnl-2010:32843/f252b29c5433447f943f0a276a7ffd1c/PSI/PWA.svc 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.26 w3wp.exe (0x1CF4) 0x1384 Project Server General g4y2 Verbose Invoking business object method 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.26 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Topology e5mc Medium WcfSendRequest: RemoteAddress: 'http://proj-hnl-2010:32843/f252b29c5433447f943f0a276a7ffd1c/PSI/PWA.svc' Channel: 'Microsoft.Office.Project.Server.Interfaces.IPWA' Action: 'http://schemas.microsoft.com/office/project/server/webservices/PWA/SecurityReadUserGrantedGlobalPermissions' MessageId: 'urn:uuid:5dd7c711-1453-411f-871d-1080f80d8007' 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.27 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Logging Correlation Data 77a3 Verbose Starting correlation. 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.27 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Topology e5mb Medium WcfReceiveRequest: LocalAddress: 'http://proj-hnl-2010.phase2.local:32843/f252b29c5433447f943f0a276a7ffd1c/PSI/PWA.svc' Channel: 'System.ServiceModel.Channels.ServiceChannel' Action: 'http://schemas.microsoft.com/office/project/server/webservices/PWA/SecurityReadUserGrantedGlobalPermissions' MessageId: 'urn:uuid:5dd7c711-1453-411f-871d-1080f80d8007' 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.27 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Monitoring nasq Medium Entering monitored scope (ExecuteWcfServerOperation) 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.27 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Logging Correlation Data 77a3 Verbose Starting correlation. Transfer from 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9 2683b47a-e72d-4765-a178-2fecbb261084
03/08/2011 15:23:39.27 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Logging Correlation Data xmnv Medium Name=ExecuteWcfServerOperation 2683b47a-e72d-4765-a178-2fecbb261084
03/08/2011 15:23:39.27 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Monitoring b4ly Medium Leaving Monitored Scope (ExecuteWcfServerOperation). Execution Time=5.19591177090943 2683b47a-e72d-4765-a178-2fecbb261084
03/08/2011 15:23:39.27 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. Transfer to 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9 2683b47a-e72d-4765-a178-2fecbb261084
03/08/2011 15:23:39.27 w3wp.exe (0x1E00) 0x2414 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.29 w3wp.exe (0x1CF4) 0x1384 Project Server General g4y3 Verbose Setting LoadBalancer result to completed for PWA.svc 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.29 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Unified Logging Service cn4g Verbose Trace level override is turned off. 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.32 w3wp.exe (0x1CF4) 0x1384 SharePoint Server Logging Correlation Data 9gc5 Verbose Thread change; resetting trace level override to 0; resetting correlation to 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.32 w3wp.exe (0x1CF4) 0x1384 SharePoint Server Unified Logging Service cn4g Verbose Trace level override is turned off. 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.32 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Performance nask High An SPRequest object was not disposed before the end of this thread. To avoid wasting system resources, dispose of this object or its parent (such as an SPSite or SPWeb) as soon as you are done using it. This object will now be disposed. Allocation Id: {F53E33CD-A4C0-4B2C-A0BA-82CF04EE7880} To determine where this object was allocated, set Microsoft.SharePoint.Administration.SPWebService.ContentService.CollectSPRequestAllocationCallStacks = true. 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.32 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Monitoring b4ly Medium Leaving Monitored Scope (Request (GET:http://mfprojectonline.phase2int.com:80/PWA/default.aspx)). Execution Time=482.043946926215 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:39.32 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. 24e6b488-8eea-4ac1-8b35-d96f7f8dafa9
03/08/2011 15:23:40.73 w3wp.exe (0x095C) 0x24C4 SharePoint Server Logging Correlation Data 77a3 Verbose Starting correlation. fe645fd0-311a-4acc-ae30-9cd371b7db56
03/08/2011 15:23:40.73 w3wp.exe (0x095C) 0x24C4 SharePoint Server Logging Correlation Data 77a3 Verbose Ending correlation. fe645fd0-311a-4acc-ae30-9cd371b7db56
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Foundation Logging Correlation Data 9gc5 Verbose Thread change; resetting trace level override to 0; resetting correlation to empty list
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Foundation Monitoring nasq Medium Entering monitored scope (Request (GET:http://mfprojectonline.phase2int.com:80/pwa/resources.aspx))
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Foundation Logging Correlation Data 77a3 Verbose Starting correlation. a61dc318-7029-47f0-943c-5789c4d37e4a
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Foundation Logging Correlation Data xmnv Medium Name=Request (GET:http://mfprojectonline.phase2int.com:80/pwa/resources.aspx) a61dc318-7029-47f0-943c-5789c4d37e4a
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Foundation SQM 97qu Verbose SQM: Datapoint id 6693 marked as Increment datapoint. a61dc318-7029-47f0-943c-5789c4d37e4a
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Server Logging Correlation Data 9gc5 Verbose Thread change; resetting trace level override to 0; resetting correlation to a61dc318-7029-47f0-943c-5789c4d37e4a a61dc318-7029-47f0-943c-5789c4d37e4a
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Server Unified Logging Service cn4g Verbose Trace level override is turned off. a61dc318-7029-47f0-943c-5789c4d37e4a
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Foundation Monitoring b4ly Medium Leaving Monitored Scope (Request (GET:http://mfprojectonline.phase2int.com:80/pwa/resources.aspx)). Execution Time=0.707911201004597 a61dc318-7029-47f0-943c-5789c4d37e4a
03/08/2011 15:23:41.71 w3wp.exe (0x1CF4) 0x1A48 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. a61dc318-7029-47f0-943c-5789c4d37e4a
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Logging Correlation Data 9gc5 Verbose Thread change; resetting trace level override to 0; resetting correlation to empty list
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Monitoring nasq Medium Entering monitored scope (Request (GET:http://mfprojectonline.phase2int.com:80/pwa/resources.aspx))
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Logging Correlation Data 77a3 Verbose Starting correlation. ab0392b5-444a-41b5-a846-561c08fb7b68
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Logging Correlation Data xmnv Medium Name=Request (GET:http://mfprojectonline.phase2int.com:80/pwa/resources.aspx) ab0392b5-444a-41b5-a846-561c08fb7b68
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation SQM 97qu Verbose SQM: Datapoint id 6693 marked as Increment datapoint. ab0392b5-444a-41b5-a846-561c08fb7b68
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Server Logging Correlation Data 9gc5 Verbose Thread change; resetting trace level override to 0; resetting correlation to ab0392b5-444a-41b5-a846-561c08fb7b68 ab0392b5-444a-41b5-a846-561c08fb7b68
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Server Unified Logging Service cn4g Verbose Trace level override is turned off. ab0392b5-444a-41b5-a846-561c08fb7b68
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Monitoring b4ly Medium Leaving Monitored Scope (Request (GET:http://mfprojectonline.phase2int.com:80/pwa/resources.aspx)). Execution Time=0.994819173945292 ab0392b5-444a-41b5-a846-561c08fb7b68
03/08/2011 15:23:41.76 w3wp.exe (0x1CF4) 0x1384 SharePoint Foundation Logging Correlation Data 77a3 Verbose Ending correlation. ab0392b5-444a-41b5-a846-561c08fb7b68
03/08/2011 15:23:41.82 w3wp.exe (0x1CF4) 0x1380 SharePoint Foundation Logging Correlation Data 9gc5 Verbose Thread change; resetting trace level override to 0; resetting correlation to empty list
03/08/2011 15:23:41.82 w3wp.exe (0x1CF4) 0x1380 SharePoint Foundation Monitoring nasq Medium Entering monitored scope (Request (GET:http://mfprojectonline.phase2int.com:80/pwa/resources.aspx))
03/08/2011 15:23:41.82 w3wp.exe (0x1CF4) 0x1380 SharePoint Foundation Logging Correlation Data 77a3 Verbose Starting correlation. 170dee8a-fdd5-4e17-8f44-c27c7f7ff09e
03/08/2011 15:23:41.82 w3wp.exe (0x1CF4) 0x1380 SharePoint Foundation Logging Correlation Data xmnv Medium Name=Request (GET:http://mfprojectonline.phase2int.com:80/pwa/resources.aspx) 170dee8a-fdd5-4e17-8f44-c27c7f7ff09e
03/08/2011 15:23:41.82 w3wp.exe (0x1CF4) 0x1380 SharePoint Foundation SQM 97qu Verbose SQM: Datapoint id 6693 marked as Increment datapoint. 170dee8a-fdd5-4e17-8f44-c27c7f7ff09e
03/08/2011 15:23:41.82 w3wp.exe (0x1CF4) 0x1380 SharePoint Foundation Logging Correlation Data xmnv Medium User=PHASE2\lou.owayni 170dee8a-fdd5-4e17-8f44-c27c7f7ff09e
03/08/2011 15:23:41.85 w3wp.exe (0x1CF4) 0x1380 SharePoint Foundation Logging Correlation Data xmnv Medium Site=/pwa 170dee8a-fdd5-4e17-8f44-c27c7f7ff09eWednesday, March 9, 2011 1:34 AM -
There are references to Project Server; PWA and SharePoint Foundation throughout this log so it is clearly not a question suitable for this pre-SP 2010 forum.
Moving to a Project Server forum in view of Project Server and PWA references (rather than to a SP 2010 forum).
Moderator pre-SP 2010 forums
SP 2010 "FAQ" (mainly useful links): http://wssv4faq.mindsharp.com/default.aspx
WSS3/MOSS FAQ (FAQ and Links) http://wssv3faq.mindsharp.com/default.aspx
Both also have links to extensive book lists and to (free) on-line chaptersWednesday, March 9, 2011 6:49 AM -
Hi rix87,
What is the error the users see in PWA? Or what problem do they experience?
Acces Denied? IE crash? ...
Regards,
Hans
My EPM blog: ProjectopolisWednesday, March 9, 2011 8:22 AM -
Hi there,
I apologize for posting in the wrong forum.
The users get the standard SP error which does not tell you anything but the error ID. The users who are having the issue run the report and it seems like they time out, then they get the error. My test user returns results almost immediatly.
Thanks!
Mike
Wednesday, March 9, 2011 7:49 PM -
Hi rix87,
And what is the exact SP error and error ID?
Have you tried removing one of the users from all of their groups and adding them to the administrators group? If they can access the page while in the administrators groups (and no other groups) is it a security configuration issue and you should review the security settings (both global and category permissions).
Have you set the security on group level and assigned the users to the groups in PWA?
Is there a 'Deny' setting somewhere in the security for these users (in one of the groups or on user level)?I hope this helps,
Hans
My EPM blog: Projectopolis- Proposed as answer by Christophe FiessingerMicrosoft employee Saturday, March 19, 2011 1:15 AM
- Marked as answer by Alexander.Burton Saturday, June 11, 2011 11:27 PM
Thursday, March 10, 2011 3:14 PM -
Hi there,
The user error is 7f3f7f1d-c334-4647-9b0a-65bca4536948. yes I have tried removing them from all groups and putting them in the admin group but same results. Other accounts have no problems and have the exact same permissions. It is very strange.
Mike
Wednesday, March 23, 2011 7:23 PM