Hello helpful Alfresco community 😎
At Medway Council, we've been running the Community edition at version 3.2.0 for quite a while. It needs migrating to the latest version so we can get it on a better platform, configured from day one for a production environment, etc.
The current live system is Windows 2003 R2 (32 bit), running Tomcat and MySQL 5.1.
The system I'm trying to migrate us to is a Windows 2008 R2 (64 bit), running Tomcat and MySQL 5.5.
We're making progress - without importing the database from the live environment, Alfresco 4.0.d is working very well, as far as I can tell. But we keep hitting brick walls when we import the database schema.
I've double checked the upgrade path, and upgrading from 3.2.x to 4 is supported.
We've assigned the MySQL user 'alfresco' with full permissions to the imported database scheme 'alfresco'.
When we start the Alfresco services (servicerun.bat START), it gets as far as trying to update the database to the latest schema version. This kept failing at this script:
When we caught the query and loaded it into MySQL work bench directly, we noticed it did not like the comments without a following space ("–optional", rather than "– optional" and also it did not like the syntax of $(TRUE). We tried this script again, fixing the comments and changing all '$(TRUE)'s to 'TRUE'. The script ran no problem, except there was no idx_alf_conturl_cr index to drop, obviously not a show stopper.
So then we started up the services again. This time we got further but have hit problems that I cannot try and fix further without a bit of advice!
Here's the log:
As you can see, an SQL error causes a massive hiccup. Turning on SQL global logging briefly, the SQL I THINK is causing this issue is this one:
By removing the perculiar string <include refid="alfresco.util.escape"/>, this is a valid query, but clearly the script is broken by this point. Although the process continues after this failure, we can not log in to the new staging area of the migrated Alfresco system. Eventually, after many minutes, it times out.
Checking the Pre-update potential problem report, I see this:
And the post upgrade potential problem report gives me this:
Looks like most of these problems are quite cosmetic though, indexes and foreign keys that are slightly different names, unexpected (so I'm guessing now unused tables) found, etc. Could all be fixed manually I guess, however I'm a little surprised the scripts don't cater for all this and tidy up fully, making sure the schema is fully up to date and in line with the 4.0.d design.
But yes, to reiterate, we cannot access the new Alfresco version hosted on our new staging environment for the migration. When trying to access either /share or /alfresco, eventually, after many minutes, it times out with a server error.
A lengthy problem report but there's all the detail I think anyone will need to lend their insight
How do we proceed so we get our Alfresco installation migrated successfully to 4.0.d?
And any such offers will be greatly appreciated, I've hit a dead end with this.
Thanks in advance.
At Medway Council, we've been running the Community edition at version 3.2.0 for quite a while. It needs migrating to the latest version so we can get it on a better platform, configured from day one for a production environment, etc.
The current live system is Windows 2003 R2 (32 bit), running Tomcat and MySQL 5.1.
The system I'm trying to migrate us to is a Windows 2008 R2 (64 bit), running Tomcat and MySQL 5.5.
We're making progress - without importing the database from the live environment, Alfresco 4.0.d is working very well, as far as I can tell. But we keep hitting brick walls when we import the database schema.
I've double checked the upgrade path, and upgrading from 3.2.x to 4 is supported.
We've assigned the MySQL user 'alfresco' with full permissions to the imported database scheme 'alfresco'.
When we start the Alfresco services (servicerun.bat START), it gets as far as trying to update the database to the latest schema version. This kept failing at this script:
–
– Title: Update Content tables (pre 3.2 Enterprise Final)
– Database: MySQL InnoDB
– Since: V3.2 Schema 3009
– Author: Derek Hulley
–
– Please contact support@alfresco.com if you need assistance with the upgrade.
–
– This update is required for installations that have run any of the early 3.2
– codelines i.e. anything installed or upgraded to pre-3.2 Enterprise Final.
– This is to (a) fix the naming convention and (b) to ensure that the index is UNIQUE
DROP INDEX idx_alf_cont_url_crc ON alf_content_url; – (optional)
DROP INDEX idx_alf_conturl_cr ON alf_content_url; – (optional)
CREATE UNIQUE INDEX idx_alf_conturl_cr ON alf_content_url (content_url_short, content_url_crc);
– If this statement fails, it will be because the table already contains
– the orphan column and index
ALTER TABLE alf_content_url
DROP COLUMN version,
ADD COLUMN orphan_time BIGINT NULL AFTER content_size,
ADD INDEX idx_alf_conturl_ot (orphan_time)
; –(optional)
– This table will not exist for upgrades from pre 3.2 to 3.2 Enterprise Final
DROP TABLE alf_content_clean; –(optional)
–
– Record script finish
–
DELETE FROM alf_applied_patch WHERE id = 'patch.db-V3.2-ContentTables2';
INSERT INTO alf_applied_patch
(id, description, fixes_from_schema, fixes_to_schema, applied_to_schema, target_schema, applied_on_date, applied_to_server, was_executed, succeeded, report)
VALUES
(
'patch.db-V3.2-ContentTables2', 'Manually executed script upgrade V3.2: Content Tables 2 (pre 3.2 Enterprise Final)',
0, 3008, -1, 3009, null, 'UNKOWN', $(TRUE), $(TRUE), 'Script completed'
);
When we caught the query and loaded it into MySQL work bench directly, we noticed it did not like the comments without a following space ("–optional", rather than "– optional" and also it did not like the syntax of $(TRUE). We tried this script again, fixing the comments and changing all '$(TRUE)'s to 'TRUE'. The script ran no problem, except there was no idx_alf_conturl_cr index to drop, obviously not a show stopper.
So then we started up the services again. This time we got further but have hit problems that I cannot try and fix further without a bit of advice!
Here's the log:
2012-07-02 17:20:19,467 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
2012-07-02 17:20:19,702 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
2012-07-02 17:20:32,858 WARN [alfresco.util.AbstractTriggerBean] [Thread-1] Job ehCacheTracerJob is not active/enabled
2012-07-02 17:20:39,358 INFO [extensions.webscripts.TemplateProcessorRegistry] [Thread-1] Registered template processor Repository Template Processor for extension ftl
2012-07-02 17:20:39,373 INFO [extensions.webscripts.ScriptProcessorRegistry] [Thread-1] Registered script processor Repository Script Processor for extension js
2012-07-02 17:20:55,123 INFO [domain.schema.SchemaBootstrap] [Thread-1] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
2012-07-02 17:20:57,373 WARN [domain.schema.SchemaBootstrap] [Thread-1] Schema validation found 88 potential problems, results written to: C:\Windows\TEMP\Alfresco\Alfresco-MySQLInnoDBDialect-Validation-Pre-Upgrade-9151173859317961100.txt
2012-07-02 17:21:00,294 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-7167033840801827164.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.2/org.hibernate.dialect.MySQLInnoDBDialect/child-assoc-qname-crc.sql).
2012-07-02 17:21:09,060 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-1597311755851829174.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/modify-index-permission_id.sql).
2012-07-02 17:21:12,200 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-5939467030527907483.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.2/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoSchemaUpdate-3.2-AddFKIndexes-2.sql).
2012-07-02 17:21:12,935 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-5845158799884776767.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.1/org.hibernate.dialect.MySQLInnoDBDialect/IPv6-patch.sql).
2012-07-02 17:21:13,013 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-436437270906664381.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/remove-VersionCount.sql).
2012-07-02 17:21:13,044 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-7869134818414997679.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/fix-Repo-seqs.sql).
2012-07-02 17:21:13,060 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-5875073702574254783.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/fix-AVM-seqs.sql).
2012-07-02 17:21:13,091 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-6079789510492405931.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoPostCreate-JBPM-Extra.sql).
2012-07-02 17:21:13,106 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-3500487118944804220.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.3/org.hibernate.dialect.MySQLInnoDBDialect/node-prop-serializable.sql).
2012-07-02 17:21:13,138 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2935077253447391971.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/property-unique-ctx-value.sql).
2012-07-02 17:21:13,200 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-4544368369621933010.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/property-unique-ctx-idx.sql).
2012-07-02 17:21:13,247 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-6973531723684418650.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/authority-unique-idx.sql).
2012-07-02 17:21:13,278 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-6286037156801556460.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/child-assoc-indexes.sql).
2012-07-02 17:21:14,185 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-1521857021290616670.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/link-validation-metadata-removing.sql).
2012-07-02 17:21:14,200 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-3990653258406322629.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/AVM-rename-dupes.sql).
2012-07-02 17:21:15,622 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-8320403249042443526.sql (Copied from classpath:alfresco/dbscripts/upgrade/3.4/org.hibernate.dialect.MySQLInnoDBDialect/varchar-field-sizes-quadruple-increasing.sql).
2012-07-02 17:21:15,653 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-4026395477783336885.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoPostCreate-JBPM-FK-indexes.sql).
2012-07-02 17:21:15,669 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2236484803344212725.sql (Copied from classpath:alfresco/dbscripts/upgrade/4.0/org.hibernate.dialect.MySQLInnoDBDialect/AclChangeSet-Tracking.sql).
2012-07-02 17:21:15,981 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2470820541542039882.sql (Copied from classpath:alfresco/dbscripts/upgrade/4.0/org.hibernate.dialect.MySQLInnoDBDialect/NodeAssoc-Ordering.sql).
2012-07-02 17:21:16,122 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-6018947625655923110.sql (Copied from classpath:alfresco/dbscripts/upgrade/4.0/org.hibernate.dialect.MySQLInnoDBDialect/Node-Locale.sql).
2012-07-02 17:21:19,575 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-2794412886679340776.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-SubscriptionTables.sql).
2012-07-02 17:21:19,653 INFO [domain.schema.SchemaBootstrap] [Thread-1] Executing database script C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-Update-4930367320381692990.sql (Copied from classpath:alfresco/dbscripts/upgrade/4.0/org.hibernate.dialect.MySQLInnoDBDialect/Solr-Tracking.sql).
2012-07-02 17:21:23,606 INFO [domain.schema.SchemaBootstrap] [Thread-1] All executed statements: C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-All_Statements-7616440184094909001.sql.
2012-07-02 17:21:25,278 WARN [domain.schema.SchemaBootstrap] [Thread-1] Schema validation found 58 potential problems, results written to: C:\Windows\TEMP\Alfresco\Alfresco-MySQLInnoDBDialect-Validation-Post-Upgrade-1818111282520245889.txt
2012-07-02 17:21:28,715 INFO [domain.schema.SchemaBootstrap] [Thread-1] Normalized schema (pre-bootstrap) dumped to file C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-3872262549552899097-Startup.xml.
2012-07-02 17:21:28,715 INFO [domain.schema.SchemaBootstrap] [Thread-1] Normalized schema (post-bootstrap) dumped to file C:\Windows\TEMP\Alfresco\AlfrescoSchema-MySQLInnoDBDialect-1615851101911629746.xml.
2012-07-02 17:21:29,075 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Starting 'Search' subsystem, ID: [Search, managed, solr]
2012-07-02 17:21:29,325 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Startup of 'Search' subsystem, ID: [Search, managed, solr] complete
2012-07-02 17:21:32,699 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
2012-07-02 17:21:32,824 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
2012-07-02 17:21:32,824 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
2012-07-02 17:21:34,653 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
2012-07-02 17:21:34,903 INFO [repo.admin.ConfigurationChecker] [Thread-1] The Alfresco root data directory ('dir.root') is: E:\Alfresco\alf_data
2012-07-02 17:21:34,918 INFO [admin.patch.PatchExecuter] [Thread-1] Checking for patches to apply …
2012-07-02 17:21:35,809 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.thumbnailsAssocQName' (Update the 'cm:thumbnails' association QName to 'rn:rendition'.).
2012-07-02 17:21:35,887 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.fixNameCrcValues-2' (Fixes name and qname CRC32 values to match UTF-8 encoding.).
2012-07-02 17:21:35,949 INFO [admin.patch.PatchExecuter] [Thread-1] FixNameCrcValuesPatch: Commencing batch of 52514 entries
2012-07-02 17:21:39,887 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 1000 entries out of 52514. 2% complete. Rate: 253 per second. 0 failures detected.
2012-07-02 17:21:41,793 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 2000 entries out of 52514. 4% complete. Rate: 342 per second. 0 failures detected.
2012-07-02 17:21:42,058 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 3000 entries out of 52514. 6% complete. Rate: 491 per second. 0 failures detected.
2012-07-02 17:21:43,090 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 4000 entries out of 52514. 8% complete. Rate: 560 per second. 0 failures detected.
2012-07-02 17:21:43,543 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 5000 entries out of 52514. 10% complete. Rate: 658 per second. 0 failures detected.
2012-07-02 17:21:44,387 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 6000 entries out of 52514. 11% complete. Rate: 711 per second. 0 failures detected.
2012-07-02 17:21:44,933 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 7000 entries out of 52514. 13% complete. Rate: 779 per second. 0 failures detected.
2012-07-02 17:21:45,402 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 8000 entries out of 52514. 15% complete. Rate: 846 per second. 0 failures detected.
2012-07-02 17:21:45,668 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 9000 entries out of 52514. 17% complete. Rate: 926 per second. 0 failures detected.
2012-07-02 17:21:47,027 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 10000 entries out of 52514. 19% complete. Rate: 902 per second. 0 failures detected.
2012-07-02 17:21:47,183 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 11000 entries out of 52514. 21% complete. Rate: 979 per second. 0 failures detected.
2012-07-02 17:21:47,355 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 12000 entries out of 52514. 23% complete. Rate: 1052 per second. 0 failures detected.
2012-07-02 17:21:47,558 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 13000 entries out of 52514. 25% complete. Rate: 1119 per second. 0 failures detected.
2012-07-02 17:21:48,105 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 14000 entries out of 52514. 27% complete. Rate: 1151 per second. 0 failures detected.
2012-07-02 17:21:48,449 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 15000 entries out of 52514. 29% complete. Rate: 1200 per second. 0 failures detected.
2012-07-02 17:21:48,886 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 16000 entries out of 52514. 30% complete. Rate: 1236 per second. 0 failures detected.
2012-07-02 17:21:49,230 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 17000 entries out of 52514. 32% complete. Rate: 1280 per second. 0 failures detected.
2012-07-02 17:21:49,808 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 18000 entries out of 52514. 34% complete. Rate: 1298 per second. 0 failures detected.
2012-07-02 17:21:50,043 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 19000 entries out of 52514. 36% complete. Rate: 1348 per second. 0 failures detected.
2012-07-02 17:21:50,605 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 20000 entries out of 52514. 38% complete. Rate: 1364 per second. 0 failures detected.
2012-07-02 17:21:50,808 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 21000 entries out of 52514. 40% complete. Rate: 1413 per second. 0 failures detected.
2012-07-02 17:21:51,339 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 22000 entries out of 52514. 42% complete. Rate: 1429 per second. 0 failures detected.
2012-07-02 17:21:52,027 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 23000 entries out of 52514. 44% complete. Rate: 1430 per second. 0 failures detected.
2012-07-02 17:21:52,355 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 24000 entries out of 52514. 46% complete. Rate: 1462 per second. 0 failures detected.
2012-07-02 17:21:52,808 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 25000 entries out of 52514. 48% complete. Rate: 1482 per second. 0 failures detected.
2012-07-02 17:21:53,105 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 26000 entries out of 52514. 50% complete. Rate: 1515 per second. 0 failures detected.
2012-07-02 17:21:53,386 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 27000 entries out of 52514. 51% complete. Rate: 1548 per second. 0 failures detected.
2012-07-02 17:21:53,652 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 28000 entries out of 52514. 53% complete. Rate: 1581 per second. 0 failures detected.
2012-07-02 17:21:54,136 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 29000 entries out of 52514. 55% complete. Rate: 1594 per second. 0 failures detected.
2012-07-02 17:21:54,386 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 30000 entries out of 52514. 57% complete. Rate: 1627 per second. 0 failures detected.
2012-07-02 17:21:54,870 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 31000 entries out of 52514. 59% complete. Rate: 1638 per second. 0 failures detected.
2012-07-02 17:21:55,167 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 32000 entries out of 52514. 61% complete. Rate: 1665 per second. 0 failures detected.
2012-07-02 17:21:55,730 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 33000 entries out of 52514. 63% complete. Rate: 1668 per second. 0 failures detected.
2012-07-02 17:21:55,980 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 34000 entries out of 52514. 65% complete. Rate: 1697 per second. 0 failures detected.
2012-07-02 17:21:56,558 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 35000 entries out of 52514. 67% complete. Rate: 1698 per second. 0 failures detected.
2012-07-02 17:21:56,870 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 36000 entries out of 52514. 69% complete. Rate: 1720 per second. 0 failures detected.
2012-07-02 17:21:57,402 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 37000 entries out of 52514. 70% complete. Rate: 1724 per second. 0 failures detected.
2012-07-02 17:21:57,745 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 38000 entries out of 52514. 72% complete. Rate: 1743 per second. 0 failures detected.
2012-07-02 17:21:58,277 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 39000 entries out of 52514. 74% complete. Rate: 1746 per second. 0 failures detected.
2012-07-02 17:21:58,573 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 40000 entries out of 52514. 76% complete. Rate: 1768 per second. 0 failures detected.
2012-07-02 17:21:58,995 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 41000 entries out of 52514. 78% complete. Rate: 1779 per second. 0 failures detected.
2012-07-02 17:21:59,292 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 42000 entries out of 52514. 80% complete. Rate: 1799 per second. 0 failures detected.
2012-07-02 17:21:59,870 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 43000 entries out of 52514. 82% complete. Rate: 1797 per second. 0 failures detected.
2012-07-02 17:22:00,167 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 44000 entries out of 52514. 84% complete. Rate: 1816 per second. 0 failures detected.
2012-07-02 17:22:00,589 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 45000 entries out of 52514. 86% complete. Rate: 1826 per second. 0 failures detected.
2012-07-02 17:22:00,917 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 46000 entries out of 52514. 88% complete. Rate: 1842 per second. 0 failures detected.
2012-07-02 17:22:01,448 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 47000 entries out of 52514. 89% complete. Rate: 1843 per second. 0 failures detected.
2012-07-02 17:22:01,730 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 48000 entries out of 52514. 91% complete. Rate: 1861 per second. 0 failures detected.
2012-07-02 17:22:02,183 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 49000 entries out of 52514. 93% complete. Rate: 1867 per second. 0 failures detected.
2012-07-02 17:22:02,448 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 50000 entries out of 52514. 95% complete. Rate: 1886 per second. 0 failures detected.
2012-07-02 17:22:02,636 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch2] FixNameCrcValuesPatch: Processed 51000 entries out of 52514. 97% complete. Rate: 1911 per second. 0 failures detected.
2012-07-02 17:22:02,808 INFO [admin.patch.PatchExecuter] [FixNameCrcValuesPatch1] FixNameCrcValuesPatch: Processed 52000 entries out of 52514. 99% complete. Rate: 1936 per second. 0 failures detected.
2012-07-02 17:22:02,855 INFO [admin.patch.PatchExecuter] [Thread-1] FixNameCrcValuesPatch: Processed 52514 entries out of 52514. 100% complete. Rate: 1951 per second. 0 failures detected.
2012-07-02 17:22:02,855 INFO [admin.patch.PatchExecuter] [Thread-1] FixNameCrcValuesPatch: Completed batch of 52514 entries
2012-07-02 17:22:03,151 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.fixUserQNames' (Fixes user store qnames to improve native authentication performance).
2012-07-02 17:22:03,167 INFO [patch.impl.FixUserQNamesPatch] [Thread-1] FixUserQNamesPatch: Commencing batch of 1 entries
2012-07-02 17:22:03,292 INFO [patch.impl.FixUserQNamesPatch] [Thread-1] FixUserQNamesPatch: Processed 1 entries out of 1. 100% complete. Rate: 8 per second. 0 failures detected.
2012-07-02 17:22:03,292 INFO [patch.impl.FixUserQNamesPatch] [Thread-1] FixUserQNamesPatch: Completed batch of 1 entries
2012-07-02 17:22:03,355 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.personUsagePatch' (Add person 'cm:sizeCurrent' property (if missing).).
2012-07-02 17:22:03,355 INFO [patch.impl.PersonUsagePatch] [Thread-1] Checking for people with missing 'cm:sizeCurrent' property …
2012-07-02 17:22:03,480 INFO [patch.impl.PersonUsagePatch] [Thread-1] … no people were missing the 'cm:sizeCurrent' property
2012-07-02 17:22:03,526 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.redeployNominatedInvitationProcessWithPropsForShare' (Redeploy nominated invitation workflow).
2012-07-02 17:22:03,558 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.rendition.rendering_actions' (Creates the Rendering Actions folder.).
2012-07-02 17:22:03,870 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.emailInviteAndNotifyTemplatesFolder' (Ensures the existence of the 'Email Invite Templates' and 'Email Notify Templates' folders.).
2012-07-02 17:22:03,933 INFO [admin.patch.PatchExecuter] [Thread-1] 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.).
2012-07-02 17:22:03,964 INFO [admin.patch.PatchExecuter] [Thread-1] Content URL conversion progress:
2012-07-02 17:22:03,964 INFO [admin.patch.PatchExecuter] [Thread-1] Processing ADM Content URLs.
2012-07-02 17:22:06,479 INFO [admin.patch.PatchExecuter] [Thread-1] Finished processing ADM nodes up to ID 135,640.
2012-07-02 17:22:06,495 INFO [admin.patch.PatchExecuter] [Thread-1] Processing AVM Content URLs.
2012-07-02 17:22:42,619 ERROR [admin.patch.PatchExecuter] [Thread-1] Content URL conversion failed:
### Error querying database. Cause: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'refid="alfresco.util.escape"/>
order by
id ASC' at line 10
### The error may involve alfresco.patch.select_avmNodesWithOldContentProperties-Inline
### The error occurred while setting parameters
### Cause: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'refid="alfresco.util.escape"/>
order by
id ASC' at line 10
; bad SQL grammar []; nested exception is com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'refid="alfresco.util.escape"/>
order by
id ASC' at line 10
org.springframework.jdbc.BadSqlGrammarException:
### Error querying database. Cause: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'refid="alfresco.util.escape"/>
order by
id ASC' at line 10
### The error may involve alfresco.patch.select_avmNodesWithOldContentProperties-Inline
### The error occurred while setting parameters
### Cause: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'refid="alfresco.util.escape"/>
order by
id ASC' at line 10
; bad SQL grammar []; nested exception is com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'refid="alfresco.util.escape"/>
order by
id ASC' at line 10
at org.springframework.jdbc.support.SQLErrorCodeSQLExceptionTranslator.doTranslate(SQLErrorCodeSQLExceptionTranslator.java:233)
at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:72)
at org.mybatis.spring.MyBatisExceptionTranslator.translateExceptionIfPossible(MyBatisExceptionTranslator.java:71)
at org.mybatis.spring.SqlSessionTemplate$SqlSessionInterceptor.invoke(SqlSessionTemplate.java:346)
at $Proxy6.selectList(Unknown Source)
at org.mybatis.spring.SqlSessionTemplate.selectList(SqlSessionTemplate.java:189)
at org.alfresco.repo.domain.patch.ibatis.PatchDAOImpl.getAvmNodesWithOldContentProperties(PatchDAOImpl.java:214)
at org.alfresco.repo.admin.patch.impl.ContentUrlConverterPatch.applyAVM(ContentUrlConverterPatch.java:520)
at org.alfresco.repo.admin.patch.impl.ContentUrlConverterPatch.access$400(ContentUrlConverterPatch.java:83)
at org.alfresco.repo.admin.patch.impl.ContentUrlConverterPatch$5.execute(ContentUrlConverterPatch.java:477)
at org.alfresco.repo.admin.patch.impl.ContentUrlConverterPatch$5.execute(ContentUrlConverterPatch.java:474)
at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
at org.alfresco.repo.admin.patch.impl.ContentUrlConverterPatch.applyAVMLooping(ContentUrlConverterPatch.java:483)
at org.alfresco.repo.admin.patch.impl.ContentUrlConverterPatch.applyInternal(ContentUrlConverterPatch.java:320)
at org.alfresco.repo.admin.patch.AbstractPatch.applyImpl(AbstractPatch.java:407)
at org.alfresco.repo.admin.patch.AbstractPatch.access$000(AbstractPatch.java:52)
at org.alfresco.repo.admin.patch.AbstractPatch$2$1.execute(AbstractPatch.java:466)
at org.alfresco.repo.admin.patch.AbstractPatch$2$1.execute(AbstractPatch.java:463)
at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
at org.alfresco.repo.admin.patch.AbstractPatch$2.doWork(AbstractPatch.java:469)
at org.alfresco.repo.admin.patch.AbstractPatch$2.doWork(AbstractPatch.java:456)
at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
at org.alfresco.repo.admin.patch.AbstractPatch.apply(AbstractPatch.java:478)
at org.alfresco.repo.admin.patch.PatchServiceImpl$PatchWork.applyPatch(PatchServiceImpl.java:491)
at org.alfresco.repo.admin.patch.PatchServiceImpl$PatchWork.execute(PatchServiceImpl.java:403)
at org.alfresco.repo.admin.patch.PatchServiceImpl.applyPatch(PatchServiceImpl.java:258)
at org.alfresco.repo.admin.patch.PatchServiceImpl.applyPatchAndDependencies(PatchServiceImpl.java:235)
at org.alfresco.repo.admin.patch.PatchServiceImpl.applyOutstandingPatches(PatchServiceImpl.java:171)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at $Proxy93.applyOutstandingPatches(Unknown Source)
at org.alfresco.repo.admin.patch.PatchExecuter.applyOutstandingPatches(PatchExecuter.java:76)
at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:124)
at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:120)
at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
at org.alfresco.repo.admin.patch.PatchExecuter.onBootstrap(PatchExecuter.java:128)
at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEventInternal(SafeApplicationEventMulticaster.java:209)
at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEvent(SafeApplicationEventMulticaster.java:180)
at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:303)
at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:911)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:428)
at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:276)
at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:197)
at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:47)
at org.alfresco.web.app.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:63)
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4135)
at org.apache.catalina.core.StandardContext.start(StandardContext.java:4630)
at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)
at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:637)
at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:563)
at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:498)
at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
at org.apache.catalina.core.StandardHost.start(StandardHost.java:785)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:445)
at org.apache.catalina.core.StandardService.start(StandardService.java:519)
at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)
Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'refid="alfresco.util.escape"/>
order by
id ASC' at line 10
at sun.reflect.GeneratedConstructorAccessor174.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
at com.mysql.jdbc.Util.handleNewInstance(Util.java:411)
at com.mysql.jdbc.Util.getInstance(Util.java:386)
at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1052)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4096)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4028)
at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2490)
at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2651)
at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2683)
at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2144)
at com.mysql.jdbc.PreparedStatement.execute(PreparedStatement.java:1379)
at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java:172)
at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java:172)
at org.apache.commons.dbcp.DelegatingPreparedStatement.execute(DelegatingPreparedStatement.java:172)
at org.apache.ibatis.executor.statement.PreparedStatementHandler.query(PreparedStatementHandler.java:39)
at org.apache.ibatis.executor.statement.RoutingStatementHandler.query(RoutingStatementHandler.java:55)
at org.apache.ibatis.executor.SimpleExecutor.doQuery(SimpleExecutor.java:41)
at org.apache.ibatis.executor.BaseExecutor.query(BaseExecutor.java:120)
at org.apache.ibatis.executor.CachingExecutor.query(CachingExecutor.java:75)
at org.apache.ibatis.session.defaults.DefaultSqlSession.selectList(DefaultSqlSession.java:75)
at org.apache.ibatis.session.defaults.DefaultSqlSession.selectList(DefaultSqlSession.java:69)
at sun.reflect.GeneratedMethodAccessor283.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.mybatis.spring.SqlSessionTemplate$SqlSessionInterceptor.invoke(SqlSessionTemplate.java:338)
… 74 more
2012-07-02 17:22:42,650 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.transferServiceFolder' (Add transfer definitions folder to data dictionary.).
2012-07-02 17:22:42,885 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.fixAuthoritiesCrcValues' (Fixes authority CRC32 values to match UTF-8 encoding.).
2012-07-02 17:22:43,010 INFO [patch.impl.FixAuthoritiesCrcValuesPatch] [Thread-1] FixAuthorityCrcValuesPatch: Commencing batch of 0 entries
2012-07-02 17:22:43,010 INFO [patch.impl.FixAuthoritiesCrcValuesPatch] [Thread-1] FixAuthorityCrcValuesPatch: Completed batch of 0 entries
2012-07-02 17:22:43,291 INFO [admin.patch.PatchExecuter] [Thread-1] Applying patch 'patch.updateMimetypes1' (Fix mimetypes for Excel and Powerpoint.).
2012-07-02 17:22:43,478 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, passthru1]
2012-07-02 17:22:44,353 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, passthru1] complete
2012-07-02 17:22:44,353 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Starting 'Authentication' subsystem, ID: [Authentication, managed, ldap1]
2012-07-02 17:22:44,650 INFO [management.subsystems.ChildApplicationContextFactory] [Thread-1] Startup of 'Authentication' subsystem, ID: [Authentication, managed, ldap1] complete
As you can see, an SQL error causes a massive hiccup. Turning on SQL global logging briefly, the SQL I THINK is causing this issue is this one:
select
id
from
avm_nodes
where
id >= 1
and id < 10001
and class_type = 'plainfile'
and content_url is not null
and content_url not like 'id:%' <include refid="alfresco.util.escape"/>
order by
id ASCBy removing the perculiar string <include refid="alfresco.util.escape"/>, this is a valid query, but clearly the script is broken by this point. Although the process continues after this failure, we can not log in to the new staging area of the migrated Alfresco system. Eventually, after many minutes, it times out.
Checking the Pre-update potential problem report, I see this:
Difference: expected index .alf_access_control_entry.permission_id.columnNames="[permission_id, authority_id, allowed, applies]", but was .alf_access_control_entry.permission_id.columnNames[4]="context_id"
Difference: missing column from database, expected at path: .alf_acl_change_set.commit_time_ms
Difference: unexpected column found in database with path: .alf_acl_change_set.version
Difference: missing index from database, expected at path: .alf_acl_change_set.idx_alf_acs_ctms
Difference: expected index .alf_acl_member.aclm_acl_id.name="aclm_acl_id", but was .alf_acl_member.acl_id.name="acl_id"
Difference: missing foreign key from database, expected at path: .alf_audit_app.fk_alf_aud_app_an
Difference: missing foreign key from database, expected at path: .alf_audit_app.fk_alf_aud_app_mod
Difference: missing foreign key from database, expected at path: .alf_audit_app.fk_alf_aud_app_dis
Difference: unexpected foreign key found in database with path: .alf_audit_app.fk_alf_audit_app_app
Difference: unexpected foreign key found in database with path: .alf_audit_app.fk_alf_audit_app_dis
Difference: unexpected foreign key found in database with path: .alf_audit_app.fk_alf_audit_app_model
Difference: expected index .alf_audit_app.idx_alf_aud_app_an.name="idx_alf_aud_app_an", but was .alf_audit_app.idx_alf_audit_app_app.name="idx_alf_audit_app_app"
Difference: expected index .alf_audit_app.fk_alf_aud_app_mod.name="fk_alf_aud_app_mod", but was .alf_audit_app.fk_alf_audit_app_model.name="fk_alf_audit_app_model"
Difference: expected index .alf_audit_app.fk_alf_aud_app_dis.name="fk_alf_aud_app_dis", but was .alf_audit_app.fk_alf_audit_app_dis.name="fk_alf_audit_app_dis"
Difference: missing foreign key from database, expected at path: .alf_audit_entry.fk_alf_aud_ent_app
Difference: missing foreign key from database, expected at path: .alf_audit_entry.fk_alf_aud_ent_use
Difference: missing foreign key from database, expected at path: .alf_audit_entry.fk_alf_aud_ent_pro
Difference: unexpected foreign key found in database with path: .alf_audit_entry.fk_alf_audit_ent_app
Difference: unexpected foreign key found in database with path: .alf_audit_entry.fk_alf_audit_ent_prop
Difference: unexpected foreign key found in database with path: .alf_audit_entry.fk_alf_audit_ent_user
Difference: expected index .alf_audit_entry.fk_alf_aud_ent_app.name="fk_alf_aud_ent_app", but was .alf_audit_entry.fk_alf_audit_ent_app.name="fk_alf_audit_ent_app"
Difference: expected index .alf_audit_entry.idx_alf_aud_ent_tm.name="idx_alf_aud_ent_tm", but was .alf_audit_entry.idx_alf_audit_ent_time.name="idx_alf_audit_ent_time"
Difference: expected index .alf_audit_entry.fk_alf_aud_ent_use.name="fk_alf_aud_ent_use", but was .alf_audit_entry.fk_alf_audit_ent_user.name="fk_alf_audit_ent_user"
Difference: expected index .alf_audit_entry.fk_alf_aud_ent_pro.name="fk_alf_aud_ent_pro", but was .alf_audit_entry.fk_alf_audit_ent_prop.name="fk_alf_audit_ent_prop"
Difference: missing foreign key from database, expected at path: .alf_audit_model.fk_alf_aud_mod_cd
Difference: unexpected foreign key found in database with path: .alf_audit_model.fk_alf_audit_model_cd
Difference: expected index .alf_audit_model.idx_alf_aud_mod_cr.name="idx_alf_aud_mod_cr", but was .alf_audit_model.idx_alf_audit_cfg_crc.name="idx_alf_audit_cfg_crc"
Difference: expected index .alf_audit_model.fk_alf_aud_mod_cd.name="fk_alf_aud_mod_cd", but was .alf_audit_model.fk_alf_audit_model_cd.name="fk_alf_audit_model_cd"
Difference: missing column from database, expected at path: .alf_child_assoc.qname_crc
Difference: expected column .alf_child_assoc.is_primary.order="11", but was .alf_child_assoc.is_primary.order="10"
Difference: expected column .alf_child_assoc.assoc_index.order="12", but was .alf_child_assoc.assoc_index.order="11"
Difference: missing index from database, expected at path: .alf_child_assoc.idx_alf_cass_qncrc
Difference: missing index from database, expected at path: .alf_child_assoc.idx_alf_cass_pri
Difference: unexpected index found in database with path: .alf_child_assoc.idx_alf_cass_qnln
Difference: missing column from database, expected at path: .alf_node.locale_id
Difference: expected column .alf_node.acl_id.order="9", but was .alf_node.acl_id.order="8"
Difference: expected column .alf_node.audit_creator.order="10", but was .alf_node.audit_creator.order="9"
Difference: expected column .alf_node.audit_created.order="11", but was .alf_node.audit_created.order="10"
Difference: expected column .alf_node.audit_modifier.order="12", but was .alf_node.audit_modifier.order="11"
Difference: expected column .alf_node.audit_modified.order="13", but was .alf_node.audit_modified.order="12"
Difference: expected column .alf_node.audit_accessed.order="14", but was .alf_node.audit_accessed.order="13"
Difference: missing foreign key from database, expected at path: .alf_node.fk_alf_node_loc
Difference: missing index from database, expected at path: .alf_node.idx_alf_node_txn_del
Difference: missing index from database, expected at path: .alf_node.fk_alf_node_loc
Difference: missing column from database, expected at path: .alf_node_assoc.assoc_index
Difference: expected index .alf_node_assoc.fk_alf_nass_snode.columnNames[1]="type_qname_id", but was .alf_node_assoc.fk_alf_nass_snode.columnNames="[source_node_id]"
Difference: expected index .alf_node_assoc.fk_alf_nass_snode.columnNames[2]="assoc_index", but was .alf_node_assoc.fk_alf_nass_snode.columnNames="[source_node_id]"
Difference: expected index .alf_node_assoc.fk_alf_nass_tnode.columnNames[1]="type_qname_id", but was .alf_node_assoc.fk_alf_nass_tnode.columnNames="[target_node_id]"
Difference: expected index .alf_prop_class.idx_alf_propc_crc.name="idx_alf_propc_crc", but was .alf_prop_class.idx_alf_prop_class_crc.name="idx_alf_prop_class_crc"
Difference: expected index .alf_prop_class.idx_alf_propc_clas.name="idx_alf_propc_clas", but was .alf_prop_class.idx_alf_prop_class_class.name="idx_alf_prop_class_class"
Difference: expected index .alf_prop_date_value.idx_alf_propdt_dt.name="idx_alf_propdt_dt", but was .alf_prop_date_value.idx_alf_prop_date_units.name="idx_alf_prop_date_units"
Difference: expected index .alf_prop_double_value.idx_alf_propd_val.name="idx_alf_propd_val", but was .alf_prop_double_value.idx_alf_prop_dbl_val.name="idx_alf_prop_dbl_val"
Difference: missing foreign key from database, expected at path: .alf_prop_link.fk_alf_propln_root
Difference: missing foreign key from database, expected at path: .alf_prop_link.fk_alf_propln_key
Difference: missing foreign key from database, expected at path: .alf_prop_link.fk_alf_propln_val
Difference: unexpected foreign key found in database with path: .alf_prop_link.fk_alf_prop_link_key
Difference: unexpected foreign key found in database with path: .alf_prop_link.fk_alf_prop_link_root
Difference: unexpected foreign key found in database with path: .alf_prop_link.fk_alf_prop_link_val
Difference: expected index .alf_prop_link.fk_alf_propln_key.name="fk_alf_propln_key", but was .alf_prop_link.fk_alf_prop_link_key.name="fk_alf_prop_link_key"
Difference: expected index .alf_prop_link.fk_alf_propln_val.name="fk_alf_propln_val", but was .alf_prop_link.fk_alf_prop_link_val.name="fk_alf_prop_link_val"
Difference: expected index .alf_prop_link.idx_alf_propln_for.name="idx_alf_propln_for", but was .alf_prop_link.idx_alf_prop_link_for.name="idx_alf_prop_link_for"
Difference: expected index .alf_prop_string_value.idx_alf_props_crc.name="idx_alf_props_crc", but was .alf_prop_string_value.idx_alf_prop_crc.name="idx_alf_prop_crc"
Difference: expected index .alf_prop_string_value.idx_alf_props_str.name="idx_alf_props_str", but was .alf_prop_string_value.idx_alf_prop_str.name="idx_alf_prop_str"
Difference: missing column from database, expected at path: .alf_prop_unique_ctx.prop1_id
Difference: missing foreign key from database, expected at path: .alf_prop_unique_ctx.fk_alf_propuctx_v1
Difference: missing foreign key from database, expected at path: .alf_prop_unique_ctx.fk_alf_propuctx_v2
Difference: missing foreign key from database, expected at path: .alf_prop_unique_ctx.fk_alf_propuctx_v3
Difference: missing foreign key from database, expected at path: .alf_prop_unique_ctx.fk_alf_propuctx_p1
Difference: unexpected foreign key found in database with path: .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_1
Difference: unexpected foreign key found in database with path: .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_2
Difference: unexpected foreign key found in database with path: .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_3
Difference: expected index .alf_prop_unique_ctx.idx_alf_propuctx.name="idx_alf_propuctx", but was .alf_prop_unique_ctx.idx_alf_prop_unique_ctx.name="idx_alf_prop_unique_ctx"
Difference: expected index .alf_prop_unique_ctx.fk_alf_propuctx_v2.name="fk_alf_propuctx_v2", but was .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_2.name="fk_alf_prop_unique_ctx_2"
Difference: expected index .alf_prop_unique_ctx.fk_alf_propuctx_v3.name="fk_alf_propuctx_v3", but was .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_3.name="fk_alf_prop_unique_ctx_3"
Difference: missing index from database, expected at path: .alf_prop_unique_ctx.fk_alf_propuctx_p1
Difference: expected index .alf_prop_value.idx_alf_propv_act.name="idx_alf_propv_act", but was .alf_prop_value.idx_alf_prop_act.name="idx_alf_prop_act"
Difference: expected index .alf_prop_value.idx_alf_propv_per.name="idx_alf_propv_per", but was .alf_prop_value.idx_alf_prop_per.name="idx_alf_prop_per"
Difference: expected column .alf_server.ip_address.type="varchar(39)", but was .alf_server.ip_address.type="varchar(15)"
Difference: missing table from database, expected at path: .alf_subscriptions
Difference: unexpected table found in database with path: .alf_attributes
Difference: unexpected table found in database with path: .alf_audit_config
Difference: unexpected table found in database with path: .alf_audit_date
Difference: unexpected table found in database with path: .alf_audit_fact
Difference: unexpected table found in database with path: .alf_audit_source
Difference: unexpected table found in database with path: .alf_global_attributes
Difference: unexpected table found in database with path: .alf_list_attribute_entries
Difference: unexpected table found in database with path: .alf_map_attribute_entries
Difference: unexpected table found in database with path: .alf_version_count
And the post upgrade potential problem report gives me this:
Difference: expected index .alf_acl_member.aclm_acl_id.name="aclm_acl_id", but was .alf_acl_member.acl_id.name="acl_id"
Difference: missing foreign key from database, expected at path: .alf_audit_app.fk_alf_aud_app_an
Difference: missing foreign key from database, expected at path: .alf_audit_app.fk_alf_aud_app_mod
Difference: missing foreign key from database, expected at path: .alf_audit_app.fk_alf_aud_app_dis
Difference: unexpected foreign key found in database with path: .alf_audit_app.fk_alf_audit_app_app
Difference: unexpected foreign key found in database with path: .alf_audit_app.fk_alf_audit_app_dis
Difference: unexpected foreign key found in database with path: .alf_audit_app.fk_alf_audit_app_model
Difference: expected index .alf_audit_app.idx_alf_aud_app_an.name="idx_alf_aud_app_an", but was .alf_audit_app.idx_alf_audit_app_app.name="idx_alf_audit_app_app"
Difference: expected index .alf_audit_app.fk_alf_aud_app_mod.name="fk_alf_aud_app_mod", but was .alf_audit_app.fk_alf_audit_app_model.name="fk_alf_audit_app_model"
Difference: expected index .alf_audit_app.fk_alf_aud_app_dis.name="fk_alf_aud_app_dis", but was .alf_audit_app.fk_alf_audit_app_dis.name="fk_alf_audit_app_dis"
Difference: missing foreign key from database, expected at path: .alf_audit_entry.fk_alf_aud_ent_app
Difference: missing foreign key from database, expected at path: .alf_audit_entry.fk_alf_aud_ent_use
Difference: missing foreign key from database, expected at path: .alf_audit_entry.fk_alf_aud_ent_pro
Difference: unexpected foreign key found in database with path: .alf_audit_entry.fk_alf_audit_ent_app
Difference: unexpected foreign key found in database with path: .alf_audit_entry.fk_alf_audit_ent_prop
Difference: unexpected foreign key found in database with path: .alf_audit_entry.fk_alf_audit_ent_user
Difference: expected index .alf_audit_entry.fk_alf_aud_ent_app.name="fk_alf_aud_ent_app", but was .alf_audit_entry.fk_alf_audit_ent_app.name="fk_alf_audit_ent_app"
Difference: expected index .alf_audit_entry.idx_alf_aud_ent_tm.name="idx_alf_aud_ent_tm", but was .alf_audit_entry.idx_alf_audit_ent_time.name="idx_alf_audit_ent_time"
Difference: expected index .alf_audit_entry.fk_alf_aud_ent_use.name="fk_alf_aud_ent_use", but was .alf_audit_entry.fk_alf_audit_ent_user.name="fk_alf_audit_ent_user"
Difference: expected index .alf_audit_entry.fk_alf_aud_ent_pro.name="fk_alf_aud_ent_pro", but was .alf_audit_entry.fk_alf_audit_ent_prop.name="fk_alf_audit_ent_prop"
Difference: missing foreign key from database, expected at path: .alf_audit_model.fk_alf_aud_mod_cd
Difference: unexpected foreign key found in database with path: .alf_audit_model.fk_alf_audit_model_cd
Difference: expected index .alf_audit_model.idx_alf_aud_mod_cr.name="idx_alf_aud_mod_cr", but was .alf_audit_model.idx_alf_audit_cfg_crc.name="idx_alf_audit_cfg_crc"
Difference: expected index .alf_audit_model.fk_alf_aud_mod_cd.name="fk_alf_aud_mod_cd", but was .alf_audit_model.fk_alf_audit_model_cd.name="fk_alf_audit_model_cd"
Difference: expected index .alf_prop_class.idx_alf_propc_crc.name="idx_alf_propc_crc", but was .alf_prop_class.idx_alf_prop_class_crc.name="idx_alf_prop_class_crc"
Difference: expected index .alf_prop_class.idx_alf_propc_clas.name="idx_alf_propc_clas", but was .alf_prop_class.idx_alf_prop_class_class.name="idx_alf_prop_class_class"
Difference: expected index .alf_prop_date_value.idx_alf_propdt_dt.name="idx_alf_propdt_dt", but was .alf_prop_date_value.idx_alf_prop_date_units.name="idx_alf_prop_date_units"
Difference: expected index .alf_prop_double_value.idx_alf_propd_val.name="idx_alf_propd_val", but was .alf_prop_double_value.idx_alf_prop_dbl_val.name="idx_alf_prop_dbl_val"
Difference: missing foreign key from database, expected at path: .alf_prop_link.fk_alf_propln_root
Difference: missing foreign key from database, expected at path: .alf_prop_link.fk_alf_propln_key
Difference: missing foreign key from database, expected at path: .alf_prop_link.fk_alf_propln_val
Difference: unexpected foreign key found in database with path: .alf_prop_link.fk_alf_prop_link_key
Difference: unexpected foreign key found in database with path: .alf_prop_link.fk_alf_prop_link_root
Difference: unexpected foreign key found in database with path: .alf_prop_link.fk_alf_prop_link_val
Difference: expected index .alf_prop_link.fk_alf_propln_key.name="fk_alf_propln_key", but was .alf_prop_link.fk_alf_prop_link_key.name="fk_alf_prop_link_key"
Difference: expected index .alf_prop_link.fk_alf_propln_val.name="fk_alf_propln_val", but was .alf_prop_link.fk_alf_prop_link_val.name="fk_alf_prop_link_val"
Difference: expected index .alf_prop_link.idx_alf_propln_for.name="idx_alf_propln_for", but was .alf_prop_link.idx_alf_prop_link_for.name="idx_alf_prop_link_for"
Difference: expected index .alf_prop_string_value.idx_alf_props_crc.name="idx_alf_props_crc", but was .alf_prop_string_value.idx_alf_prop_crc.name="idx_alf_prop_crc"
Difference: expected index .alf_prop_string_value.idx_alf_props_str.name="idx_alf_props_str", but was .alf_prop_string_value.idx_alf_prop_str.name="idx_alf_prop_str"
Difference: missing foreign key from database, expected at path: .alf_prop_unique_ctx.fk_alf_propuctx_v1
Difference: missing foreign key from database, expected at path: .alf_prop_unique_ctx.fk_alf_propuctx_v2
Difference: missing foreign key from database, expected at path: .alf_prop_unique_ctx.fk_alf_propuctx_v3
Difference: unexpected foreign key found in database with path: .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_1
Difference: unexpected foreign key found in database with path: .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_2
Difference: unexpected foreign key found in database with path: .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_3
Difference: expected index .alf_prop_unique_ctx.idx_alf_propuctx.name="idx_alf_propuctx", but was .alf_prop_unique_ctx.idx_alf_prop_unique_ctx.name="idx_alf_prop_unique_ctx"
Difference: expected index .alf_prop_unique_ctx.fk_alf_propuctx_v2.name="fk_alf_propuctx_v2", but was .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_2.name="fk_alf_prop_unique_ctx_2"
Difference: expected index .alf_prop_unique_ctx.fk_alf_propuctx_v3.name="fk_alf_propuctx_v3", but was .alf_prop_unique_ctx.fk_alf_prop_unique_ctx_3.name="fk_alf_prop_unique_ctx_3"
Difference: expected index .alf_prop_value.idx_alf_propv_act.name="idx_alf_propv_act", but was .alf_prop_value.idx_alf_prop_act.name="idx_alf_prop_act"
Difference: expected index .alf_prop_value.idx_alf_propv_per.name="idx_alf_propv_per", but was .alf_prop_value.idx_alf_prop_per.name="idx_alf_prop_per"
Difference: unexpected table found in database with path: .alf_attributes
Difference: unexpected table found in database with path: .alf_audit_config
Difference: unexpected table found in database with path: .alf_audit_date
Difference: unexpected table found in database with path: .alf_audit_fact
Difference: unexpected table found in database with path: .alf_audit_source
Difference: unexpected table found in database with path: .alf_global_attributes
Difference: unexpected table found in database with path: .alf_list_attribute_entries
Difference: unexpected table found in database with path: .alf_map_attribute_entries
Looks like most of these problems are quite cosmetic though, indexes and foreign keys that are slightly different names, unexpected (so I'm guessing now unused tables) found, etc. Could all be fixed manually I guess, however I'm a little surprised the scripts don't cater for all this and tidy up fully, making sure the schema is fully up to date and in line with the 4.0.d design.
But yes, to reiterate, we cannot access the new Alfresco version hosted on our new staging environment for the migration. When trying to access either /share or /alfresco, eventually, after many minutes, it times out with a server error.
A lengthy problem report but there's all the detail I think anyone will need to lend their insight
How do we proceed so we get our Alfresco installation migrated successfully to 4.0.d?And any such offers will be greatly appreciated, I've hit a dead end with this.
Thanks in advance.
