Migration 3.2r vers 3.4d : accès aux sites Share impossible

cancel
Showing results for 
Search instead for 
Did you mean: 
tomasb
Member II

Migration 3.2r vers 3.4d : accès aux sites Share impossible

Bonjour à tous,

Je viens de tenter le passage de la 3.2r + module RM vers la 3.4d en respectant la procédure donnée dans le wiki. Sauf erreur de ma part, la migration semble s'être passée sans problèmes (cf. le log du premier lancement ci-dessous).

Le serveur tourne sous Windows 2008R2 avec 6Go. La version 3.2r fonctionnement parfaitement dessus.


Log premier lancement migration

20:01:25,443  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
20:01:25,446  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
20:01:25,447  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\webapps\alfresco\WEB-INF\classes\alfresco\module\org_alfresco_module_dod5015\alfresco-global.properties]
20:01:25,447  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\webapps\alfresco\WEB-INF\classes\alfresco\module\test\alfresco-global.properties]
20:01:25,447  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\webapps\alfresco\WEB-INF\classes\alfresco\module\tests\alfresco-global.properties]
20:01:25,447  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco-global.properties]
20:01:25,511  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:01:25,701  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:01:25,761  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:01:25,788  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:01:25,789  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:01:32,293  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\shared\classes\alfresco\extension\subsystems\Authentication\alfrescoNtlm\alfrescoNtlm1\alfresco-authentication.properties]
20:01:32,294  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\shared\classes\alfresco\extension\subsystems\Authentication\alfrescoNtlm\alfrescoNtlm1\ntlm-filter.properties]
20:01:32,298  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\shared\classes\alfresco\extension\subsystems\Authentication\ldap-ad\ldap-ad1\ldap-ad-authentication.properties]
20:01:32,307  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\shared\classes\alfresco\extension\subsystems\Authentication\passthru\passthru1\ntlm-filter.properties]
20:01:32,307  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\shared\classes\alfresco\extension\subsystems\Authentication\passthru\passthru1\passthru-authentication-context.properties]
20:01:37,205  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\shared\classes\alfresco\extension\subsystems\fileServers\default\default\file-servers.properties]
20:01:43,797  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
20:01:43,800  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
20:01:51,761  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
20:01:55,398  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-3609806081110883237.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.2/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoSchemaUpdate-3.2-ContentTables2.sql).
20:01:56,978  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-4665730059621518170.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/modify-index-permission_id.sql).
20:01:57,445  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2203451891221471263.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.2/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoSchemaUpdate-3.2-AddFKIndexes-2.sql).
20:01:57,989  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-1894743848245058974.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/remove-VersionCount.sql).
20:01:58,012  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-7868186780289454965.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/fix-Repo-seqs.sql).
20:01:58,020  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2410995450465383976.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/fix-AVM-seqs.sql).
20:01:58,029  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-1206095750741069926.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoPostCreate-JBPM-Extra.sql).
20:01:58,037  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2971992493042682222.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/node-prop-serializable.sql).
20:01:58,047  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-5074242320502768271.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/property-unique-ctx-value.sql).
20:01:58,128  INFO  [domain.schema.SchemaBootstrap] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-4697170712173394391.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/child-assoc-indexes.sql).
20:02:01,737  INFO  [domain.schema.SchemaBootstrap] All executed statements:  C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-All_Statements-5338179614371448620.sql.
20:02:04,802  INFO  [domain.schema.SchemaBootstrap] Normalized schema (pre-bootstrap)  dumped to file C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-3557112535689987824-Startup.xml.
20:02:04,802  INFO  [domain.schema.SchemaBootstrap] Normalized schema (post-bootstrap) dumped to file C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-2283450135135978912.xml.
20:02:04,812  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
20:02:04,827  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:02:04,827  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:02:04,828  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:02:04,828  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:02:04,828  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:02:04,863  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
20:02:07,883  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
20:02:07,903  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:02:07,903  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:02:07,905  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:02:07,905  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:02:07,905  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:02:08,024  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
20:02:08,025  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
20:02:08,038  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:02:08,038  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:02:08,039  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:02:08,039  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:02:08,039  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:02:09,608  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
20:02:10,098  INFO  [node.index.FullIndexRecoveryComponent] Index recovery started: 13 583 transactions.
false false null
20:02:33,809  INFO  [node.index.FullIndexRecoveryComponent]    10 % complete.
20:02:40,147  INFO  [node.index.FullIndexRecoveryComponent]    20 % complete.
20:02:47,839  INFO  [node.index.FullIndexRecoveryComponent]    30 % complete.
20:02:50,412  INFO  [node.index.FullIndexRecoveryComponent]    40 % complete.
20:02:57,055  INFO  [node.index.FullIndexRecoveryComponent]    50 % complete.
20:03:09,425  INFO  [node.index.FullIndexRecoveryComponent]    60 % complete.
20:03:15,698  INFO  [node.index.FullIndexRecoveryComponent]    70 % complete.
20:03:20,032  INFO  [node.index.FullIndexRecoveryComponent]    80 % complete.
20:03:27,673  INFO  [node.index.FullIndexRecoveryComponent]    90 % complete.
20:03:33,823  INFO  [node.index.FullIndexRecoveryComponent]    100 % complete.
20:03:36,463  INFO  [node.index.FullIndexRecoveryComponent] Index recovery completed.
20:03:36,494  INFO  [node.index.AVMFullIndexRecoveryComponent] Rebuilding indexes for 1 AVM Stores
20:03:36,494  INFO  [node.index.AVMFullIndexRecoveryComponent]     Rebuilding index for sitestore
20:03:36,562  INFO  [node.index.AVMFullIndexRecoveryComponent]   Reindex   100% complete
20:03:36,562  INFO  [node.index.AVMFullIndexRecoveryComponent] Finished rebuilding indexes for AVM Stores
20:03:36,746  INFO  [repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: D:\alf_data
20:03:36,753  INFO  [admin.patch.PatchExecuter] Checking for patches to apply …
20:03:37,084  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.redeployNominatedInvitationProcessWithPropsForShare' (Redeploy nominated invitation workflow).
20:03:37,220  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.rendition.rendering_actions' (Creates the Rendering Actions folder.).
20:03:37,414  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.thumbnailsAssocQName' (Update the 'cm:thumbnails' association QName to 'rn:rendition'.).
20:03:37,439  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.emailInviteAndNotifyTemplatesFolder' (Ensures the existence of the 'Email Invite Templates' and 'Email Notify Templates' folders.).
20:03:37,456  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.convertContentUrls' (Converts pre-3.2 content URLs to use the alf_content_data table.  The conversion work can also be done on a schedule; please contact Alfresco Support for further details.).
20:03:37,494 User:System INFO  [admin.patch.PatchExecuter] Content URL conversion progress:
20:03:37,494 User:System INFO  [admin.patch.PatchExecuter]    Processing ADM Content URLs.
20:03:39,558 User:System INFO  [admin.patch.PatchExecuter]    Finished processing ADM nodes up to ID 150 828.
20:03:39,570 User:System INFO  [admin.patch.PatchExecuter]    Processing AVM Content URLs.
20:03:44,353 User:System INFO  [admin.patch.PatchExecuter]    Finished processing AVM nodes up to ID 4 369.
20:03:44,365 User:System INFO  [admin.patch.PatchExecuter]    Reading content URLs from store FileContentStore[ root=D:\alf_data\contentstore, allowRandomAccess=true, readOnly=false].
20:04:58,613 User:System INFO  [admin.patch.PatchExecuter]       Processed 500 content URLs from store.
20:04:58,883 User:System INFO  [admin.patch.PatchExecuter]       Processed 1 000 content URLs from store.
20:04:59,143 User:System INFO  [admin.patch.PatchExecuter]       Processed 1 500 content URLs from store.
20:04:59,413 User:System INFO  [admin.patch.PatchExecuter]       Processed 2 000 content URLs from store.
20:04:59,709 User:System INFO  [admin.patch.PatchExecuter]       Processed 2 500 content URLs from store.
20:05:00,013 User:System INFO  [admin.patch.PatchExecuter]       Processed 3 000 content URLs from store.
20:05:00,490 User:System INFO  [admin.patch.PatchExecuter]       Processed 3 500 content URLs from store.
20:05:00,713 User:System INFO  [admin.patch.PatchExecuter]       Processed 4 000 content URLs from store.
20:05:00,998 User:System INFO  [admin.patch.PatchExecuter]       Processed 4 500 content URLs from store.
20:05:01,238 User:System INFO  [admin.patch.PatchExecuter]       Processed 5 000 content URLs from store.
20:05:01,460 User:System INFO  [admin.patch.PatchExecuter]       Processed 5 500 content URLs from store.
20:05:01,701 User:System INFO  [admin.patch.PatchExecuter]       Processed 6 000 content URLs from store.
20:05:01,960 User:System INFO  [admin.patch.PatchExecuter]       Processed 6 500 content URLs from store.
20:05:02,240 User:System INFO  [admin.patch.PatchExecuter]       Processed 7 000 content URLs from store.
20:05:02,573 User:System INFO  [admin.patch.PatchExecuter]       Processed 7 500 content URLs from store.
20:05:02,907 User:System INFO  [admin.patch.PatchExecuter]       Processed 8 000 content URLs from store.
20:05:03,172 User:System INFO  [admin.patch.PatchExecuter]       Processed 8 500 content URLs from store.
20:05:03,438 User:System INFO  [admin.patch.PatchExecuter]       Processed 9 000 content URLs from store.
20:05:03,703 User:System INFO  [admin.patch.PatchExecuter]       Processed 9 500 content URLs from store.
20:05:03,927 User:System INFO  [admin.patch.PatchExecuter]       Processed 10 000 content URLs from store.
20:05:04,156 User:System INFO  [admin.patch.PatchExecuter]       Processed 10 500 content URLs from store.
20:05:04,363 User:System INFO  [admin.patch.PatchExecuter]       Processed 11 000 content URLs from store.
20:05:04,561 User:System INFO  [admin.patch.PatchExecuter]       Processed 11 500 content URLs from store.
20:05:04,755 User:System INFO  [admin.patch.PatchExecuter]       Processed 12 000 content URLs from store.
20:05:04,953 User:System INFO  [admin.patch.PatchExecuter]       Processed 12 500 content URLs from store.
20:05:05,158 User:System INFO  [admin.patch.PatchExecuter]       Processed 13 000 content URLs from store.
20:05:05,356 User:System INFO  [admin.patch.PatchExecuter]       Processed 13 500 content URLs from store.
20:05:05,551 User:System INFO  [admin.patch.PatchExecuter]       Processed 14 000 content URLs from store.
20:05:05,744 User:System INFO  [admin.patch.PatchExecuter]       Processed 14 500 content URLs from store.
20:05:05,939 User:System INFO  [admin.patch.PatchExecuter]       Processed 15 000 content URLs from store.
20:05:06,134 User:System INFO  [admin.patch.PatchExecuter]       Processed 15 500 content URLs from store.
20:05:06,381 User:System INFO  [admin.patch.PatchExecuter]       Processed 16 000 content URLs from store.
20:05:06,580 User:System INFO  [admin.patch.PatchExecuter]       Processed 16 500 content URLs from store.
20:05:06,775 User:System INFO  [admin.patch.PatchExecuter]       Processed 17 000 content URLs from store.
20:05:06,968 User:System INFO  [admin.patch.PatchExecuter]       Processed 17 500 content URLs from store.
20:05:07,163 User:System INFO  [admin.patch.PatchExecuter]       Processed 18 000 content URLs from store.
20:05:07,357 User:System INFO  [admin.patch.PatchExecuter]       Processed 18 500 content URLs from store.
20:05:07,568 User:System INFO  [admin.patch.PatchExecuter]       Processed 19 000 content URLs from store.
20:05:07,766 User:System INFO  [admin.patch.PatchExecuter]       Processed 19 500 content URLs from store.
20:05:07,962 User:System INFO  [admin.patch.PatchExecuter]       Processed 20 000 content URLs from store.
20:05:08,157 User:System INFO  [admin.patch.PatchExecuter]       Processed 20 500 content URLs from store.
20:05:08,367 User:System INFO  [admin.patch.PatchExecuter]       Processed 21 000 content URLs from store.
20:05:08,578 User:System INFO  [admin.patch.PatchExecuter]       Processed 21 500 content URLs from store.
20:05:08,779 User:System INFO  [admin.patch.PatchExecuter]       Processed 22 000 content URLs from store.
20:05:08,984 User:System INFO  [admin.patch.PatchExecuter]       Processed 22 500 content URLs from store.
20:05:09,234 User:System INFO  [admin.patch.PatchExecuter]       Processed 23 000 content URLs from store.
20:05:09,460 User:System INFO  [admin.patch.PatchExecuter]       Processed 23 500 content URLs from store.
20:05:09,671 User:System INFO  [admin.patch.PatchExecuter]       Processed 24 000 content URLs from store.
20:05:09,916 User:System INFO  [admin.patch.PatchExecuter]       Processed 24 500 content URLs from store.
20:05:10,111 User:System INFO  [admin.patch.PatchExecuter]       Processed 25 000 content URLs from store.
20:05:10,313 User:System INFO  [admin.patch.PatchExecuter]       Processed 25 500 content URLs from store.
20:05:10,526 User:System INFO  [admin.patch.PatchExecuter]       Processed 26 000 content URLs from store.
20:05:10,742 User:System INFO  [admin.patch.PatchExecuter]       Processed 26 500 content URLs from store.
20:05:10,957 User:System INFO  [admin.patch.PatchExecuter]       Processed 27 000 content URLs from store.
20:05:11,158 User:System INFO  [admin.patch.PatchExecuter]       Processed 27 500 content URLs from store.
20:05:11,358 User:System INFO  [admin.patch.PatchExecuter]       Processed 28 000 content URLs from store.
20:05:11,558 User:System INFO  [admin.patch.PatchExecuter]       Processed 28 500 content URLs from store.
20:05:11,759 User:System INFO  [admin.patch.PatchExecuter]       Processed 29 000 content URLs from store.
20:05:11,990 User:System INFO  [admin.patch.PatchExecuter]       Processed 29 500 content URLs from store.
20:05:12,230 User:System INFO  [admin.patch.PatchExecuter]       Processed 30 000 content URLs from store.
20:05:12,479 User:System INFO  [admin.patch.PatchExecuter]       Processed 30 500 content URLs from store.
20:05:12,769 User:System INFO  [admin.patch.PatchExecuter]       Processed 31 000 content URLs from store.
20:05:13,034 User:System INFO  [admin.patch.PatchExecuter]       Processed 31 500 content URLs from store.
20:05:13,288 User:System INFO  [admin.patch.PatchExecuter]       Processed 32 000 content URLs from store.
20:05:13,541 User:System INFO  [admin.patch.PatchExecuter]       Processed 32 500 content URLs from store.
20:05:13,833 User:System INFO  [admin.patch.PatchExecuter]       Processed 33 000 content URLs from store.
20:05:14,029 User:System INFO  [admin.patch.PatchExecuter]       Processed 33 500 content URLs from store.
20:05:14,226 User:System INFO  [admin.patch.PatchExecuter]       Processed 34 000 content URLs from store.
20:05:14,430 User:System INFO  [admin.patch.PatchExecuter]       Processed 34 500 content URLs from store.
20:05:14,632 User:System INFO  [admin.patch.PatchExecuter]       Processed 35 000 content URLs from store.
20:05:14,876 User:System INFO  [admin.patch.PatchExecuter]       Processed 35 500 content URLs from store.
20:05:15,127 User:System INFO  [admin.patch.PatchExecuter]       Processed 36 000 content URLs from store.
20:05:15,333 User:System INFO  [admin.patch.PatchExecuter]       Processed 36 500 content URLs from store.
20:05:15,529 User:System INFO  [admin.patch.PatchExecuter]       Processed 37 000 content URLs from store.
20:05:15,750 User:System INFO  [admin.patch.PatchExecuter]       Processed 37 500 content URLs from store.
20:05:15,950 User:System INFO  [admin.patch.PatchExecuter]       Processed 38 000 content URLs from store.
20:05:16,146 User:System INFO  [admin.patch.PatchExecuter]       Processed 38 500 content URLs from store.
20:05:16,360 User:System INFO  [admin.patch.PatchExecuter]       Processed 39 000 content URLs from store.
20:05:16,573 User:System INFO  [admin.patch.PatchExecuter]       Processed 39 500 content URLs from store.
20:05:16,794 User:System INFO  [admin.patch.PatchExecuter]       Processed 40 000 content URLs from store.
20:05:17,048 User:System INFO  [admin.patch.PatchExecuter]       Processed 40 500 content URLs from store.
20:05:17,262 User:System INFO  [admin.patch.PatchExecuter]       Processed 41 000 content URLs from store.
20:05:17,514 User:System INFO  [admin.patch.PatchExecuter]       Processed 41 500 content URLs from store.
20:05:17,751 User:System INFO  [admin.patch.PatchExecuter]       Processed 42 000 content URLs from store.
20:05:17,982 User:System INFO  [admin.patch.PatchExecuter]       Processed 42 500 content URLs from store.
20:05:18,205 User:System INFO  [admin.patch.PatchExecuter]       Processed 43 000 content URLs from store.
20:05:18,474 User:System INFO  [admin.patch.PatchExecuter]       Processed 43 500 content URLs from store.
20:05:18,692 User:System INFO  [admin.patch.PatchExecuter]       Processed 44 000 content URLs from store.
20:05:18,909 User:System INFO  [admin.patch.PatchExecuter]       Processed 44 500 content URLs from store.
20:05:19,128 User:System INFO  [admin.patch.PatchExecuter]       Processed 45 000 content URLs from store.
20:05:19,344 User:System INFO  [admin.patch.PatchExecuter]       Processed 45 500 content URLs from store.
20:05:19,554 User:System INFO  [admin.patch.PatchExecuter]       Processed 46 000 content URLs from store.
20:05:19,755 User:System INFO  [admin.patch.PatchExecuter]       Processed 46 500 content URLs from store.
20:05:19,960 User:System INFO  [admin.patch.PatchExecuter]       Processed 47 000 content URLs from store.
20:05:20,165 User:System INFO  [admin.patch.PatchExecuter]       Processed 47 500 content URLs from store.
20:05:20,363 User:System INFO  [admin.patch.PatchExecuter]       Processed 48 000 content URLs from store.
20:05:20,559 User:System INFO  [admin.patch.PatchExecuter]       Processed 48 500 content URLs from store.
20:05:20,763 User:System INFO  [admin.patch.PatchExecuter]       Processed 49 000 content URLs from store.
20:05:21,032 User:System INFO  [admin.patch.PatchExecuter]       Processed 49 500 content URLs from store.
20:05:21,237 User:System INFO  [admin.patch.PatchExecuter]       Processed 50 000 content URLs from store.
20:05:21,438 User:System INFO  [admin.patch.PatchExecuter]       Processed 50 500 content URLs from store.
20:05:21,635 User:System INFO  [admin.patch.PatchExecuter]       Processed 51 000 content URLs from store.
20:05:21,831 User:System INFO  [admin.patch.PatchExecuter]       Processed 51 500 content URLs from store.
20:05:22,042 User:System INFO  [admin.patch.PatchExecuter]       Processed 52 000 content URLs from store.
20:05:22,243 User:System INFO  [admin.patch.PatchExecuter]       Processed 52 500 content URLs from store.
20:05:22,438 User:System INFO  [admin.patch.PatchExecuter]       Processed 53 000 content URLs from store.
20:05:22,637 User:System INFO  [admin.patch.PatchExecuter]       Processed 53 500 content URLs from store.
20:05:22,833 User:System INFO  [admin.patch.PatchExecuter]       Processed 54 000 content URLs from store.
20:05:23,034 User:System INFO  [admin.patch.PatchExecuter]       Processed 54 500 content URLs from store.
20:05:23,235 User:System INFO  [admin.patch.PatchExecuter]       Processed 55 000 content URLs from store.
20:05:23,436 User:System INFO  [admin.patch.PatchExecuter]       Processed 55 500 content URLs from store.
20:05:23,630 User:System INFO  [admin.patch.PatchExecuter]       Processed 56 000 content URLs from store.
20:05:23,827 User:System INFO  [admin.patch.PatchExecuter]       Processed 56 500 content URLs from store.
20:05:24,051 User:System INFO  [admin.patch.PatchExecuter]       Processed 57 000 content URLs from store.
20:05:24,265 User:System INFO  [admin.patch.PatchExecuter]       Processed 57 500 content URLs from store.
20:05:24,513 User:System INFO  [admin.patch.PatchExecuter]       Processed 58 000 content URLs from store.
20:05:24,712 User:System INFO  [admin.patch.PatchExecuter]       Processed 58 500 content URLs from store.
20:05:24,908 User:System INFO  [admin.patch.PatchExecuter]       Processed 59 000 content URLs from store.
20:05:25,101 User:System INFO  [admin.patch.PatchExecuter]       Processed 59 500 content URLs from store.
20:05:25,321 User:System INFO  [admin.patch.PatchExecuter]       Processed 60 000 content URLs from store.
20:05:25,559 User:System INFO  [admin.patch.PatchExecuter]       Processed 60 500 content URLs from store.
20:05:25,753 User:System INFO  [admin.patch.PatchExecuter]       Processed 61 000 content URLs from store.
20:05:25,947 User:System INFO  [admin.patch.PatchExecuter]       Processed 61 500 content URLs from store.
20:05:26,268 User:System INFO  [admin.patch.PatchExecuter]       Processed 62 000 content URLs from store.
20:05:26,478 User:System INFO  [admin.patch.PatchExecuter]       Processed 62 500 content URLs from store.
20:05:26,676 User:System INFO  [admin.patch.PatchExecuter]       Processed 63 000 content URLs from store.
20:05:26,875 User:System INFO  [admin.patch.PatchExecuter]       Processed 63 500 content URLs from store.
20:05:27,075 User:System INFO  [admin.patch.PatchExecuter]       Processed 64 000 content URLs from store.
20:05:27,273 User:System INFO  [admin.patch.PatchExecuter]       Processed 64 500 content URLs from store.
20:05:27,487 User:System INFO  [admin.patch.PatchExecuter]       Processed 65 000 content URLs from store.
20:05:27,690 User:System INFO  [admin.patch.PatchExecuter]       Processed 65 500 content URLs from store.
20:05:27,903 User:System INFO  [admin.patch.PatchExecuter]       Processed 66 000 content URLs from store.
20:05:28,159 User:System INFO  [admin.patch.PatchExecuter]       Processed 66 500 content URLs from store.
20:05:28,409 User:System INFO  [admin.patch.PatchExecuter]       Processed 67 000 content URLs from store.
20:05:28,660 User:System INFO  [admin.patch.PatchExecuter]       Processed 67 500 content URLs from store.
20:05:28,895 User:System INFO  [admin.patch.PatchExecuter]       Processed 68 000 content URLs from store.
20:05:29,175 User:System INFO  [admin.patch.PatchExecuter]       Processed 68 500 content URLs from store.
20:05:29,410 User:System INFO  [admin.patch.PatchExecuter]       Processed 69 000 content URLs from store.
20:05:29,623 User:System INFO  [admin.patch.PatchExecuter]       Processed 69 500 content URLs from store.
20:05:29,829 User:System INFO  [admin.patch.PatchExecuter]       Processed 70 000 content URLs from store.
20:05:30,030 User:System INFO  [admin.patch.PatchExecuter]       Processed 70 500 content URLs from store.
20:05:30,233 User:System INFO  [admin.patch.PatchExecuter]       Processed 71 000 content URLs from store.
20:05:30,431 User:System INFO  [admin.patch.PatchExecuter]       Processed 71 500 content URLs from store.
20:05:30,641 User:System INFO  [admin.patch.PatchExecuter]       Processed 72 000 content URLs from store.
20:05:30,845 User:System INFO  [admin.patch.PatchExecuter]       Processed 72 500 content URLs from store.
20:05:31,049 User:System INFO  [admin.patch.PatchExecuter]       Processed 73 000 content URLs from store.
20:05:31,288 User:System INFO  [admin.patch.PatchExecuter]       Processed 73 500 content URLs from store.
20:05:31,506 User:System INFO  [admin.patch.PatchExecuter]       Processed 74 000 content URLs from store.
20:05:31,748 User:System INFO  [admin.patch.PatchExecuter]       Processed 74 500 content URLs from store.
20:05:31,957 User:System INFO  [admin.patch.PatchExecuter]       Processed 75 000 content URLs from store.
20:05:32,183 User:System INFO  [admin.patch.PatchExecuter]       Processed 75 500 content URLs from store.
20:05:32,390 User:System INFO  [admin.patch.PatchExecuter]       Processed 76 000 content URLs from store.
20:05:32,592 User:System INFO  [admin.patch.PatchExecuter]       Processed 76 500 content URLs from store.
20:05:32,797 User:System INFO  [admin.patch.PatchExecuter]       Processed 77 000 content URLs from store.
20:05:32,991 User:System INFO  [admin.patch.PatchExecuter]       Processed 77 500 content URLs from store.
20:05:33,190 User:System INFO  [admin.patch.PatchExecuter]       Processed 78 000 content URLs from store.
20:05:33,390 User:System INFO  [admin.patch.PatchExecuter]       Processed 78 500 content URLs from store.
20:05:33,585 User:System INFO  [admin.patch.PatchExecuter]       Processed 79 000 content URLs from store.
20:05:33,849 User:System INFO  [admin.patch.PatchExecuter]       Processed 79 500 content URLs from store.
20:05:34,106 User:System INFO  [admin.patch.PatchExecuter]       Processed 80 000 content URLs from store.
20:05:34,339 User:System INFO  [admin.patch.PatchExecuter]       Processed 80 500 content URLs from store.
20:05:34,538 User:System INFO  [admin.patch.PatchExecuter]       Processed 81 000 content URLs from store.
20:05:34,731 User:System INFO  [admin.patch.PatchExecuter]       Processed 81 500 content URLs from store.
20:05:34,925 User:System INFO  [admin.patch.PatchExecuter]       Processed 82 000 content URLs from store.
20:05:35,116 User:System INFO  [admin.patch.PatchExecuter]       Processed 82 500 content URLs from store.
20:05:35,365 User:System INFO  [admin.patch.PatchExecuter]       Processed 83 000 content URLs from store.
20:05:35,569 User:System INFO  [admin.patch.PatchExecuter]       Processed 83 500 content URLs from store.
20:05:35,763 User:System INFO  [admin.patch.PatchExecuter]       Processed 84 000 content URLs from store.
20:05:35,957 User:System INFO  [admin.patch.PatchExecuter]       Processed 84 500 content URLs from store.
20:05:36,155 User:System INFO  [admin.patch.PatchExecuter]       Processed 85 000 content URLs from store.
20:05:36,369 User:System INFO  [admin.patch.PatchExecuter]       Processed 85 500 content URLs from store.
20:05:36,590 User:System INFO  [admin.patch.PatchExecuter]       Processed 86 000 content URLs from store.
20:05:36,789 User:System INFO  [admin.patch.PatchExecuter]       Processed 86 500 content URLs from store.
20:05:36,989 User:System INFO  [admin.patch.PatchExecuter]       Processed 87 000 content URLs from store.
20:05:37,186 User:System INFO  [admin.patch.PatchExecuter]       Processed 87 500 content URLs from store.
20:05:37,387 User:System INFO  [admin.patch.PatchExecuter]       Processed 88 000 content URLs from store.
20:05:37,588 User:System INFO  [admin.patch.PatchExecuter]       Processed 88 500 content URLs from store.
20:05:37,787 User:System INFO  [admin.patch.PatchExecuter]       Processed 89 000 content URLs from store.
20:05:37,989 User:System INFO  [admin.patch.PatchExecuter]       Processed 89 500 content URLs from store.
20:05:38,183 User:System INFO  [admin.patch.PatchExecuter]       Processed 90 000 content URLs from store.
20:05:38,378 User:System INFO  [admin.patch.PatchExecuter]       Processed 90 500 content URLs from store.
20:05:38,590 User:System INFO  [admin.patch.PatchExecuter]       Processed 91 000 content URLs from store.
20:05:38,830 User:System INFO  [admin.patch.PatchExecuter]       Processed 91 500 content URLs from store.
20:05:39,032 User:System INFO  [admin.patch.PatchExecuter]       Processed 92 000 content URLs from store.
20:05:39,225 User:System INFO  [admin.patch.PatchExecuter]       Processed 92 500 content URLs from store.
20:05:39,424 User:System INFO  [admin.patch.PatchExecuter]       Processed 93 000 content URLs from store.
20:05:39,639 User:System INFO  [admin.patch.PatchExecuter]       Processed 93 500 content URLs from store.
20:05:39,917 User:System INFO  [admin.patch.PatchExecuter]       Processed 94 000 content URLs from store.
20:05:40,168 User:System INFO  [admin.patch.PatchExecuter]       Processed 94 500 content URLs from store.
20:05:40,384 User:System INFO  [admin.patch.PatchExecuter]       Processed 95 000 content URLs from store.
20:05:40,588 User:System INFO  [admin.patch.PatchExecuter]       Processed 95 500 content URLs from store.
20:05:40,828 User:System INFO  [admin.patch.PatchExecuter]       Processed 96 000 content URLs from store.
20:05:41,055 User:System INFO  [admin.patch.PatchExecuter]       Processed 96 500 content URLs from store.
20:05:41,256 User:System INFO  [admin.patch.PatchExecuter]       Processed 97 000 content URLs from store.
20:05:41,463 User:System INFO  [admin.patch.PatchExecuter]       Processed 97 500 content URLs from store.
20:05:41,659 User:System INFO  [admin.patch.PatchExecuter]       Processed 98 000 content URLs from store.
20:05:41,851 User:System INFO  [admin.patch.PatchExecuter]       Processed 98 500 content URLs from store.
20:05:42,047 User:System INFO  [admin.patch.PatchExecuter]       Processed 99 000 content URLs from store.
20:05:42,239 User:System INFO  [admin.patch.PatchExecuter]       Processed 99 500 content URLs from store.
20:05:42,508 User:System INFO  [admin.patch.PatchExecuter]       Processed 100 000 content URLs from store.
20:05:42,704 User:System INFO  [admin.patch.PatchExecuter]       Processed 100 500 content URLs from store.
20:05:42,908 User:System INFO  [admin.patch.PatchExecuter]       Processed 101 000 content URLs from store.
20:05:43,107 User:System INFO  [admin.patch.PatchExecuter]       Processed 101 500 content URLs from store.
20:05:43,304 User:System INFO  [admin.patch.PatchExecuter]       Processed 102 000 content URLs from store.
20:05:43,559 User:System INFO  [admin.patch.PatchExecuter]       Processed 102 500 content URLs from store.
20:05:43,818 User:System INFO  [admin.patch.PatchExecuter]       Processed 103 000 content URLs from store.
20:05:44,099 User:System INFO  [admin.patch.PatchExecuter]       Processed 103 500 content URLs from store.
20:05:44,354 User:System INFO  [admin.patch.PatchExecuter]       Processed 104 000 content URLs from store.
20:05:44,617 User:System INFO  [admin.patch.PatchExecuter]       Processed 104 500 content URLs from store.
20:05:44,872 User:System INFO  [admin.patch.PatchExecuter]       Processed 105 000 content URLs from store.
20:05:45,128 User:System INFO  [admin.patch.PatchExecuter]       Processed 105 500 content URLs from store.
20:05:45,398 User:System INFO  [admin.patch.PatchExecuter]       Processed 106 000 content URLs from store.
20:05:45,653 User:System INFO  [admin.patch.PatchExecuter]       Processed 106 500 content URLs from store.
20:05:45,904 User:System INFO  [admin.patch.PatchExecuter]       Processed 107 000 content URLs from store.
20:05:46,159 User:System INFO  [admin.patch.PatchExecuter]       Processed 107 500 content URLs from store.
20:05:46,415 User:System INFO  [admin.patch.PatchExecuter]       Processed 108 000 content URLs from store.
20:05:46,697 User:System INFO  [admin.patch.PatchExecuter]       Processed 108 500 content URLs from store.
20:05:46,919 User:System INFO  [admin.patch.PatchExecuter]       Processed 109 000 content URLs from store.
20:05:47,153 User:System INFO  [admin.patch.PatchExecuter]       Processed 109 500 content URLs from store.
20:05:47,386 User:System INFO  [admin.patch.PatchExecuter]       Processed 110 000 content URLs from store.
20:05:47,625 User:System INFO  [admin.patch.PatchExecuter]       Processed 110 500 content URLs from store.
20:05:47,873 User:System INFO  [admin.patch.PatchExecuter]       Processed 111 000 content URLs from store.
20:05:48,106 User:System INFO  [admin.patch.PatchExecuter]       Processed 111 500 content URLs from store.
20:05:48,334 User:System INFO  [admin.patch.PatchExecuter]       Processed 112 000 content URLs from store.
20:05:48,567 User:System INFO  [admin.patch.PatchExecuter]       Processed 112 500 content URLs from store.
20:05:48,781 User:System INFO  [admin.patch.PatchExecuter]       Processed 113 000 content URLs from store.
20:05:49,000 User:System INFO  [admin.patch.PatchExecuter]       Processed 113 500 content URLs from store.
20:05:49,216 User:System INFO  [admin.patch.PatchExecuter]       Processed 114 000 content URLs from store.
20:05:49,431 User:System INFO  [admin.patch.PatchExecuter]       Processed 114 500 content URLs from store.
20:05:49,632 User:System INFO  [admin.patch.PatchExecuter]       Processed 115 000 content URLs from store.
20:05:49,831 User:System INFO  [admin.patch.PatchExecuter]       Processed 115 500 content URLs from store.
20:05:50,033 User:System INFO  [admin.patch.PatchExecuter]       Processed 116 000 content URLs from store.
20:05:50,254 User:System INFO  [admin.patch.PatchExecuter]       Processed 116 500 content URLs from store.
20:05:50,497 User:System INFO  [admin.patch.PatchExecuter]       Processed 117 000 content URLs from store.
20:05:52,102 User:System INFO  [admin.patch.PatchExecuter]       Processed 117 500 content URLs from store.
20:05:52,294 User:System INFO  [admin.patch.PatchExecuter]       Processed 118 000 content URLs from store.
20:05:52,537 User:System INFO  [admin.patch.PatchExecuter]       Processed 118 500 content URLs from store.
20:05:52,741 User:System INFO  [admin.patch.PatchExecuter]       Processed 119 000 content URLs from store.
20:05:52,939 User:System INFO  [admin.patch.PatchExecuter]       Processed 119 500 content URLs from store.
20:05:53,149 User:System INFO  [admin.patch.PatchExecuter]       Processed 120 000 content URLs from store.
20:05:53,384 User:System INFO  [admin.patch.PatchExecuter]       Processed 120 500 content URLs from store.
20:05:53,618 User:System INFO  [admin.patch.PatchExecuter]       Processed 121 000 content URLs from store.
20:05:53,824 User:System INFO  [admin.patch.PatchExecuter]       Processed 121 500 content URLs from store.
20:05:54,075 User:System INFO  [admin.patch.PatchExecuter]       Processed 122 000 content URLs from store.
20:05:54,353 User:System INFO  [admin.patch.PatchExecuter]       Processed 122 500 content URLs from store.
20:05:54,608 User:System INFO  [admin.patch.PatchExecuter]       Processed 123 000 content URLs from store.
20:05:54,821 User:System INFO  [admin.patch.PatchExecuter]       Processed 123 500 content URLs from store.
20:05:55,035 User:System INFO  [admin.patch.PatchExecuter]       Processed 124 000 content URLs from store.
20:05:55,248 User:System INFO  [admin.patch.PatchExecuter]       Processed 124 500 content URLs from store.
20:05:55,514 User:System INFO  [admin.patch.PatchExecuter]       Processed 125 000 content URLs from store.
20:05:55,748 User:System INFO  [admin.patch.PatchExecuter]       Processed 125 500 content URLs from store.
20:05:55,991 User:System INFO  [admin.patch.PatchExecuter]       Processed 126 000 content URLs from store.
20:05:56,226 User:System INFO  [admin.patch.PatchExecuter]       Processed 126 500 content URLs from store.
20:05:56,462 User:System INFO  [admin.patch.PatchExecuter]       Processed 127 000 content URLs from store.
20:05:56,719 User:System INFO  [admin.patch.PatchExecuter]       Processed 127 500 content URLs from store.
20:05:56,944 User:System INFO  [admin.patch.PatchExecuter]       Processed 128 000 content URLs from store.
20:05:57,143 User:System INFO  [admin.patch.PatchExecuter]       Processed 128 500 content URLs from store.
20:05:57,376 User:System INFO  [admin.patch.PatchExecuter]       Processed 129 000 content URLs from store.
20:05:57,579 User:System INFO  [admin.patch.PatchExecuter]       Processed 129 500 content URLs from store.
20:05:57,814 User:System INFO  [admin.patch.PatchExecuter]       Processed 130 000 content URLs from store.
20:05:58,089 User:System INFO  [admin.patch.PatchExecuter]       Processed 130 500 content URLs from store.
20:05:58,364 User:System INFO  [admin.patch.PatchExecuter]       Processed 131 000 content URLs from store.
20:05:58,616 User:System INFO  [admin.patch.PatchExecuter]       Processed 131 500 content URLs from store.
20:05:58,876 User:System INFO  [admin.patch.PatchExecuter]       Processed 132 000 content URLs from store.
20:05:59,147 User:System INFO  [admin.patch.PatchExecuter]       Processed 132 500 content URLs from store.
20:05:59,388 User:System INFO  [admin.patch.PatchExecuter]       Processed 133 000 content URLs from store.
20:05:59,641 User:System INFO  [admin.patch.PatchExecuter]       Processed 133 500 content URLs from store.
20:05:59,878 User:System INFO  [admin.patch.PatchExecuter]       Processed 134 000 content URLs from store.
20:06:00,079 User:System INFO  [admin.patch.PatchExecuter]       Processed 134 500 content URLs from store.
20:06:00,357 User:System INFO  [admin.patch.PatchExecuter]       Processed 135 000 content URLs from store.
20:06:00,592 User:System INFO  [admin.patch.PatchExecuter]       Processed 135 500 content URLs from store.
20:06:00,824 User:System INFO  [admin.patch.PatchExecuter]       Processed 136 000 content URLs from store.
20:06:01,057 User:System INFO  [admin.patch.PatchExecuter]       Processed 136 500 content URLs from store.
20:06:01,321 User:System INFO  [admin.patch.PatchExecuter]       Processed 137 000 content URLs from store.
20:06:01,538 User:System INFO  [admin.patch.PatchExecuter]       Processed 137 500 content URLs from store.
20:06:01,782 User:System INFO  [admin.patch.PatchExecuter]       Processed 138 000 content URLs from store.
20:06:02,019 User:System INFO  [admin.patch.PatchExecuter]       Processed 138 500 content URLs from store.
20:06:02,262 User:System INFO  [admin.patch.PatchExecuter]       Processed 139 000 content URLs from store.
20:06:02,525 User:System INFO  [admin.patch.PatchExecuter]       Processed 139 500 content URLs from store.
20:06:02,755 User:System INFO  [admin.patch.PatchExecuter]       Processed 140 000 content URLs from store.
20:06:02,995 User:System INFO  [admin.patch.PatchExecuter]       Processed 140 500 content URLs from store.
20:06:03,259 User:System INFO  [admin.patch.PatchExecuter]       Processed 141 000 content URLs from store.
20:06:03,480 User:System INFO  [admin.patch.PatchExecuter]       Processed 141 500 content URLs from store.
20:06:03,716 User:System INFO  [admin.patch.PatchExecuter]       Processed 142 000 content URLs from store.
20:06:03,952 User:System INFO  [admin.patch.PatchExecuter]       Processed 142 500 content URLs from store.
20:06:04,181 User:System INFO  [admin.patch.PatchExecuter]       Processed 143 000 content URLs from store.
20:06:04,444 User:System INFO  [admin.patch.PatchExecuter]       Processed 143 500 content URLs from store.
20:06:04,710 User:System INFO  [admin.patch.PatchExecuter]       Processed 144 000 content URLs from store.
20:06:04,946 User:System INFO  [admin.patch.PatchExecuter]       Processed 144 500 content URLs from store.
20:06:05,227 User:System INFO  [admin.patch.PatchExecuter]    Scheduled 127 057 content URLs for deletion from store: FileContentStore[ root=D:\alf_data\contentstore, allowRandomAccess=true, readOnly=false]
20:06:05,345  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.transferServiceFolder' (Add transfer definitions folder to data dictionary.).
20:06:05,556  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.fixAuthoritiesCrcValues' (Fixes authority CRC32 values to match UTF-8 encoding.).
20:06:05,618 User:System INFO  [patch.impl.FixAuthoritiesCrcValuesPatch] FixAuthorityCrcValuesPatch: Commencing batch of 25 entries
20:06:05,680 User:System INFO  [patch.impl.FixAuthoritiesCrcValuesPatch] FixAuthorityCrcValuesPatch: Processed 25 entries out of 25. 100 % complete. Rate: 403 per second. 0 failures detected.
20:06:05,681 User:System INFO  [patch.impl.FixAuthoritiesCrcValuesPatch] FixAuthorityCrcValuesPatch: Completed batch of 25 entries
20:06:05,683  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.updateMimetypes1' (Fix mimetypes for Excel and Powerpoint.).
20:06:08,746  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.eliminateDuplicates' (Fixes ALF-4203: Searches for AVM duplicate nodes and changes their name).
20:06:08,858  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrAVMLocks' (Migrate old AVM Lock attributes).
20:06:08,886  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrPropBackedBeans' (Migrate old Property-Backed Bean component attributes).
20:06:08,890  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrChainingURS' (Migrate old Chaining User Registry Synchronizer attributes).
20:06:09,051 User:System INFO  [patch.impl.MigrateAttrChainingURSPatch] Processed 4 Chaining URS attrs in 0 secs
20:06:09,056  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrDelete' (Delete old attributes (if any) after they have been migrated).
20:06:09,058 User:System INFO  [patch.ibatis.PatchDAOImpl] Deleted 0 rows from alf_list_attribute_entries
20:06:09,059 User:System INFO  [patch.ibatis.PatchDAOImpl] Deleted 7 rows from alf_map_attribute_entries
20:06:09,060 User:System INFO  [patch.ibatis.PatchDAOImpl] Deleted 2 rows from alf_global_attributes
20:06:09,073 User:System INFO  [patch.ibatis.PatchDAOImpl] Deleted 11 rows from alf_attributes
20:06:09,078  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.replication.replication_actions' (Creates the Replication Actions folder.).
20:06:09,212  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.transfer.targetrulefolder' (Creates the transfer target rule folder for the default transfer group.).
20:06:09,430  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.transfer.targetrule' (Creates the transfer target rule for the default transfer group.).
20:06:09,684  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.actions.scheduledfolder' (Creates the scheduled actions folder in the Data Dictionary.).
20:06:10,290  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.updateMimetypes2' (Fix mimetypes for Excel and Powerpoint.).
20:06:12,101  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-ContentTables2
RESULT:
Script completed
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.redeployNominatedInvitationProcessWithPropsForShare
RESULT:
Re-deployed 1 workflows.
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.3-Remove-VersionCount
RESULT:
Script completed
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.rendition.rendering_actions
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/8ac9d4c4-7cfe-4930-8cbb-b88febc09f84)
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.thumbnailsAssocQName
RESULT:
Successfully updated the '{http://www.alfresco.org/model/content/1.0}thumbnails' QName to '{http://www.alfresco.org/model/rendition/1.0}rendition'.
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.emailInviteAndNotifyTemplatesFolder
RESULT:
The Email Notify Templates folder already exists: workspace://SpacesStore/9a203494-67d5-4bd4-8ef0-c2d1bc53c2e5; The Email Invite Templates folder already exists: workspace://SpacesStore/9a203494-67d5-4bd4-8ef0-c2d1bc53c2e5
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.convertContentUrls
RESULT:
Content URL conversion completed.
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.transferServiceFolder
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/8ac9d4c4-7cfe-4930-8cbb-b88febc09f84)
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.fixAuthoritiesCrcValues
RESULT:
Fixed CRC32 values for UTF-8 encoding for 25 authorities.  See file C:\Windows\TEMP\Alfresco\longLife_patches\FixAuthorityCrcValuesPatch.log for details.
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.updateMimetypes1
RESULT:
Updating mimetypes.
   Updated 862 content references with mimetype 'application/vnd.excel' to use 'application/vnd.ms-excel'.
   Indexed 525 nodes in store 'workspace://SpacesStore'.
   Indexed 577 nodes in store 'archive://SpacesStore'.
   Updated 136 content references with mimetype 'application/vnd.powerpoint' to use 'application/vnd.ms-powerpoint'.
   Indexed 117 nodes in store 'workspace://SpacesStore'.
   Indexed 132 nodes in store 'archive://SpacesStore'.
   Finished updating mimetypes.
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.3-modify-index-permission_id
RESULT:
Script completed
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.3-Fix-AVM-Seqs
RESULT:
Script completed
=====================================
20:06:12,102  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.3-Fix-Repo-Seqs
RESULT:
Script completed
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.4-child-assoc-indexes
RESULT:
Script completed
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.4-property-unique-ctx-value
RESULT:
Script completed
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.3-JBPM-Extra
RESULT:
Script completed
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.3-Node-Prop-Serializable
RESULT:
Script completed
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.migrateAttrDelete
RESULT:
Old attributes were deleted (if any)
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.migrateAttrAVMLocks
RESULT:
Processed 0 attributes
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.migrateAttrChainingURS
RESULT:
Processed 4 attributes
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.migrateAttrPropBackedBeans
RESULT:
Processed 0 attributes (0 properties)
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.replication.replication_actions
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/8ac9d4c4-7cfe-4930-8cbb-b88febc09f84)
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.transfer.targetrule
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary/app:transfers/app:transfer_groups/cm:default/rule:ruleFolder (workspace://SpacesStore/8ac9d4c4-7cfe-4930-8cbb-b88febc09f84)
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.transfer.targetrulefolder
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary/app:transfers/app:transfer_groups/cm:default (workspace://SpacesStore/8ac9d4c4-7cfe-4930-8cbb-b88febc09f84)
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.actions.scheduledfolder
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/8ac9d4c4-7cfe-4930-8cbb-b88febc09f84)
=====================================
20:06:12,103  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.updateMimetypes2
RESULT:
Updating mimetypes.
   Updated 0 content references with mimetype 'application/vnd.excel' to use 'application/vnd.ms-excel'.
   Indexed 525 nodes in store 'workspace://SpacesStore'.
   Indexed 577 nodes in store 'archive://SpacesStore'.
   Updated 0 content references with mimetype 'application/vnd.powerpoint' to use 'application/vnd.ms-powerpoint'.
   Indexed 117 nodes in store 'workspace://SpacesStore'.
   Indexed 132 nodes in store 'archive://SpacesStore'.
   Finished updating mimetypes.
=====================================
20:06:12,104  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-AddFKIndexes-2
RESULT:
Script completed
=====================================
20:06:12,104  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.eliminateDuplicates
RESULT:
0 duplicate group(s) were found
0 affected node entries
—————-
Total renamed: 0

=====================================
20:06:12,117 User:System INFO  [repo.module.ModuleServiceImpl] Found 2 module(s).
20:06:12,193 User:System INFO  [repo.module.ModuleServiceImpl] Starting module 'org_alfresco_module_dod5015' version 1.0.
20:06:12,228 User:System INFO  [repo.module.ModuleServiceImpl] Starting module 'org.alfresco.module.vti' version 1.2.
20:06:12,246 User:System WARN  [repo.module.ModuleServiceImpl] A previously-installed module 'com.atolcd.alfresco.audit' (version 1.1.0) is not present in your distribution.
20:06:12,250  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
20:06:12,274  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:12,274  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:12,275  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:12,275  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:12,275  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:12,550  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
20:06:12,576  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:12,577  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:12,577  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:12,577  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:12,577  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:12,687  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
20:06:12,687  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, ldap-ad1]
20:06:12,711  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:12,712  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:12,712  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:12,712  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:12,712  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:12,866  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, ldap-ad1] complete
20:06:12,866  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, passthru1]
20:06:12,915  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:12,916  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:12,916  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:12,917  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:12,917  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:12,982  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, passthru1] complete
20:06:13,045  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
20:06:13,046  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
20:06:13,059  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:13,060  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:13,060  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:13,060  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:13,060  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:13,140  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
20:06:13,140  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, outbound]
20:06:13,152  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:13,153  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:13,154  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:13,154  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:13,154  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:13,213  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, outbound] complete
20:06:13,213  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, inbound]
20:06:13,226  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:13,230  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:13,231  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:13,231  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:13,231  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:13,279  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'blockedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.lang.String)]: [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.util.List)]
20:06:13,279  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'allowedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.util.List)]: [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.lang.String)]
20:06:13,305  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, inbound] complete
20:06:13,305  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'googledocs' subsystem, ID: [googledocs, default]
20:06:13,332  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:13,332  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:13,333  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:13,333  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:13,333  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:13,683  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
20:06:13,683  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
20:06:14,260  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
20:06:14,261  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
20:06:14,261  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
20:06:14,273  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:14,274  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:14,274  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:14,274  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:14,274  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:14,310 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] Synchronizing users and groups with user registry 'ldap-ad1'
20:06:14,324 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] Retrieving groups changed since 23 mai 2011 11:02:56 from user registry 'ldap-ad1'
20:06:14,391 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] ldap-ad1 Group Analysis: Commencing batch of 0 entries
20:06:14,391 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] ldap-ad1 Group Analysis: Completed batch of 0 entries
20:06:14,394 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] Retrieving users changed since 25 mai 2011 14:31:45 from user registry 'ldap-ad1'
20:06:14,543 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] ldap-ad1 User Creation and Association: Commencing batch of 1 entries
20:06:14,843 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] ldap-ad1 User Creation and Association: Processed 1 entries out of 1. 100 % complete. Rate: 3 per second. 0 failures detected.
20:06:14,843 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] ldap-ad1 User Creation and Association: Completed batch of 1 entries
20:06:14,925 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] Finished synchronizing users and groups with user registry 'ldap-ad1'
20:06:14,925 User:System INFO  [security.sync.ChainingUserRegistrySynchronizer] 1 user(s) and 0 group(s) processed
20:06:14,935  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
20:06:14,969  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_18-b07; maximum heap size 730,688MB
20:06:14,969  INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.4.0 (d 3370) schema 4113 - Originally installed version 3.2.0 (r 2384) schema 3003
20:06:14,970  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Replication' subsystem, ID: [Replication, default]
20:06:14,982  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
20:06:14,982  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:06:14,982  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
20:06:14,983  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
20:06:14,983  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
20:06:14,989  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Replication' subsystem, ID: [Replication, default] complete
20:06:18,088  INFO  [module.vti.VtiServer] Vti server started successfully on port: 7070
20:06:32,226 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 440 Web Scripts (+0 failed), 691 URLs
20:06:32,227 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 2 Package Description Documents (+0 failed)
20:06:32,227 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 1 Schema Description Documents (+0 failed)
20:06:32,230 User:System INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Repository Web Script Container (in 13662.946ms)
20:06:32,241  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
20:06:32,243  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
20:06:40,578  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 305 Web Scripts (+0 failed), 315 URLs
20:06:40,580  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 8 Package Description Documents (+0 failed)
20:06:40,580  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 0 Schema Description Documents (+0 failed)
20:06:40,814  INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Spring Surf Container Web Script Container (in 2500.4746ms)
20:06:40,861  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
20:06:40,971  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
20:06:41,287  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
20:06:41,291  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
20:06:41,497  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
20:06:41,501  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
false false null
false false null

