topic Re: Upgrade proceeds normally, result abnormal in Alfresco Archive

Upgrade proceeds normally, result abnormal

zshay — Thu, 12 Mar 2009 21:13:04 GMT

Thanks for the earlier help by telling me to remove the # from a properties file. Hopefully this is just as easy….After confirming our latest version of alfresco installed properly I did the following:*ran db_remove.sql script to remove the alfresco database and alfresco user(s)*removed the generat

Re: Upgrade proceeds normally, result abnormal

zshay — Fri, 13 Mar 2009 18:34:07 GMT

Hello all,

Here is a posting of the log when it attempted the upgrade. And also the log from the next startup (after the upgrade)….

Thanks everyone,
Zach

Updgrade and shutdown


15:29:37,620 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
15:29:42,014 INFO  [org.alfresco.web.scripts.DeclarativeRegistry] Registered 67 Web Scripts (+0 failed), 71 URLs
15:29:42,016 INFO  [org.alfresco.web.scripts.AbstractRuntimeContainer] Initialised Presentation Web Script Container (in 599.58075ms)
15:30:43,348 INFO  [org.alfresco.web.scripts.DeclarativeRegistry] Registered 101 Web Scripts (+0 failed), 105 URLs
15:30:43,348 INFO  [org.alfresco.web.scripts.AbstractRuntimeContainer] Initialised WebFramework Web Script Container (in 61312.965ms)
15:30:43,436 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
15:31:20,011 INFO  [org.alfresco.config.xml.XMLConfigService$PropertyConfigurer] Loading properties file from class path resource [alfresco/file-servers.proper
ties]
15:31:44,038 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
15:31:44,463 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaCreate-org.hibe
rnate.dialect.MySQLInnoDBDialect-305086269638884247.sql (Generated).
15:31:49,673 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-4877697821738664976.sql (Copied from classpath:alfresco/dbscripts/create/2.2/org.hibernate.dialect.MySQLInnoDBDialect/Alfresco
PostCreate-2.2-MappedFKIndexes.sql).
15:31:49,737 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-1369661523202798734.sql (Copied from classpath:alfresco/dbscripts/create/2.2/org.hibernate.dialect.MySQLInnoDBDialect/Alfresco
PostCreate-2.2-Extra.sql).
15:31:50,035 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-8329891332788881796.sql (Copied from classpath:alfresco/dbscripts/create/2.2/org.hibernate.dialect.MySQLInnoDBDialect/post-cre
ate-indexes-04.sql).
15:31:50,062 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-5199560369683909185.sql (Copied from classpath:alfresco/dbscripts/create/3.0/org.hibernate.dialect.MySQLInnoDBDialect/create-a
ctivities-extras.sql).
15:31:50,239 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] All executed statements written to file /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaU
pdate-All_Statements-7097954446266868388.sql.
15:32:38,088 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /opt/alfresco/alf_data
15:32:38,435 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
15:32:41,962 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.wcmFolders' (Ensures the existance of the WCM specific 'Web Proje
cts' and 'Web Forms' folders.).
15:32:42,910 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.0-0-CreateActivitiesExtras
RESULT:
Script completed
=====================================
15:32:42,911 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.wcmFolders
RESULT:
The Web Projects folder was successfully created: workspace://SpacesStore/c394d4d9-a68b-4ee9-b602-e317f7a7d122
The Web Forms folder was successfully created: workspace://SpacesStore/c394d4d9-a68b-4ee9-b602-e317f7a7d122
=====================================
15:32:43,090 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Found 0 module(s).
15:32:48,599 ERROR [org.alfresco.smb.protocol] Failed to get local domain/workgroup name, using default of WORKGROUP
15:32:49,008 ERROR [org.alfresco.smb.protocol] (This may be due to firewall settings or incorrect <broadcast> setting)
15:32:54,460 WARN  [org.alfresco.util.OpenOfficeConnectionTester] An initial OpenOffice connection could not be established.
15:32:57,072 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_12-b04; maximum heap size 506.313MB
15:32:57,581 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Labs): Current version 3.0.0 (Stable 1526) schema 1002 - Installed ver
sion 3.0.0 (Stable 1526) schema 1002
15:36:07,055 INFO  [org.alfresco.util.OpenOfficeConnectionTester] The OpenOffice connection was re-established.
16:17:26,134 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
16:17:30,563 INFO  [org.alfresco.web.scripts.DeclarativeRegistry] Registered 67 Web Scripts (+0 failed), 71 URLs
16:17:30,565 INFO  [org.alfresco.web.scripts.AbstractRuntimeContainer] Initialised Presentation Web Script Container (in 624.1711ms)
16:18:32,520 INFO  [org.alfresco.web.scripts.DeclarativeRegistry] Registered 101 Web Scripts (+0 failed), 105 URLs
16:18:32,527 INFO  [org.alfresco.web.scripts.AbstractRuntimeContainer] Initialised WebFramework Web Script Container (in 61943.242ms)
16:18:32,620 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
16:19:09,599 INFO  [org.alfresco.config.xml.XMLConfigService$PropertyConfigurer] Loading properties file from class path resource [alfresco/file-servers.proper
ties]
16:19:34,191 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
16:19:34,577 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-4624949678996554556.sql (Copied from classpath:alfresco/dbscripts/upgrade/2.2/org.hibernate.dialect.MySQLInnoDBDialect/Alfresc
oSchemaUpdate-2.2-ACL.sql).
16:19:35,210 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-5710985921303193992.sql (Copied from classpath:alfresco/dbscripts/upgrade/2.2/org.hibernate.dialect.MySQLInnoDBDialect/upgrade
-0-create-missing-tables.sql).
16:19:35,317 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-4397874497862175182.sql (Copied from classpath:alfresco/dbscripts/upgrade/2.2/org.hibernate.dialect.MySQLInnoDBDialect/upgrade
-from-2.1.sql).
16:20:03,974 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-8222585827419458966.sql (Copied from classpath:alfresco/dbscripts/upgrade/2.2/org.hibernate.dialect.MySQLInnoDBDialect/Alfresc
oSchemaUpdate-Person.sql).
16:20:09,620 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-3756781264885022433.sql (Generated).
16:20:09,968 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-9113946248588391611.sql (Copied from classpath:alfresco/dbscripts/upgrade/1.4/org.hibernate.dialect.MySQLInnoDBDialect/Alfresc
oSchemaUpdate-1.4-TxnCommitTimeIndex.sql).
16:20:10,468 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-9135208295771923546.sql (Copied from classpath:alfresco/dbscripts/upgrade/2.0/org.hibernate.dialect.MySQLInnoDBDialect/Alfresc
oSchemaUpdate-2.0-ContentUrls.sql).
16:20:10,517 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-4192881469341196331.sql (Copied from classpath:alfresco/dbscripts/upgrade/2.1/org.hibernate.dialect.MySQLInnoDBDialect/Alfresc
oSchemaUpdate-2.1-RemoveWcmSubmittedAspect.sql).
16:20:10,540 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-1288421216208302347.sql (Copied from classpath:alfresco/dbscripts/upgrade/2.2/org.hibernate.dialect.MySQLInnoDBDialect/Alfresc
oSchemaUpdate-2.1-AuditPathIndex.sql).
16:20:10,594 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Executing database script /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaUpdate-org.hibe
rnate.dialect.MySQLInnoDBDialect-992709741821115210.sql (Copied from classpath:alfresco/dbscripts/create/3.0/org.hibernate.dialect.MySQLInnoDBDialect/create-ac
tivities-extras.sql).
16:20:10,895 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] All executed statements written to file /opt/alfresco/tomcat/temp/Alfresco/AlfrescoSchemaU
pdate-All_Statements-3282423530389438601.sql.
16:20:48,454 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /opt/alfresco/alf_data
16:20:49,428 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
16:20:52,530 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.webscripts2' (Adds Web Scripts (second set) to Data Dictionary.).
16:20:57,877 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.customModels' (Adds 'Models' folder to Data Dictionary).
16:21:01,853 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.customMessages' (Adds 'Messages' folder to Data Dictionary).
16:21:02,349 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.customWebClientExtension' (Adds 'Web Client Extension' folder to
Data Dictionary).
16:21:02,865 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.webscripts3' (Update Web Scripts ReadMe.).
16:21:05,320 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.customWorkflowDefs' (Adds 'Workflow Definitions' folder to Data D
ictionary.).
16:21:06,698 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.emailContributorGroup' (Adds the 'GROUP_EMAIL_CONTRIBUTORS' group
.).
16:21:10,625 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.avmStoreAsIdentifier' (Reindex wca:webfolder to make wca:avmstore
 an identifier).
