Process starts but connection fails on Win - any clues?

148 views
Skip to first unread message

PaulHH

unread,
Jan 12, 2010, 8:26:33 AM1/12/10
to JODConverter
Now I'm seeing a more useful reason for stuff not working. Does
anyone have any hints for me on why the connection might be failing
once the process is running on WinXP?

Thanks
Paul.

Exception:

12-Jan-2010 13:16:29
org.artofsolving.jodconverter.office.ProcessPoolOfficeManag
er <init>
INFO: ProcessManager implementation is WindowsProcessManager
12-Jan-2010 13:16:33
org.artofsolving.jodconverter.office.OfficeProcess prepareI
nstanceProfileDir
WARNING: profile dir 'C:\Alfresco\tomcat\temp
\.jodconverter_socket_host-127.0.0.
1_port-2022' already exists; deleting
12-Jan-2010 13:16:33
org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString
'socket,host=127.0.0.1,port=2022,tcpNoD
elay=1' and profileDir 'C:\Alfresco\tomcat\temp
\.jodconverter_socket_host-127.0.
0.1_port-2022'
12-Jan-2010 13:16:35
org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process; pid = 3880
13:17:07,656 INFO
[management.subsystems.ChildApplicationContextFactory] Stopp
ing 'OOoDirect' subsystem, ID: [OOoDirect, default]
13:17:07,671 INFO
[management.subsystems.ChildApplicationContextFactory] Stopp
ed 'OOoDirect' subsystem, ID: [OOoDirect, default]
13:17:07,687 INFO
[management.subsystems.ChildApplicationContextFactory] Stopp
ing 'thirdparty' subsystem, ID: [thirdparty, default]
13:17:07,687 INFO
[management.subsystems.ChildApplicationContextFactory] Stopp
ed 'thirdparty' subsystem, ID: [thirdparty, default]
13:17:07,703 INFO
[management.subsystems.ChildApplicationContextFactory] Stopp
ing 'sysAdmin' subsystem, ID: [sysAdmin, default]
13:17:07,703 INFO
[management.subsystems.ChildApplicationContextFactory] Stopp
ed 'sysAdmin' subsystem, ID: [sysAdmin, default]
13:17:07,734 ERROR [web.context.ContextLoader] Context initialization
failed
org.springframework.beans.factory.BeanCreationException: Error
creating bean wit
h name 'jodconverter.shared.instance' defined in file [C:\Alfresco
\tomcat\webapp
s\alfresco\WEB-INF\classes\alfresco\subsystems\OOoJodconverter\default
\jodconver
ter-context.xml]: Invocation of init method failed; nested exception
is org.arto
fsolving.jodconverter.office.OfficeException: failed to start and
connect
Caused by: org.artofsolving.jodconverter.office.OfficeException:
failed to start
and connect
at
org.artofsolving.jodconverter.office.ManagedOfficeProcess.startAndWai
t(ManagedOfficeProcess.java:61)
at
org.artofsolving.jodconverter.office.PooledOfficeManager.start(Pooled
OfficeManager.java:102)
at
org.artofsolving.jodconverter.office.ProcessPoolOfficeManager.start(P
rocessPoolOfficeManager.java:59)
at
org.alfresco.enterprise.repo.content.JodConverterSharedInstance.after
PropertiesSet(JodConverterSharedInstance.java:217)
at
org.springframework.beans.factory.support.AbstractAutowireCapableBean
Factory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:
1203)
at
org.springframework.beans.factory.support.AbstractAutowireCapableBean
Factory.initializeBean(AbstractAutowireCapableBeanFactory.java:1172)
at
org.springframework.beans.factory.support.AbstractAutowireCapableBean
Factory.createBean(AbstractAutowireCapableBeanFactory.java:427)
at
org.springframework.beans.factory.support.AbstractBeanFactory$1.getOb
ject(AbstractBeanFactory.java:249)
at
org.springframework.beans.factory.support.DefaultSingletonBeanRegistr
y.getSingleton(DefaultSingletonBeanRegistry.java:155)
at
org.springframework.beans.factory.support.AbstractBeanFactory.getBean
(AbstractBeanFactory.java:246)
at
org.springframework.beans.factory.support.AbstractBeanFactory.getBean
(AbstractBeanFactory.java:160)
at
org.springframework.beans.factory.support.DefaultListableBeanFactory.
preInstantiateSingletons(DefaultListableBeanFactory.java:291)
at
org.springframework.context.support.AbstractApplicationContext.refres
h(AbstractApplicationContext.java:352)
at
org.alfresco.repo.management.subsystems.ChildApplicationContextFactor
y$ApplicationContextState.start(ChildApplicationContextFactory.java:
637)
at
org.alfresco.repo.management.subsystems.AbstractPropertyBackedBean.st
art(AbstractPropertyBackedBean.java:454)
at
org.alfresco.repo.management.subsystems.AbstractPropertyBackedBean.on
ApplicationEvent(AbstractPropertyBackedBean.java:377)
at
org.springframework.context.event.SimpleApplicationEventMulticaster$1
.run(SimpleApplicationEventMulticaster.java:77)
at org.springframework.core.task.SyncTaskExecutor.execute
(SyncTaskExecut
or.java:49)
at
org.springframework.context.event.SimpleApplicationEventMulticaster.m
ulticastEvent(SimpleApplicationEventMulticaster.java:75)
at
org.springframework.context.support.AbstractApplicationContext.publis
hEvent(AbstractApplicationContext.java:246)
at
org.springframework.context.support.AbstractApplicationContext.finish
Refresh(AbstractApplicationContext.java:617)
at
org.springframework.context.support.AbstractApplicationContext.refres
h(AbstractApplicationContext.java:355)
at
org.springframework.web.context.ContextLoader.createWebApplicationCon
text(ContextLoader.java:246)
at
org.springframework.web.context.ContextLoader.initWebApplicationConte
xt(ContextLoader.java:189)
at
org.springframework.web.context.ContextLoaderListener.contextInitiali
zed(ContextLoaderListener.java:49)
at
org.alfresco.web.app.ContextLoaderListener.contextInitialized(Context
LoaderListener.java:69)
at org.apache.catalina.core.StandardContext.listenerStart
(StandardContex
t.java:3843)
at org.apache.catalina.core.StandardContext.start
(StandardContext.java:4
342)
at org.apache.catalina.core.ContainerBase.addChildInternal
(ContainerBase
.java:791)
at org.apache.catalina.core.ContainerBase.addChild
(ContainerBase.java:77
1)
at org.apache.catalina.core.StandardHost.addChild
(StandardHost.java:525)