Je parviens à me connecter à Share normalement, mais la dashlet "Mes sites"  est vide alors que je devrais avoir une dizaine de sites.
Lorsque je fais une recherche de site, le message suivant apparaît

Échec de la récupération des informations d'appartenance relatives à l'utilisateur actuel. Les boutons Rejoindre et Quitter ne seront pas disponibles.

Avec les logs suivants :


20:53:48,646 User:admin ERROR [freemarker.runtime] Method public org.alfresco.repo.jscript.ScriptableHashMap org.alfresco.repo.site.script.Site.listMembers(java.lang.String,java.lang.String,int) threw an exception when invoked on org.alfresco.repo.site.script.Site@596c9523

Method public org.alfresco.repo.jscript.ScriptableHashMap org.alfresco.repo.site.script.Site.listMembers(java.lang.String,java.lang.String,int) threw an exception when invoked on org.alfresco.repo.site.script.Site@596c9523
The problematic instruction:
———-
==> assignment: managers=site.listMembers(null, "SiteManager", 0)?keys [on line 32, column 17 in org/alfresco/repository/site/site.lib.ftl]
in user-directive siteLib.siteJSON [on line 5, column 17 in org/alfresco/repository/person/person.sites.get.json.ftl]
———-

Java backtrace for programmers:
———-
freemarker.template.TemplateModelException: Method public org.alfresco.repo.jscript.ScriptableHashMap org.alfresco.repo.site.script.Site.listMembers(java.lang.String,java.lang.String,int) threw an exception when invoked on org.alfresco.repo.site.script.Site@596c9523
   at freemarker.ext.beans.SimpleMethodModel.exec(SimpleMethodModel.java:130)
   at freemarker.core.MethodCall._getAsTemplateModel(MethodCall.java:93)
   at freemarker.core.Expression.getAsTemplateModel(Expression.java:89)
   at freemarker.core.BuiltIn$keysBI._getAsTemplateModel(BuiltIn.java:596)
   at freemarker.core.Expression.getAsTemplateModel(Expression.java:89)
   at freemarker.core.Assignment.accept(Assignment.java:90)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.MixedContent.accept(MixedContent.java:92)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.EscapeBlock.accept(EscapeBlock.java:84)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.Macro$Context.runMacro(Macro.java:172)
   at freemarker.core.Environment.visit(Environment.java:603)
   at freemarker.core.UnifiedCall.accept(UnifiedCall.java:106)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.MixedContent.accept(MixedContent.java:92)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.IteratorBlock$Context.runLoop(IteratorBlock.java:179)
   at freemarker.core.Environment.visit(Environment.java:417)
   at freemarker.core.IteratorBlock.accept(IteratorBlock.java:102)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.MixedContent.accept(MixedContent.java:92)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.Environment.process(Environment.java:190)
   at freemarker.template.Template.process(Template.java:237)
   at org.alfresco.repo.template.FreeMarkerProcessor.process(FreeMarkerProcessor.java:197)
   at org.springframework.extensions.webscripts.AbstractWebScript.renderTemplate(AbstractWebScript.java:589)
   at org.springframework.extensions.webscripts.DeclarativeWebScript.renderFormatTemplate(DeclarativeWebScript.java:267)
   at org.springframework.extensions.webscripts.DeclarativeWebScript.execute(DeclarativeWebScript.java:147)
   at org.alfresco.repo.web.scripts.RepositoryContainer$2.execute(RepositoryContainer.java:377)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:381)
   at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecute(RepositoryContainer.java:436)
   at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecuteAs(RepositoryContainer.java:466)
   at org.alfresco.repo.web.scripts.RepositoryContainer.executeScript(RepositoryContainer.java:304)
   at org.springframework.extensions.webscripts.AbstractRuntime.executeScript(AbstractRuntime.java:333)
   at org.springframework.extensions.webscripts.AbstractRuntime.executeScript(AbstractRuntime.java:189)
   at org.springframework.extensions.webscripts.servlet.WebScriptServlet.service(WebScriptServlet.java:118)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
   at org.alfresco.web.app.servlet.GlobalLocalizationFilter.doFilter(GlobalLocalizationFilter.java:58)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
   at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
   at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
   at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
   at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
   at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
   at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298)
   at org.apache.coyote.http11.Http11AprProcessor.process(Http11AprProcessor.java:859)
   at org.apache.coyote.http11.Http11AprProtocol$Http11ConnectionHandler.process(Http11AprProtocol.java:579)
   at org.apache.tomcat.util.net.AprEndpoint$Worker.run(AprEndpoint.java:1555)
   at java.lang.Thread.run(Thread.java:619)
