Tomcat crashes on PDF upload (3.4a)

[mhmorys]

Hello,

I am facing a serious and reproducible problem with Alfresco 3.4a. Whenever I upload some specific PDF files, tomcat crashes with the following stdout_DATE.log:

10:08:44,415  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
10:08:44,431  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
10:08:44,446  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\webapps\alfresco\WEB-INF\classes\alfresco\module\org_alfresco_module_dod5015\alfresco-global.properties]
10:08:44,462  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\webapps\alfresco\WEB-INF\classes\alfresco\module\org_alfresco_module_wcmquickstart\alfresco-global.properties]
10:08:44,462  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\webapps\alfresco\WEB-INF\classes\alfresco\module\test\alfresco-global.properties]
10:08:44,462  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [C:\Alfresco\tomcat\webapps\alfresco\WEB-INF\classes\alfresco\module\tests\alfresco-global.properties]
10:08:44,462  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/C:/Alfresco/tomcat/shared/classes/alfresco-global.properties]
10:08:44,603  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:09:30,149  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'serviceBeans' being accessed! Ambiguous write methods found next to actually used [public void org.apache.cxf.jaxrs.JAXRSServerFactoryBean.setServiceBeans(java.lang.Object[])]: [public void org.apache.cxf.jaxrs.JAXRSServerFactoryBean.setServiceBeans(java.util.List)]
10:09:33,431  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
10:09:33,431  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
10:09:44,993  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
10:09:47,618  INFO  [domain.schema.SchemaBootstrap] No changes were made to the schema.
10:09:47,806  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
10:09:47,837  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:09:48,165  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
10:09:51,118  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
10:09:51,196  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:09:53,181  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
10:09:53,181  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
10:09:53,212  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:09:54,696  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
10:09:57,118  INFO  [repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: C:\Alfresco\alf_data
10:09:57,228  INFO  [admin.patch.PatchExecuter] Sucht nach anwendbaren Patches…
10:09:59,165  INFO  [admin.patch.PatchExecuter] Es sind keine Patches erforderlich
10:09:59,243 User:System INFO  [repo.module.ModuleServiceImpl] 4 Modul(e) gefunden.
10:09:59,384 User:System INFO  [repo.module.ModuleServiceImpl] Starte Modul 'org_alfresco_module_wcmquickstart' Version 1.0.
10:10:00,603 User:System INFO  [repo.module.ModuleServiceImpl] Starte Modul 'org_alfresco_module_dod5015' Version 1.0.
10:10:00,728 User:System INFO  [repo.module.ModuleServiceImpl] Starte Modul 'org.alfresco.module.vti' Version 1.2.
10:10:00,837 User:System INFO  [repo.module.ModuleServiceImpl] Starte Modul 'org.alfresco.module.quickr' Version 1.0.
10:10:00,884  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
10:10:01,056  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:02,103  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
10:10:02,165  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:02,556  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
10:10:02,681  ERROR [org.alfresco.fileserver] Error accessing Win32 NetBIOS, check DLL is on the path
10:10:02,774  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
10:10:02,774  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
10:10:02,821  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:03,024  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
10:10:03,024  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, outbound]
10:10:03,056  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:03,321  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, outbound] complete
10:10:03,321  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, inbound]
10:10:03,353  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:03,493  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'blockedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.lang.String)]: [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.util.List)]
10:10:03,493  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'allowedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.util.List)]: [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.lang.String)]
10:10:03,540  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, inbound] complete
10:10:03,540  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'googledocs' subsystem, ID: [googledocs, default]
10:10:03,618  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:05,634  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
10:10:05,634  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
10:10:05,728  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
10:10:05,728  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
10:10:05,728  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
10:10:05,790  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:07,181  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
10:10:07,415  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_18-b07; maximum heap size 455,125MB
10:10:07,415  WARN  [service.descriptor.DescriptorService] Alfresco JVM - WARNING - maximum heap size 455,125MB is less than recommended 512MB
10:10:07,415  INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.4.0 (a 3169) schema 4111 - Originally installed version 3.4.0 (a 3169) schema 4111
10:10:07,493  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Replication' subsystem, ID: [Replication, default]
10:10:07,524  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:07,556  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Replication' subsystem, ID: [Replication, default] complete
10:10:13,618  INFO  [module.vti.VtiServer] Vti server started successfully on port: 7070
10:10:20,353  INFO  [post.lookup.PostLookup] Update: 1 activity posts
10:10:50,384 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 443 Web Scripts (+0 failed), 694 URLs
10:10:50,384 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 2 Package Description Documents (+0 failed) 
10:10:50,384 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 1 Schema Description Documents (+0 failed) 
10:10:50,399 User:System INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Repository Web Script Container (in 20728.543ms)
10:10:50,399  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
10:10:50,399  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
10:10:51,149  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'wcm_deployment_receiver' subsystem, ID: [wcm_deployment_receiver, default]
10:10:51,228  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:10:51,399  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'wcm_deployment_receiver' subsystem, ID: [wcm_deployment_receiver, default] complete
10:10:51,618  INFO  [activities.feed.FeedTaskProcessor] >>> Generated 4 activity feed entries for 1 activity posts (in 1390 msecs)
10:11:18,665  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 301 Web Scripts (+0 failed), 309 URLs
10:11:18,665  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 8 Package Description Documents (+0 failed) 
10:11:18,665  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 0 Schema Description Documents (+0 failed) 
10:11:19,118  INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Spring Surf Container Web Script Container (in 5271.874ms)
10:11:19,290  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
10:11:19,462  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
10:11:19,743  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
10:11:19,743  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
10:11:20,134  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
10:11:20,134  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
WARN : org.springframework.beans.GenericTypeAwarePropertyDescriptor - Invalid JavaBean property 'baseUrl' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.wcm.client.impl.WebScriptCallerImpl.setBaseUrl(java.net.URI)]: [public void org.alfresco.wcm.client.impl.WebScriptCallerImpl.setBaseUrl(java.lang.String) throws java.net.URISyntaxException]
WARN : net.sf.ehcache.config.ConfigurationFactory - No configuration found. Configuring ehcache from ehcache-failsafe.xml  found in the classpath: jar:file:/C:/Alfresco/tomcat/webapps/wcmqs/WEB-INF/lib/ehcache-core-2.0.0.jar!/ehcache-failsafe.xml
Exception in thread "Thread-64" org.apache.chemistry.opencmis.commons.exceptions.CmisRuntimeException: Unauthorized
   at org.apache.chemistry.opencmis.client.bindings.spi.atompub.AbstractAtomPubService.convertStatusCode(AbstractAtomPubService.java:411)
   at org.apache.chemistry.opencmis.client.bindings.spi.atompub.AbstractAtomPubService.read(AbstractAtomPubService.java:483)
   at org.apache.chemistry.opencmis.client.bindings.spi.atompub.AbstractAtomPubService.getRepositoriesInternal(AbstractAtomPubService.java:635)
   at org.apache.chemistry.opencmis.client.bindings.spi.atompub.RepositoryServiceImpl.getRepositoryInfos(RepositoryServiceImpl.java:68)
   at org.apache.chemistry.opencmis.client.bindings.impl.RepositoryServiceImpl.getRepositoryInfos(RepositoryServiceImpl.java:85)
   at org.apache.chemistry.opencmis.client.runtime.SessionFactoryImpl.getRepositories(SessionFactoryImpl.java:108)
   at org.alfresco.wcm.client.util.impl.GuestSessionFactoryImpl.getRepository(GuestSessionFactoryImpl.java:105)
   at org.alfresco.wcm.client.util.impl.GuestSessionFactoryImpl.run(GuestSessionFactoryImpl.java:115)
   at java.lang.Thread.run(Thread.java:619)‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

