AnsweredAssumed Answered

Can't create sharepoint workspace

Question asked by meecect on Dec 13, 2009
Latest reply on May 25, 2010 by johna1
I am using Community 3.2r2 on ubuntu 9.10.  I installed the vti amp and edited alfresco-global.properties to say:

vti.server.port=7070
vti.alfresco.deployment.context=/alfresco
vti.alfresco.alfresoHostWithPort=http://myserver:8080
vti.share.shareHostWithPort=http://myserver:8080

I am trying to connect from word 2003 in windows xp.

When I try to create the workspace, I put in a simple worksapce name, and this for the URL:

http://myserver:7070/alfresco

I get a authentication box and have tried my username/password, as well as the username/password for admin.

Word then tells me that the operation could not be completed, and to contact an administrator. 

alfresco.log:
*NOTE* I have also been unable to connect from word 2008 on a mac, so some of the failed requests in the log may have been from word 2008 connection attempts.  In general, when connecting from word 2008, I get the 'session is null, must authenticate' and then nothing.  With word 2003, I tend to get the 'Url must start with alfresco context' message.


07:29:27,574 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Starting module 'org.alfresco.module.vti' version 1.2.
07:29:27,852 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
07:29:27,879 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:29:28,293 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
07:29:28,293 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
07:29:28,316 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:29:28,398 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
07:29:28,399 INFO  [org.alfresco.repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
07:29:28,425 INFO  [org.alfresco.repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
07:29:28,425 INFO  [org.alfresco.repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
07:29:28,425 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
07:29:28,526 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:29:28,791 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
07:29:28,820 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:29:28,979 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
07:29:28,995 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
07:29:29,366 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_15-b03; maximum heap size 455.125MB
07:29:29,367 WARN  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - WARNING - maximum heap size 455.125MB is less than recommended 512MB
07:29:29,367 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.2.0 (r2 2440) schema 3300 - Originally installed version 3.2.0 (r2 2440) schema 3300
07:29:30,836 INFO  [org.alfresco.module.vti.VtiServer] Vti server started successfully on port: 7070
07:30:00,385 INFO  [org.alfresco.util.OpenOfficeConnectionTester] The OpenOffice connection was re-established.
07:30:34,878 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
07:30:56,995 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
07:30:57,016 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
07:30:57,016 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
07:30:57,017 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:14:58,954 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:14:58,954 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:14:58,954 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:15:06,842 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:15:06,842 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:15:06,842 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:15:06,871 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:19:02,229 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:19:02,229 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:19:02,229 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:19:12,508 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:19:12,508 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:19:12,508 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:19:12,640 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:27:41,195 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:27:41,196 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:27:41,196 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:27:41,217 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:27:45,079 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:27:45,079 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:27:45,079 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:27:45,096 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:28:07,216 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:28:07,216 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:28:07,216 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:28:07,233 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:29:57,065 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:29:57,066 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:29:57,066 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:30:03,771 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:30:03,772 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:30:03,772 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:30:03,815 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:18,303 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:18,304 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:18,304 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:18,304 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:18,349 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:18,349 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:18,349 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:18,349 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:18,412 DEBUG [org.alfresco.module.vti.web.VtiFilter] User was authenticated successfully
16:41:18,412 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:18,412 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:18,412 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:18,412 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/'
16:41:18,462 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:18,462 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:18,462 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:18,462 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking user ticket
16:41:18,472 DEBUG [org.alfresco.module.vti.handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:18,472 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:18,472 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:18,472 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:18,472 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/alfresco'
16:41:19,112 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:19,112 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:19,113 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:19,113 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking user ticket
16:41:19,122 DEBUG [org.alfresco.module.vti.handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:19,123 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:19,123 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,123 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,123 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_inf.html'
16:41:19,123 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Action found for request
16:41:19,123 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Execute target action
16:41:19,196 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:19,197 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:19,197 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:19,216 DEBUG [org.alfresco.module.vti.web.VtiFilter] User was authenticated successfully
16:41:19,217 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:19,217 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,217 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,217 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_bin/shtml.dll/_vti_rpc'
16:41:19,217 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Action found for request
16:41:19,217 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Execute target action
16:41:19,229 DEBUG [org.alfresco.module.vti.web.actions.VtiBinAction] Executing vtiMethod: org.alfresco.module.vti.web.fp.ServerVersionMethod@407a8d92
16:41:19,229 DEBUG [org.alfresco.module.vti.web.fp.ServerVersionMethod] Start method execution. Method name: server version
16:41:19,229 DEBUG [org.alfresco.module.vti.web.fp.ServerVersionMethod] End of method execution. Method name: server version
16:41:19,352 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:19,352 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:19,352 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:19,373 DEBUG [org.alfresco.module.vti.web.VtiFilter] User was authenticated successfully
16:41:19,373 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:19,373 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,373 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,373 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_bin/shtml.dll/_vti_rpc'
16:41:19,373 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Action found for request
16:41:19,374 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Execute target action
16:41:19,374 DEBUG [org.alfresco.module.vti.web.actions.VtiBinAction] Executing vtiMethod: org.alfresco.module.vti.web.fp.UrlToWebUrlMethod@16039307
16:41:19,374 DEBUG [org.alfresco.module.vti.web.fp.UrlToWebUrlMethod] Start method execution. Method name: url to web url
16:41:19,378 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:19,487 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:19,487 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:19,487 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:19,487 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking user ticket
16:41:19,497 DEBUG [org.alfresco.module.vti.handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:19,497 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:19,498 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,498 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,498 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/'
16:41:19,544 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:19,544 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:19,544 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:19,545 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking user ticket
16:41:19,554 DEBUG [org.alfresco.module.vti.handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:19,554 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:19,554 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,554 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,554 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/alfresco'
16:41:19,760 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:19,760 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:19,760 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:19,760 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking user ticket
16:41:19,770 DEBUG [org.alfresco.module.vti.handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:19,770 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:19,770 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,770 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,770 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/'
16:41:19,876 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:19,876 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:19,876 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:19,897 DEBUG [org.alfresco.module.vti.web.VtiFilter] User was authenticated successfully
16:41:19,897 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:19,897 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,897 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,897 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_bin/shtml.dll/_vti_rpc'
16:41:19,897 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Action found for request
16:41:19,897 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Execute target action
16:41:19,898 DEBUG [org.alfresco.module.vti.web.actions.VtiBinAction] Executing vtiMethod: org.alfresco.module.vti.web.fp.UrlToWebUrlMethod@16039307
16:41:19,898 DEBUG [org.alfresco.module.vti.web.fp.UrlToWebUrlMethod] Start method execution. Method name: url to web url
16:41:19,901 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:20,001 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:20,001 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:20,001 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:20,001 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking user ticket
16:41:20,011 DEBUG [org.alfresco.module.vti.handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:20,011 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:20,011 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:20,011 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:20,011 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/'
16:41:20,055 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:20,055 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:20,056 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
16:41:20,056 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking user ticket
16:41:20,065 DEBUG [org.alfresco.module.vti.handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:20,065 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
16:41:20,065 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:20,065 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
16:41:20,065 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/alfresco'
16:41:20,130 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:20,131 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:20,131 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:20,131 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking is resource exist
16:41:20,148 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:31,196 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:31,196 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:31,196 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:31,196 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking is resource exist
16:41:31,214 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:35,097 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:35,097 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:35,097 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:35,097 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking is resource exist
16:41:43,867 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:43,867 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:43,867 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:43,867 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking is resource exist
16:41:43,885 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:43,946 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:43,947 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
16:41:43,947 DEBUG [org.alfresco.module.vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:43,947 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking is resource exist
16:41:43,964 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
17:16:15,046 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
17:16:15,046 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
17:16:15,046 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
17:16:21,366 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
17:16:21,366 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
17:16:21,367 DEBUG [org.alfresco.module.vti.web.VtiFilter] Session user is null. Authenticate user.
17:16:21,390 DEBUG [org.alfresco.module.vti.web.VtiFilter] User was authenticated successfully
17:16:21,390 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
17:16:21,390 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
17:16:21,390 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
17:16:21,390 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_bin/dws.asmx'
17:16:21,390 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Action found for request
17:16:21,390 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Execute target action
17:16:21,394 DEBUG [org.alfresco.module.vti.web.ws.CreateDwsEndpoint] SOAP method with name CreateDws is started.
17:16:21,416 DEBUG [org.alfresco.module.vti.handler] Resolved file info for '' is FileInfo[name=Sites, isFolder=true, nodeRef=workspace://SpacesStore/1359c545-72d8-454a-ad10-bfdde0936df3]
17:16:32,191 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking request for VTI or not
17:16:32,191 DEBUG [org.alfresco.module.vti.web.VtiFilter] Check authentication
17:16:32,191 DEBUG [org.alfresco.module.vti.web.VtiFilter] Checking user ticket
17:16:32,205 DEBUG [org.alfresco.module.vti.handler.alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
17:16:32,205 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Process request
17:16:32,205 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
17:16:32,205 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Specific rule not found
17:16:32,206 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/alfresco/_vti_bin/dws.asmx'
17:16:32,206 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Action found for request
17:16:32,206 DEBUG [org.alfresco.module.vti.web.VtiRequestDispatcher] Execute target action
17:16:32,207 DEBUG [org.alfresco.module.vti.web.ws.CreateDwsEndpoint] SOAP method with name CreateDws is started.
17:16:32,215 DEBUG [org.alfresco.module.vti.handler] Resolved file info for '' is FileInfo[name=Sites, isFolder=true, nodeRef=workspace://SpacesStore/1359c545-72d8-454a-ad10-bfdde0936df3]



catalina.out:



.
07:29:27,574 User:System INFO  [repo.module.ModuleServiceImpl] Starting module 'org.alfresco.module.vti' version 1.2.
07:29:27,852  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
07:29:27,879  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:29:28,293  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
07:29:28,293  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
07:29:28,316  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:29:28,398  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
07:29:28,399  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
07:29:28,425  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
07:29:28,425  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
07:29:28,425  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
07:29:28,526  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:29:28,791 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
07:29:28,820 User:System INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:29:28,979 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
07:29:28,995  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
07:29:29,366  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_15-b03; maximum heap size 455.125MB
07:29:29,367  WARN  [service.descriptor.DescriptorService] Alfresco JVM - WARNING - maximum heap size 455.125MB is less than recommended 512MB
07:29:29,367  INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.2.0 (r2 2440) schema 3300 - Originally installed version 3.2.0 (r2 2440) schema 3300
07:29:30,836  INFO  [module.vti.VtiServer] Vti server started successfully on port: 7070
07:30:00,385  INFO  [alfresco.util.OpenOfficeConnectionTester] The OpenOffice connection was re-established.
Dec 13, 2009 7:30:28 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive mobile.war
07:30:29,550  INFO  [alfresco.config.JBossEnabledWebApplicationContext] Refreshing org.alfresco.config.JBossEnabledWebApplicationContext@773f8807: display name [Root WebApplicationContext]; startup date [Sun Dec 13 07:30:29 UTC 2009]; root of context hierarchy
07:30:29,947  INFO  [alfresco.config.JBossEnabledWebApplicationContext] Bean factory for application context [org.alfresco.config.JBossEnabledWebApplicationContext@773f8807]: org.springframework.beans.factory.support.DefaultListableBeanFactory@604a8cdc
07:30:31,223  INFO  [web.scripts.DeclarativeRegistry] Registered 22 Web Scripts (+0 failed), 24 URLs
07:30:31,244  INFO  [web.scripts.AbstractRuntimeContainer] Initialised Presentation Web Script Container (in 268.82407ms)
07:30:31,380  INFO  [web.scripts.DeclarativeRegistry] Registered 40 Web Scripts (+0 failed), 42 URLs
07:30:31,401  INFO  [web.scripts.AbstractRuntimeContainer] Initialised WebFramework Web Script Container (in 152.36916ms)
07:30:31,448  INFO  [web.site.FrameworkHelper] Successfully Initialized Web Framework
Dec 13, 2009 7:30:31 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive share.war
07:30:34,878  INFO  [web.site.FrameworkHelper] Successfully Initialized Web Framework
Dec 13, 2009 7:30:35 AM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Dec 13, 2009 7:30:35 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 134187 ms
07:30:56,995  DEBUG [vti.web.VtiFilter] Checking request for VTI or not
07:30:57,016  DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
07:30:57,016  DEBUG [vti.web.VtiFilter] Check authentication
07:30:57,017  DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:14:58,954  DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:14:58,954  DEBUG [vti.web.VtiFilter] Check authentication
16:14:58,954  DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:15:06,842  DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:15:06,842  DEBUG [vti.web.VtiFilter] Check authentication
16:15:06,842  DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:15:06,871 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:19:02,229 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:19:02,229 User:meecect DEBUG [vti.web.VtiFilter] Check authentication
16:19:02,229 User:meecect DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:19:12,508 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:19:12,508 User:meecect DEBUG [vti.web.VtiFilter] Check authentication
16:19:12,508 User:meecect DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:19:12,640 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:27:41,195 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:27:41,196 User:meecect DEBUG [vti.web.VtiFilter] Check authentication
16:27:41,196 User:meecect DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:27:41,217 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:27:45,079 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:27:45,079 User:meecect DEBUG [vti.web.VtiFilter] Check authentication
16:27:45,079 User:meecect DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:27:45,096 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:28:07,216 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:28:07,216 User:meecect DEBUG [vti.web.VtiFilter] Check authentication
16:28:07,216 User:meecect DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:28:07,233 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:29:57,065 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:29:57,066 User:meecect DEBUG [vti.web.VtiFilter] Check authentication
16:29:57,066 User:meecect DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:30:03,771 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:30:03,772 User:meecect DEBUG [vti.web.VtiFilter] Check authentication
16:30:03,772 User:meecect DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:30:03,815 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:18,303 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:18,304 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:18,304 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:18,304 User:admin DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:18,349 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:18,349 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:18,349 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:18,349 User:admin DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:18,412 User:admin DEBUG [vti.web.VtiFilter] User was authenticated successfully
16:41:18,412 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:18,412 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:18,412 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:18,412 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/'
16:41:18,462 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:18,462 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:18,462 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:18,462 User:admin DEBUG [vti.web.VtiFilter] Checking user ticket
16:41:18,472 User:admin DEBUG [handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:18,472 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:18,472 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:18,472 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:18,472 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/alfresco'
16:41:19,112 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:19,112 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:19,113 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:19,113 User:admin DEBUG [vti.web.VtiFilter] Checking user ticket
16:41:19,122 User:admin DEBUG [handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:19,123 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:19,123 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,123 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,123 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_inf.html'
16:41:19,123 User:admin DEBUG [vti.web.VtiRequestDispatcher] Action found for request
16:41:19,123 User:admin DEBUG [vti.web.VtiRequestDispatcher] Execute target action
16:41:19,196 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:19,197 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:19,197 User:admin DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:19,216 User:admin DEBUG [vti.web.VtiFilter] User was authenticated successfully
16:41:19,217 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:19,217 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,217 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,217 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_bin/shtml.dll/_vti_rpc'
16:41:19,217 User:admin DEBUG [vti.web.VtiRequestDispatcher] Action found for request
16:41:19,217 User:admin DEBUG [vti.web.VtiRequestDispatcher] Execute target action
16:41:19,229 User:admin DEBUG [web.actions.VtiBinAction] Executing vtiMethod: org.alfresco.module.vti.web.fp.ServerVersionMethod@407a8d92
16:41:19,229 User:admin DEBUG [web.fp.ServerVersionMethod] Start method execution. Method name: server version
16:41:19,229 User:admin DEBUG [web.fp.ServerVersionMethod] End of method execution. Method name: server version
16:41:19,352 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:19,352 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:19,352 User:admin DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:19,373 User:admin DEBUG [vti.web.VtiFilter] User was authenticated successfully
16:41:19,373 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:19,373 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,373 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,373 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_bin/shtml.dll/_vti_rpc'
16:41:19,373 User:admin DEBUG [vti.web.VtiRequestDispatcher] Action found for request
16:41:19,374 User:admin DEBUG [vti.web.VtiRequestDispatcher] Execute target action
16:41:19,374 User:admin DEBUG [web.actions.VtiBinAction] Executing vtiMethod: org.alfresco.module.vti.web.fp.UrlToWebUrlMethod@16039307
16:41:19,374 User:admin DEBUG [web.fp.UrlToWebUrlMethod] Start method execution. Method name: url to web url
16:41:19,378 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:19,487 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:19,487 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:19,487 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:19,487 User:admin DEBUG [vti.web.VtiFilter] Checking user ticket
16:41:19,497 User:admin DEBUG [handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:19,497 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:19,498 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,498 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,498 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/'
16:41:19,544 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:19,544 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:19,544 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:19,545 User:admin DEBUG [vti.web.VtiFilter] Checking user ticket
16:41:19,554 User:admin DEBUG [handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:19,554 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:19,554 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,554 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,554 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/alfresco'
16:41:19,760 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:19,760 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:19,760 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:19,760 User:admin DEBUG [vti.web.VtiFilter] Checking user ticket
16:41:19,770 User:admin DEBUG [handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:19,770 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:19,770 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,770 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,770 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/'
16:41:19,876 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:19,876 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:19,876 User:admin DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
16:41:19,897 User:admin DEBUG [vti.web.VtiFilter] User was authenticated successfully
16:41:19,897 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:19,897 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:19,897 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:19,897 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_bin/shtml.dll/_vti_rpc'
16:41:19,897 User:admin DEBUG [vti.web.VtiRequestDispatcher] Action found for request
16:41:19,897 User:admin DEBUG [vti.web.VtiRequestDispatcher] Execute target action
16:41:19,898 User:admin DEBUG [web.actions.VtiBinAction] Executing vtiMethod: org.alfresco.module.vti.web.fp.UrlToWebUrlMethod@16039307
16:41:19,898 User:admin DEBUG [web.fp.UrlToWebUrlMethod] Start method execution. Method name: url to web url
16:41:19,901 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:20,001 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:20,001 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:20,001 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:20,001 User:admin DEBUG [vti.web.VtiFilter] Checking user ticket
16:41:20,011 User:admin DEBUG [handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:20,011 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:20,011 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:20,011 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:20,011 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/'
16:41:20,055 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:20,055 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for OPTIONS request
16:41:20,056 User:admin DEBUG [vti.web.VtiFilter] Check authentication
16:41:20,056 User:admin DEBUG [vti.web.VtiFilter] Checking user ticket
16:41:20,065 User:admin DEBUG [handler.alfresco.DefaultAuthenticationHandler] Ticket was validated
16:41:20,065 User:admin DEBUG [vti.web.VtiRequestDispatcher] Process request
16:41:20,065 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
16:41:20,065 User:admin DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
16:41:20,065 User:admin DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/alfresco'
16:41:20,130 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:20,131 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:20,131 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:20,131 User:admin DEBUG [vti.web.VtiFilter] Checking is resource exist
16:41:20,148 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:31,196 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:31,196 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:31,196 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:31,196 User:admin DEBUG [vti.web.VtiFilter] Checking is resource exist
16:41:31,214 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:35,097 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:35,097 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:35,097 User:meecect DEBUG [vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:35,097 User:meecect DEBUG [vti.web.VtiFilter] Checking is resource exist
16:41:43,867 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:43,867  DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:43,867  DEBUG [vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:43,867  DEBUG [vti.web.VtiFilter] Checking is resource exist
16:41:43,885 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:43,946 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
16:41:43,947 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
16:41:43,947 User:admin DEBUG [vti.web.VtiFilter] Return VTI answer for HEAD request
16:41:43,947 User:admin DEBUG [vti.web.VtiFilter] Checking is resource exist
16:41:43,964 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
17:16:15,046 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
17:16:15,046 User:admin DEBUG [vti.web.VtiFilter] Check authentication
17:16:15,046 User:admin DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
17:16:21,366 User:admin DEBUG [vti.web.VtiFilter] Checking request for VTI or not
17:16:21,366 User:admin DEBUG [vti.web.VtiFilter] Check authentication
17:16:21,367 User:admin DEBUG [vti.web.VtiFilter] Session user is null. Authenticate user.
17:16:21,390 User:meecect DEBUG [vti.web.VtiFilter] User was authenticated successfully
17:16:21,390 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Process request
17:16:21,390 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
17:16:21,390 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
17:16:21,390 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/_vti_bin/dws.asmx'
17:16:21,390 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Action found for request
17:16:21,390 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Execute target action
17:16:21,394 User:meecect DEBUG [web.ws.CreateDwsEndpoint] SOAP method with name CreateDws is started.
17:16:21,416 User:meecect DEBUG [module.vti.handler] Resolved file info for '' is FileInfo[name=Sites, isFolder=true, nodeRef=workspace://SpacesStore/1359c545-72d8-454a-ad10-bfdde0936df3]
17:16:32,191 User:meecect DEBUG [vti.web.VtiFilter] Checking request for VTI or not
17:16:32,191 User:meecect DEBUG [vti.web.VtiFilter] Check authentication
17:16:32,191 User:meecect DEBUG [vti.web.VtiFilter] Checking user ticket
17:16:32,205 User:System DEBUG [alfresco.v3.AlfrescoMethodHandler] Url must start with alfresco context.
17:16:32,205 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Process request
17:16:32,205 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by specific rules
17:16:32,205 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Specific rule not found
17:16:32,206 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Find appropriate action by pattern for uri='/alfresco/_vti_bin/dws.asmx'
17:16:32,206 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Action found for request
17:16:32,206 User:meecect DEBUG [vti.web.VtiRequestDispatcher] Execute target action
17:16:32,207 User:meecect DEBUG [web.ws.CreateDwsEndpoint] SOAP method with name CreateDws is started.
17:16:32,215 User:meecect DEBUG [module.vti.handler] Resolved file info for '' is FileInfo[name=Sites, isFolder=true, nodeRef=workspace://SpacesStore/1359c545-72d8-454a-ad10-bfdde0936df3]

Outcomes