Caused by: org.alfresco.repo.security.authority.UnknownAuthorityException: 04250058 An authority was not found for GROUP_site_sitev_SiteManager
   at org.alfresco.repo.security.authority.AuthorityDAOImpl.getContainedAuthorities(AuthorityDAOImpl.java:442)
   at org.alfresco.repo.security.authority.AuthorityServiceImpl.getContainedAuthorities(AuthorityServiceImpl.java:385)
   at org.alfresco.repo.site.SiteServiceImpl.listMembersImpl(SiteServiceImpl.java:1183)
   at org.alfresco.repo.site.SiteServiceImpl.listMembers(SiteServiceImpl.java:1151)
   at org.alfresco.repo.site.SiteServiceImpl.listMembers(SiteServiceImpl.java:1127)
   at org.alfresco.repo.site.script.Site.listMembers(Site.java:309)
   at sun.reflect.GeneratedMethodAccessor459.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at freemarker.ext.beans.BeansWrapper.invokeMethod(BeansWrapper.java:840)
   at freemarker.ext.beans.SimpleMethodModel.exec(SimpleMethodModel.java:106)
   … 52 more
20:53:48,646  ERROR [extensions.webscripts.AbstractRuntime] Exception from executeScript - redirecting to status template error: 04250005 Wrapped Exception (with status template): 04250059 Error during processing of the template 'Method public org.alfresco.repo.jscript.ScriptableHashMap org.alfresco.repo.site.script.Site.listMembers(java.lang.String,java.lang.String,int) threw an exception when invoked on org.alfresco.repo.site.script.Site@596c9523'. Please contact your system administrator.
org.springframework.extensions.webscripts.WebScriptException: 04250005 Wrapped Exception (with status template): 04250059 Error during processing of the template 'Method public org.alfresco.repo.jscript.ScriptableHashMap org.alfresco.repo.site.script.Site.listMembers(java.lang.String,java.lang.String,int) threw an exception when invoked on org.alfresco.repo.site.script.Site@596c9523'. Please contact your system administrator.
   at org.springframework.extensions.webscripts.AbstractWebScript.createStatusException(AbstractWebScript.java:758)
   at org.springframework.extensions.webscripts.DeclarativeWebScript.execute(DeclarativeWebScript.java:171)
   at org.alfresco.repo.web.scripts.RepositoryContainer$2.execute(RepositoryContainer.java:377)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:381)
   at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecute(RepositoryContainer.java:436)
   at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecuteAs(RepositoryContainer.java:466)
   at org.alfresco.repo.web.scripts.RepositoryContainer.executeScript(RepositoryContainer.java:304)
   at org.springframework.extensions.webscripts.AbstractRuntime.executeScript(AbstractRuntime.java:333)
   at org.springframework.extensions.webscripts.AbstractRuntime.executeScript(AbstractRuntime.java:189)
   at org.springframework.extensions.webscripts.servlet.WebScriptServlet.service(WebScriptServlet.java:118)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
   at org.alfresco.web.app.servlet.GlobalLocalizationFilter.doFilter(GlobalLocalizationFilter.java:58)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
   at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
   at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
   at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
   at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
   at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
   at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298)
   at org.apache.coyote.http11.Http11AprProcessor.process(Http11AprProcessor.java:859)
   at org.apache.coyote.http11.Http11AprProtocol$Http11ConnectionHandler.process(Http11AprProtocol.java:579)
   at org.apache.tomcat.util.net.AprEndpoint$Worker.run(AprEndpoint.java:1555)
   at java.lang.Thread.run(Thread.java:619)