On restart, I can access the site, but whenever I try to load the document library or the document itself, tomcat crashes again. The only solution is to delete the whole site.

I could not identify, what the problem is, since there are different PDF files causing the problem, having nothing special in common (no big size, text, pictures etc., some are to PDF converted PPT slides).  I tested this on two different machines (Windows Server 2008, XP), the problem occured on both.

I first thought, it would be a problem with Flash and mutliple file upload or not enough memory for the JVM. Uploading every single file in some directories lead me to those files causing the problem.

I installed 3.3g (which apperently has some issues regarding IE8 / Flash 10) and did not have the problem, uploading, preview etc. is working fine.

I found some (unsolved) threads with similar problems, but no solution yet. Can anybody help?

[leonk]

Did you try the solution I've posted here earlier?
It worked for me.

[mthomas]

Yep, just replaced the 1.2 jar files with 1.3.1
This solved the problem.
Thanks!!!

[jriker1]

Where are you guy snagging pdfbox 1.3 or 1.3.1 from?  The version from the PDFBox site is like 27k and the version 1.2.1 that comes with Alfresco is 4.7 megs.  One comment made it sound like it comes with Alfresco but it does not.

Thanks.

[mikeh]

Where are you guy snagging pdfbox 1.3 or 1.3.1 from?  The version from the PDFBox site is like 27k and the version 1.2.1 that comes with Alfresco is 4.7 megs.  One comment made it sound like it comes with Alfresco but it does not.

