Can't create sharepoint workspace

[meecect]

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]

‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

[iseyfi]

I am also having the same problem with MS WORD 2007 with same version of Alfresco 3.2.r2. If it helps anyone to solve the issue, I am using passthru and NTML (with out SSO - enabling SSO is breaking Share).

[rama_honge]

Does anyone find a solution/workaround for this issue?

[rama_honge]

Was able to create sharepoint workspace in MS Office 2007.

Added the following properties in alfresco-global.properties and restarted alfresco. I'm using basic NTLM authentication.

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

[iseyfi]

It turns out this was not a bug at the code level. My Vti configuration had a space at the end for Alfresco context setting.

If you look at the code that throws this error, you can quickly figure out why it is happening.

if (!url.startsWith(alfrescoContext))
                {
                    if (logger.isDebugEnabled())
                    {
                       logger.debug("Url:" + url);
                       logger.debug("Alfresco Context:" + alfrescoContext);
                        logger.debug("Url must start with alfresco context.");
                    }
                    throw new VtiHandlerException(VtiHandlerException.BAD_URL);
                }

[mikeh]

Thanks for the follow-up. That sounds like something we should be tolerant of - please raise a bug report in JIRA.

Thanks,
Mike

[xana_cloete]

Hi

I'm using EE 3.2
I've enabled the sharepoint module as explained on the website.
When I try to create a new workspace in office it starts the process then it quits and displays "Try Again Later"
What I find strange is the the Workspace get created under share sites but there is no document located there.
I don't know if this problem is related to this post.

I would appreciate if someone can help with this.

Thanks.

My settings in the vti.properties

vti.server.port=7070
vti.alfresco.deployment.context=/alfresco
vti.alfresco.alfresoHostWithPort=http://172.16.8.177:8080
vti.share.shareHostWithPort=http://172.16.8.177:8080
vti.share.shareContext=/share
vti.share.siteInBrowser=/page/site/…/dashboard
vti.share.siteSettings=/page/site/…/customise-site
vti.share.siteGroupMembership=/page/site/…/site-members
vti.share.userInformation=/page/user/…/profile
vti.share.documentLibrary=/page/site/…/documentlibrary
vti.share.documentDetails=/page/site/…/document-details

This is the error log at the point it fails.


3:41:30,055 User:admin ERROR [org.mortbay.log] Error for /alfresco/_vti_bin/dws.asmx
java.lang.NoSuchMethodError: org.alfresco.service.cmr.security.AuthenticationService.validate(Ljava/lang/StringV
   at org.alfresco.module.vti.handler.alfresco.DefaultAuthenticationHandler.checkUserTicket(DefaultAuthenticationHandler.java:71)
   at org.alfresco.module.vti.web.VtiFilter.doFilter(VtiFilter.java:161)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1115)
   at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:361)
   at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
   at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:766)
   at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
   at org.mortbay.jetty.Server.handle(Server.java:324)
   at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:534)
   at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:879)
   at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:741)
   at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:213)
   at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:403)
   at org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:228)
   at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:522)

[xana_cloete]

HI

Can I please get some news on this.
I've tried this on the nightly build community edition and it works fine.
I realy need a reliable working version of alfresco and at this point not even the enterprise edition seems to work correctly.

Is there a verion that I can use with these features all working correctly.
passthru
Sharepoint feature
share

ps * The nightly build you guys updated with NTLM + SSO works fine until you use ad auth and import users, after that it  breaks the share function again.

Please can I get an update on this

Thanks

[johna1]

Hi
I had almost identical symptoms with:

Alfresco 3.3 on Ubuntu 9.10
Passthru authentication with SSO (to Windows DCs)
Office 2003 on Windows XP

Applying Office 2003 SP3 resolved the problem.

Cheers,
John