Caused by: org.alfresco.service.cmr.repository.TemplateException: 04250059 Error during processing of the template 'Method public org.alfresco.repo.jscript.ScriptableHashMap org.alfresco.repo.site.script.Site.listMembers(java.lang.String,java.lang.String,int) threw an exception when invoked on org.alfresco.repo.site.script.Site@596c9523'. Please contact your system administrator.
   at org.alfresco.repo.template.FreeMarkerProcessor.process(FreeMarkerProcessor.java:201)
   at org.springframework.extensions.webscripts.AbstractWebScript.renderTemplate(AbstractWebScript.java:589)
   at org.springframework.extensions.webscripts.DeclarativeWebScript.renderFormatTemplate(DeclarativeWebScript.java:267)
   at org.springframework.extensions.webscripts.DeclarativeWebScript.execute(DeclarativeWebScript.java:147)
   … 24 more
Caused by: freemarker.template.TemplateModelException: Method public org.alfresco.repo.jscript.ScriptableHashMap org.alfresco.repo.site.script.Site.listMembers(java.lang.String,java.lang.String,int) threw an exception when invoked on org.alfresco.repo.site.script.Site@596c9523
   at freemarker.ext.beans.SimpleMethodModel.exec(SimpleMethodModel.java:130)
   at freemarker.core.MethodCall._getAsTemplateModel(MethodCall.java:93)
   at freemarker.core.Expression.getAsTemplateModel(Expression.java:89)
   at freemarker.core.BuiltIn$keysBI._getAsTemplateModel(BuiltIn.java:596)
   at freemarker.core.Expression.getAsTemplateModel(Expression.java:89)
   at freemarker.core.Assignment.accept(Assignment.java:90)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.MixedContent.accept(MixedContent.java:92)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.EscapeBlock.accept(EscapeBlock.java:84)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.Macro$Context.runMacro(Macro.java:172)
   at freemarker.core.Environment.visit(Environment.java:603)
   at freemarker.core.UnifiedCall.accept(UnifiedCall.java:106)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.MixedContent.accept(MixedContent.java:92)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.IteratorBlock$Context.runLoop(IteratorBlock.java:179)
   at freemarker.core.Environment.visit(Environment.java:417)
   at freemarker.core.IteratorBlock.accept(IteratorBlock.java:102)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.MixedContent.accept(MixedContent.java:92)
   at freemarker.core.Environment.visit(Environment.java:210)
   at freemarker.core.Environment.process(Environment.java:190)
   at freemarker.template.Template.process(Template.java:237)
   at org.alfresco.repo.template.FreeMarkerProcessor.process(FreeMarkerProcessor.java:197)
   … 27 more