Thanks.

http://pdfbox.apache.org/download.html

Thanks,
Mike

[jriker1]

Thanks MIke I tried that but it didn't work.  Found out it was wget not working.  Need to go a few layers down to get to the direct download.  Hate these sites that list a file on the task bar as a direct link to the file but are really just a redirect.

Thanks.

JR

[mhmorys]

What exactly do I have to configure to get pdfbox 1.3.1 and fontbox running instead of v1.2? Just copying at the location where the old jar's are located and renaming the old versions didn't do the trick. Tomcat starts with a long List of exceptions concerning ClassNotFoundExceptions in pdfbox…

Any idea that could help?

tia, mhmorys

[gnyce]

Just move the old pdfbox and fontbox jarfiles elsewhere (out of the alfresco directory structure), and drop the new ones in the same directory as where the old ones where.  Don't rename anything - let the new ones keep their rev'd version numbers…. worked here.

[dward]

FYI this was caused by older versions of the pdfbox and fontbox libraries. See https://issues.alfresco.com/jira/browse/ALF-8097

We have upgraded these on HEAD and you can try it in the latest nightly build.

[sans]

This is how I solved my problem.
replaced fontbox1.2.1.jar with fontbox1.5.0.jar
replaced pdfbox1.2.1.jar with pdfbox1.5.0.jar
open a command prompt.
cd to C:\Alfresco\tomcat\bin Note :- that's where my alfresco bundled tomcat is installed.
run version.
Check JRE_HOME in output. If it is showing JRE_HOME=C:\Alfresco\java
Add the environment variable JRE_HOME and point it to your jre installation.
My jre is installed at C:\Java\jre6.
Here is the version command output after chaning/adding environment variable JRE_HOME.

Using CATALINA_BASE:   "C:\Alfresco\tomcat"
Using CATALINA_HOME:   "C:\Alfresco\tomcat"
Using CATALINA_TMPDIR: "C:\Alfresco\tomcat\temp"
Using JRE_HOME:        "C:\Java\jre6" Note : Note the changed JRE_HOME.
Using CLASSPATH:       "C:\Alfresco\tomcat\bin\bootstrap.jar"
Server version: Apache Tomcat/6.0.26
Server built:   March 9 2010 1805
Server number:  6.0.26.0
OS Name:        Windows Server 2008
OS Version:     6.0
Architecture:   x86
JVM Version:    1.6.0_25-b06
JVM Vendor:     Sun Microsystems Inc.

That should solve the problem.