16:21:11,730 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.avmFormPropertyIdentifier' (Reindex wca:webform to make wca:formn
ame an identifier).
16:21:12,859 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.formsFolder' (Adds 'Forms' folder to Data Dictionary.).
16:21:13,448 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.tagRootCategory' (Adds 'Tags' as new top-level category root.).
16:21:16,747 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.deploymentMigration' (Migrates deployment data to the new model.)
.
16:21:17,068 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.updateAvmPermissionData' (Update avm permissions from 'webfolder'
 to 'cmobject'.).
16:21:17,900 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.updateAvmPermissions' (Update ACLs on all avm objects to the new
2.2 permission model).
16:21:21,473 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.db-V2.2-2-MoveQNames' (A placeholder patch; usually marks a super
ceded patch.).
16:21:22,281 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.wcmPermissionPatch' (Fix ACLs so they are only set on the staging
 area store.).
16:21:22,390 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.wcmPostPermissionSnapshotPatch' (Snapshot stores (after fixing AC
Ls so they are only set on the staging area store).).
16:21:24,082 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.avmWebProjectInheritPermissions02' (Break inheritance of permissi
ons on wca:webfolder object to hide access by default.).
16:21:24,115 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.updateDmPermissions' (Update ACLs on all DM node objects to the n
ew 3.0 permission model).
16:22:25,024 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.createSiteStore' (Create the AVM store for site data structure fo
r 3.0 web-tier clients.).
16:22:25,281 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.sitesFolder' (Adds 'Sites' folder to Company Home.).
16:22:27,635 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.sitePermissionRefactorPatch' (Create permission groups for sites.
).
16:22:27,873 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.migrateVersionStore' (Migrate from lightWeightVersionStore to ver
sion2Store).
16:22:38,036 INFO  [org.alfresco.repo.version.VersionMigrator] Completed migration of 4 old version histories (to new version store) in 10 secs
16:22:38,064 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.inviteEmailTemplate' (Adds invite email template to invite space)
.
16:22:38,509 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.calendarNamespaceUri' (Update the Calendar model namespace URI an
d reindex all calendar objects.).
16:22:38,623 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.spacesStoreGuestPermission' (Sets READ permissions for GUEST on r
oot node of the SpacesStore.).
16:22:55,405 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.redeploySubmitProcess4' (Re-deploy WCM Submit Process Definition.
).
16:22:57,617 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.administratorGroup' (Adds the 'ALFRESCO_ADMINISTRATORS' group.).
16:22:57,847 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]        Applying patch 'patch.wcmFolders' (Ensures the existance of the WCM specific 'Web Proje
cts' and 'Web Forms' folders.).
16:22:58,628 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.administratorGroup
RESULT:
Imported view into bootstrap location: /sys:system/sys:authorities (user://alfrescoUserStore/52c0d018-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,628 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.avmFormPropertyIdentifier
RESULT:
Reindexed wca:webform to make wca:formname an identifier
=====================================
16:22:58,628 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.avmStoreAsIdentifier
RESULT:
Reindexed wca:webfolder to make wca:avmstore an identifier
=====================================
16:22:58,629 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.avmWebProjectInheritPermissions02
RESULT:
Removed inheritance of permissions on all wca:webfolder objects.
=====================================
16:22:58,629 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.calendarNamespaceUri
RESULT:
Updated the Calendar model namespace URI to http://www.alfresco.org/model/calendar and reindexed 0 calendar objects.
=====================================
16:22:58,629 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.createSiteStore
RESULT:
Created the AVM site data store.
=====================================
16:22:58,629 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.customMessages
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,629 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.customModels
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,629 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.customWebClientExtension
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,629 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.customWorkflowDefs
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,629 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V1.4-TxnCommitTimeIndex
RESULT:
Script completed
=====================================
16:22:58,632 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.0-ContentUrls
RESULT:
Script completed
=====================================
16:22:58,632 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.1-AuditPathIndex
RESULT:
Script completed
=====================================
16:22:58,632 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.1-RemoveWcmSubmittedAspect
RESULT:
Script completed
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.2-0-CreateMissingTables
RESULT:
Script completed
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.2-2-MoveQNames
RESULT:
No-op patch
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.2-ACL
RESULT:
Script completed
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.2-Person
RESULT:
Script completed
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.2-Upgrade-From-2.1
RESULT:
Script completed
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V2.2-Upgrade-From-2.2SP1
RESULT:
Preceeded by alternative patch 'patch.db-V2.2-Upgrade-From-2.1'.
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.0-0-CreateActivitiesExtras
RESULT:
Script completed
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.deploymentMigration
RESULT:
Deployment data has been migrated.
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.emailContributorGroup
RESULT:
Imported view into bootstrap location: /sys:system/sys:authorities (user://alfrescoUserStore/52c0d018-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.formsFolder
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,633 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.inviteEmailTemplate
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary/app:email_templates (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.migrateVersionStore
RESULT:
Migrated version store. Created 4 version histories
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.redeploySubmitProcess4
RESULT:
Re-deployed 1 workflows.
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.sitePermissionRefactorPatch
RESULT:
Groups have been created for all sites and user's allocated accordingly.
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.sitesFolder
RESULT:
Imported view into bootstrap location: /app:company_home (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.spacesStoreGuestPermission
RESULT:
Granted READ permissions for GUEST on root node of the SpacesStore.
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.tagRootCategory
RESULT:
Imported view into bootstrap location: /cm:categoryRoot (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.updateAvmPermissionData
RESULT:
Changed 4 webfolder access control entries to cmobject.
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.updateAvmPermissions
RESULT:
Updated ACLs. Created 0 defining and 0 layered ACLs.
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.updateDmPermissions
RESULT:
Updated ACLs. Created 300 defining ACLs.
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.wcmFolders
RESULT:
The Web Projects folder was successfully created: workspace://SpacesStore/f3939f24-de8e-4031-8ce5-2fa4e924bba6
The Web Forms folder was successfully created: workspace://SpacesStore/f3939f24-de8e-4031-8ce5-2fa4e924bba6
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.wcmPermissionPatch
RESULT:
Updated ACLs: ACLS are moved to the staging area store and removed from all other stores. They are now applied via layering.
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.wcmPostPermissionSnapshotPatch
RESULT:
Snapshot complete after WCM ACL changes.
=====================================
16:22:58,634 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.webscripts2
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,635 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.webscripts3
RESULT:
Imported view into bootstrap location: / (workspace://SpacesStore/536db21c-081b-11dd-8385-47e9defe8f3c)
=====================================
16:22:58,771 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Found 0 module(s).
16:26:00,909 ERROR [org.alfresco.smb.protocol] Failed to get local domain/workgroup name, using default of WORKGROUP
16:26:00,960 ERROR [org.alfresco.smb.protocol] (This may be due to firewall settings or incorrect <broadcast> setting)
16:26:30,134 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_12-b04; maximum heap size 506.313MB
16:26:30,197 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Labs): Current version 3.0.0 (Stable 1526) schema 1002 - Installed ver
sion 2.1.0 (484) schema 64
16:26:36,468 INFO  [org.alfresco.repo.version.VersionMigrator]          Background deletion of migrated version histories (from old version store) 20% complete
, estimated complete at 3/12/09 4:27 PM
16:26:39,908 INFO  [org.alfresco.repo.version.VersionMigrator] Completed background deletion of 4 migrated version histories (from old version store) in 26 sec
s
16:46:41,277 ERROR [org.alfresco.repo.shutdown.ShutdownBackstop] Alfresco terminating via Shutdown Backstop
16:46:42,403 ERROR [org.alfresco.repo.shutdown.ShutdownBackstop] Alfresco terminated
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Startup after upgrade


16:47:28,355 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
16:47:32,931 INFO  [org.alfresco.web.scripts.DeclarativeRegistry] Registered 67 Web Scripts (+0 failed), 71 URLs
16:47:32,933 INFO  [org.alfresco.web.scripts.AbstractRuntimeContainer] Initialised Presentation Web Script Container (in 597.852ms)
16:48:34,390 INFO  [org.alfresco.web.scripts.DeclarativeRegistry] Registered 101 Web Scripts (+0 failed), 105 URLs
16:48:34,390 INFO  [org.alfresco.web.scripts.AbstractRuntimeContainer] Initialised WebFramework Web Script Container (in 61438.746ms)
16:48:34,476 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
16:49:12,321 INFO  [org.alfresco.config.xml.XMLConfigService$PropertyConfigurer] Loading properties file from class path resource [alfresco/file-servers.proper
ties]
16:49:35,882 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
16:49:45,959 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] No changes were made to the schema.
16:49:56,263 WARN  [org.alfresco.repo.node.index.FullIndexRecoveryComponent] The indexes are not synchronized with the database.
16:50:25,619 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /opt/alfresco/alf_data
16:50:26,625 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
16:50:27,209 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] No patches were required.
16:50:27,296 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Found 0 module(s).
16:50:28,545 ERROR [org.alfresco.smb.protocol] Failed to get local domain/workgroup name, using default of WORKGROUP
16:50:28,545 ERROR [org.alfresco.smb.protocol] (This may be due to firewall settings or incorrect <broadcast> setting)
16:50:30,694 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_12-b04; maximum heap size 506.313MB
16:50:30,695 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Labs): Current version 3.0.0 (Stable 1526) schema 1002 - Installed ver
sion 2.1.0 (484) schema 64
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Re: Upgrade proceeds normally, result abnormal

zshay — Thu, 19 Mar 2009 16:06:39 GMT

Hello all,

I have reinstalled alfresco with "index.recovery.mode=AUTO", and no longer am seeing warning/error messages regarding indexes being out of sync with the database. Alfresco still throws a major error (displayed on the webpage) when I try and access it via a web browser. Any ideas on how to fix this? This upgrade is a 2.1.0 -> 3.0Stable migration…

Kind regards,
Zach


org.alfresco.service.cmr.repository.InvalidNodeRefException: Node does not exist: workspace://SpacesStore/1b3d1404-a16a-48e6-94d5-106dc5111e6a

Hide Details

org.alfresco.service.cmr.repository.InvalidNodeRefException: Node does not exist: workspace://SpacesStore/1b3d1404-a16a-48e6-94d5-106dc5111e6a
at org.alfresco.repo.node.db.DbNodeServiceImpl.getNodePairNotNull(DbNodeServiceImpl.java:156)
at org.alfresco.repo.node.db.DbNodeServiceImpl.hasAspect(DbNodeServiceImpl.java:712)
at sun.reflect.GeneratedMethodAccessor300.invoke(Unknown Source)
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:296)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:177)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:144)
at org.alfresco.repo.transaction.SingleEntryTransactionResourceInterceptor.invokeInternal(SingleEntryTransactionResourceInterceptor.java:163)
at org.alfresco.repo.transaction.SingleEntryTransactionResourceInterceptor.invoke(SingleEntryTransactionResourceInterceptor.java:138)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy7.hasAspect(Unknown Source)
at sun.reflect.GeneratedMethodAccessor300.invoke(Unknown Source)
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:296)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:177)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:144)
at org.alfresco.repo.tenant.MultiTNodeServiceInterceptor.invoke(MultiTNodeServiceInterceptor.java:110)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy7.hasAspect(Unknown Source)
at sun.reflect.GeneratedMethodAccessor300.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:221)
at $Proxy8.hasAspect(Unknown Source)
at sun.reflect.GeneratedMethodAccessor300.invoke(Unknown Source)
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:296)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:177)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:144)
at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:306)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:306)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:274)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:274)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy7.hasAspect(Unknown Source)
at sun.reflect.GeneratedMethodAccessor300.invoke(Unknown Source)
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:296)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:177)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:144)
at net.sf.acegisecurity.intercept.method.aopalliance.MethodSecurityInterceptor.invoke(MethodSecurityInterceptor.java:80)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.alfresco.repo.security.permissions.impl.ExceptionTranslatorMethodInterceptor.invoke(ExceptionTranslatorMethodInterceptor.java:49)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.alfresco.repo.audit.AuditComponentImpl.audit(AuditComponentImpl.java:275)
at org.alfresco.repo.audit.AuditMethodInterceptor.invoke(AuditMethodInterceptor.java:69)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:107)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:166)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy7.hasAspect(Unknown Source)
at org.alfresco.web.bean.repository.User$1.execute(User.java:231)
at org.alfresco.web.bean.repository.User$1.execute(User.java:227)
at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:320)
at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:227)
at org.alfresco.web.bean.repository.User.getUserPreferencesRef(User.java:224)
at org.alfresco.web.bean.repository.User.getPreferences(User.java:206)
at org.alfresco.web.bean.repository.PreferencesService.getPreferences(PreferencesService.java:71)
at org.alfresco.web.app.Application.getLanguage(Application.java:688)
at org.alfresco.web.app.servlet.AuthenticationHelper.authenticate(AuthenticationHelper.java:246)
at org.alfresco.web.app.servlet.AuthenticationHelper.authenticate(AuthenticationHelper.java:106)
at org.alfresco.web.app.servlet.BaseServlet.servletAuthenticate(BaseServlet.java:156)
at org.alfresco.web.app.servlet.BaseServlet.servletAuthenticate(BaseServlet.java:126)
at org.alfresco.web.app.servlet.ExternalAccessServlet.service(ExternalAccessServlet.java:98)
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.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:128)
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:286)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:845)
at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
at java.lang.Thread.run(Thread.java:619)
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