Caused by: org.alfresco.repo.security.authority.UnknownAuthorityException: 04250058 An authority was not found for GROUP_site_sitev_SiteManager
   at org.alfresco.repo.security.authority.AuthorityDAOImpl.getContainedAuthorities(AuthorityDAOImpl.java:442)
   at org.alfresco.repo.security.authority.AuthorityServiceImpl.getContainedAuthorities(AuthorityServiceImpl.java:385)
   at org.alfresco.repo.site.SiteServiceImpl.listMembersImpl(SiteServiceImpl.java:1183)
   at org.alfresco.repo.site.SiteServiceImpl.listMembers(SiteServiceImpl.java:1151)
   at org.alfresco.repo.site.SiteServiceImpl.listMembers(SiteServiceImpl.java:1127)
   at org.alfresco.repo.site.script.Site.listMembers(Site.java:309)
   at sun.reflect.GeneratedMethodAccessor459.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at freemarker.ext.beans.BeansWrapper.invokeMethod(BeansWrapper.java:840)
   at freemarker.ext.beans.SimpleMethodModel.exec(SimpleMethodModel.java:106)
   … 52 more

La création de site fonctionne mais il n'apparaît pas non plus dans la liste. Je peux toutefois y accéder directement par l'URL.
Les sites apparaissent bien dans l'entrepôt, et les droits d'accès sont présents à chaque fois sous la forme 'site_NOMDUSITE_SiteCollaborator'…