at org.apache.catalina.startup.HostConfig.deployDescriptor
(HostConfig.ja
va:627)
at org.apache.catalina.startup.HostConfig.deployDescriptors
(HostConfig.j
ava:553)
at org.apache.catalina.startup.HostConfig.deployApps
(HostConfig.java:488
)
at org.apache.catalina.startup.HostConfig.start
(HostConfig.java:1149)
at org.apache.catalina.startup.HostConfig.lifecycleEvent
(HostConfig.java
:311)
at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent
(Lifecycl
eSupport.java:117)
at org.apache.catalina.core.ContainerBase.start
(ContainerBase.java:1053)

at org.apache.catalina.core.StandardHost.start
(StandardHost.java:719)
at org.apache.catalina.core.ContainerBase.start
(ContainerBase.java:1045)

at org.apache.catalina.core.StandardEngine.start
(StandardEngine.java:443
)
at org.apache.catalina.core.StandardService.start
(StandardService.java:5
16)
at org.apache.catalina.core.StandardServer.start
(StandardServer.java:710
)
at org.apache.catalina.startup.Catalina.start(Catalina.java:
578)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke
(NativeMethodAccessorImpl.
java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke
(DelegatingMethodAcces
sorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:
288)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:
413)
Caused by: java.util.concurrent.ExecutionException:
org.artofsolving.jodconverte
r.office.OfficeException: could not establish connection
at java.util.concurrent.FutureTask$Sync.innerGet
(FutureTask.java:222)
at java.util.concurrent.FutureTask.get(FutureTask.java:83)
at
org.artofsolving.jodconverter.office.ManagedOfficeProcess.startAndWai
t(ManagedOfficeProcess.java:59)
... 49 more
Caused by: org.artofsolving.jodconverter.office.OfficeException: could
not estab
lish connection
at
org.artofsolving.jodconverter.office.ManagedOfficeProcess.doStartProc
essAndConnect(ManagedOfficeProcess.java:123)
at
org.artofsolving.jodconverter.office.ManagedOfficeProcess.access$000(
ManagedOfficeProcess.java:31)
at org.artofsolving.jodconverter.office.ManagedOfficeProcess
$1.run(Manag
edOfficeProcess.java:55)
at java.util.concurrent.Executors$RunnableAdapter.call
(Executors.java:44
1)
at java.util.concurrent.FutureTask$Sync.innerRun
(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask
(ThreadPoolExec
utor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run
(ThreadPoolExecutor
.java:908)
at java.lang.Thread.run(Thread.java:619)
Caused by: org.artofsolving.jodconverter.office.RetryTimeoutException:
java.net.
ConnectException: connection failed:
'socket,host=127.0.0.1,port=2022,tcpNoDelay
=1'; java.net.ConnectException: Connection refused: connect
at org.artofsolving.jodconverter.office.Retryable.execute
(Retryable.java
:48)
at org.artofsolving.jodconverter.office.Retryable.execute
(Retryable.java
:31)
at
org.artofsolving.jodconverter.office.ManagedOfficeProcess.doStartProc
essAndConnect(ManagedOfficeProcess.java:113)
... 8 more
Caused by: java.net.ConnectException: connection failed:
'socket,host=127.0.0.1,
port=2022,tcpNoDelay=1'; java.net.ConnectException: Connection
refused: connect
at
org.artofsolving.jodconverter.office.OfficeConnection.connect(OfficeC
onnection.java:101)
at org.artofsolving.jodconverter.office.ManagedOfficeProcess
$6.attempt(M
anagedOfficeProcess.java:116)
at org.artofsolving.jodconverter.office.Retryable.execute
(Retryable.java
:41)
... 10 more

Mirko Nasato

unread,
Jan 12, 2010, 6:01:54 PM1/12/10
to JODConverter
There's a 30 seconds timeout when trying to connect to the process
after starting it, and that's what you're experiencing. It could be
that OOo is unusually slow in starting up, or it could be that it
starts up but for some strange reason it's not listening on the
requested port.

You should be able to see if OOo is listening using netstat. If it is,
then you could try raising the timeout. However it's currently hard-
coded (in ManagedOfficeProcessSettings.DEFAULT_RETRY_TIMEOUT) so you'd
need to recompile the library. (It obviously should be made
configurable.)

Kind regards

Mirko

Reply all
Reply to author
Forward
0 new messages