J'ai refait la migration trois fois mais toujours le même problème.

J'ai bien trouvé ce message http://forums.alfresco.com/en/viewtopic.php?f=47&t=37425 qui semble correspondre, mais il n'y a pas vraiment de solutions.

Donc si quelqu'un a une idée je suis preneur

Merci d'avance
2 Replies
rguinot
Customer

Re: Migration 3.2r vers 3.4d : accès aux sites Share impossible

Caused by: org.alfresco.repo.security.authority.UnknownAuthorityException: 04250058 An authority was not found for GROUP_site_sitev_SiteManager
   at org.alfresco.repo.security.authority.AuthorityDAOImpl.getContainedAuthorities(AuthorityDAOImpl.java:442)

Possible que vous ayez des corruptions d'index. sur quel type de médias sont ils stockés ? respectez vous la procédure de backup ici : http://wiki.alfresco.com/wiki/Backup_and_Restore ?

Si c'est opérationnellement possible pour vous, essayez de reconstruire les index en mode FULL
tomasb
Member II

Re: Migration 3.2r vers 3.4d : accès aux sites Share impossible

Bonjour,

Merci d'avoir pris le temps de répondre.

Oui j'ai bien respecté la procédure et j'ai tenté plusieurs fois de reconstruire les index et de faire la migration sur différentes machines.
Donc pour l'instant je reste bloqué en 3.2r.

Au bout d'une semaine (et une multitudes d'essais), je pense avoir trouvé une piste.  En regardant dans l'explorer, j'ai constaté que les groupes des sites (site_SITE_SiteManager, site_SITE_SiteCollaborator…) ne se trouvaient pas dans la zone "APP.DEFAULT".  Contrairement aux sites nouvellement créés qui eux ne posent pas de problèmes. Je ne trouve absolument pas comment faire pour les ajouter à travers l'interface. J'ai tenté le bout de code trouvé sur le forum http://forums.alfresco.com/en/viewtopic.php?f=47&t=21819#p108205, mais ça n'a fait que créer des groupes vident avec le même nom et sans membres. Donc je me retrouve avec les groupes provenant de la version 3.2r et les groupes créés avec le script ci-dessus. Notez que l’exécution du script a fait disparaître le message d'erreur mais lorsque je me rends sur un des sites aucun membre n'apparaît dans la liste.

Existe-t-il une méthode pour ajouter un groupe à une zone ?

merci