All that sipxconfig.log shows is that it's timing out when trying to connect to the XML-RPC server
Snip of when it fails (debug logging turned on): "2011-02-24T21:43:02.381000Z":2371:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace with [expovoip.bc.local, userstatic, []] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:02.421000Z":2373:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:auditlog:"Replicated userstatic to expovoip.bc.local" "2011-02-24T21:43:02.428000Z":2374:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Start replication: File replication: appearance-groups.xml" "2011-02-24T21:43:02.432000Z":2375:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC File.replace with [expovoip.bc.local, /etc/sipxpbx/appearanc..., 420, PD94bWwgdmVyc2lvbj0iMS...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:02.440000Z":2377:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:auditlog:"Replicated appearance-groups.xml to expovoip.bc.local" "2011-02-24T21:43:05.310000Z":2378:JAVA:INFO:expovoip.bc.local:activate config worker:00000000:SipxReplicationContextImpl:"Start replication: File replication: resource-lists.xml" "2011-02-24T21:43:05.533000Z":2379:JAVA:INFO:expovoip.bc.local:activate config worker:00000000:XmlRpcClientInterceptor:"XML/RPC File.replace with [expovoip.bc.local, /etc/sipxpbx/resource-..., 420, PD94bWwgdmVyc2lvbj0iMS...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:05.543000Z":2381:JAVA:INFO:expovoip.bc.local:activate config worker:00000000:auditlog:"Replicated resource-lists.xml to expovoip.bc.local" "2011-02-24T21:43:23.431000Z":2382:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:23.431000Z":2383:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:23.442000Z":2384:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:23.442000Z":2385:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:23.452000Z":2386:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:23.452000Z":2387:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:23.462000Z":2388:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:23.462000Z":2389:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:23.473000Z":2390:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:23.473000Z":2391:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:24.132000Z":2392:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:24.132000Z":2393:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:24.142000Z":2394:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:24.142000Z":2395:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:24.151000Z":2396:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:24.151000Z":2397:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:24.161000Z":2398:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:24.161000Z":2399:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:24.171000Z":2400:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:24.171000Z":2401:JAVA:INFO:expovoip.bc.local:P1-14:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:37.493000Z":2402:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Start replication: Data replication: permission" "2011-02-24T21:43:37.686000Z":2403:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace with [expovoip.bc.local, permission, [{permission=Internati...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:37.792000Z":2405:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:auditlog:"Replicated permission to expovoip.bc.local" "2011-02-24T21:43:37.793000Z":2406:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Start replication: Data replication: userlocation" "2011-02-24T21:43:37.933000Z":2407:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace with [expovoip.bc.local, userlocation, []] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:37.981000Z":2409:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:auditlog:"Replicated userlocation to expovoip.bc.local" "2011-02-24T21:43:37.981000Z":2410:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Start replication: Data replication: caller-alias" "2011-02-24T21:43:38.182000Z":2411:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace with [expovoip.bc.local, caller-alias, [{identity=404@expovoi...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:38.234000Z":2413:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:auditlog:"Replicated caller-alias to expovoip.bc.local" "2011-02-24T21:43:38.241000Z":2414:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Start replication: File replication: contact-information.xml" "2011-02-24T21:43:38.425000Z":2415:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC File.replace with [expovoip.bc.local, /etc/sipxpbx/contact-i..., 420, PD94bWwgdmVyc2lvbj0iMS...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:38.442000Z":2417:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:auditlog:"Replicated contact-information.xml to expovoip.bc.local" "2011-02-24T21:43:38.442000Z":2418:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Start replication: Data replication: alias" "2011-02-24T21:43:38.451000Z":2419:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:38.451000Z":2420:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:38.982000Z":2421:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace with [expovoip.bc.local, alias, [{contact=sip:*88@expo...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:39.087000Z":2423:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:auditlog:"Replicated alias to expovoip.bc.local" "2011-02-24T21:43:39.088000Z":2424:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Start replication: File replication: validusers.xml" "2011-02-24T21:43:39.167000Z":2425:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): null:" "2011-02-24T21:43:39.167000Z":2426:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet(): return set :[]:" "2011-02-24T21:43:39.171000Z":2427:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC File.replace with [expovoip.bc.local, /etc/sipxpbx/validuser..., 420, PD94bWwgdmVyc2lvbj0iMS...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:39.195000Z":2429:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:auditlog:"Replicated validusers.xml to expovoip.bc.local" "2011-02-24T21:43:39.195000Z":2430:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Start replication: Data replication: userforward" "2011-02-24T21:43:39.728000Z":2431:JAVA:INFO:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace with [expovoip.bc.local, userforward, [{identity=superadmin@...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:43:43.505000Z":2432:JAVA:INFO:expovoip.bc.local:activate config worker:00000000:SipxReplicationContextImpl:"Start replication: File replication: resource-lists.xml" "2011-02-24T21:43:43.713000Z":2433:JAVA:INFO:expovoip.bc.local:activate config worker:00000000:XmlRpcClientInterceptor:"XML/RPC File.replace with [expovoip.bc.local, /etc/sipxpbx/resource-..., 420, PD94bWwgdmVyc2lvbj0iMS...] on https://expovoip.bc.local:8092/RPC2"; "2011-02-24T21:44:04.735000Z":2434:JAVA:ERR:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"Timeout in XML/RPC call" "2011-02-24T21:44:04.735000Z":2435:JAVA:ERR:expovoip.bc.local:Replication worker thread:00000000:XmlRpcClientInterceptor:"Runtime error in XML/RPC call" org.sipfoundry.sipxconfig.xmlrpc.XmlRpcRemoteException at org.sipfoundry.sipxconfig.xmlrpc.XmlRpcClientInterceptor.executeWithTimeout(XmlRpcClientInterceptor.java:141) at org.sipfoundry.sipxconfig.xmlrpc.XmlRpcClientInterceptor.invoke(XmlRpcClientInterceptor.java:75) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202) at $Proxy74.replace(Unknown Source) at org.sipfoundry.sipxconfig.admin.commserver.imdb.ReplicationManagerImpl.replicateData(ReplicationManagerImpl.java:82) at org.sipfoundry.sipxconfig.admin.commserver.SipxReplicationContextImpl$1.replicate(SipxReplicationContextImpl.java:61) at org.sipfoundry.sipxconfig.admin.commserver.SipxReplicationContextImpl.doWithJob(SipxReplicationContextImpl.java:129) at org.sipfoundry.sipxconfig.admin.commserver.SipxReplicationContextImpl.generate(SipxReplicationContextImpl.java:64) at sun.reflect.GeneratedMethodAccessor683.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:616) 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.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202) at $Proxy9.generate(Unknown Source) at org.sipfoundry.sipxconfig.admin.commserver.LazySipxReplicationContextImpl$DataSetTask.replicate(LazySipxReplicationContextImpl.java:176) at org.sipfoundry.sipxconfig.admin.commserver.LazySipxReplicationContextImpl$Worker.work(LazySipxReplicationContextImpl.java:153) at org.sipfoundry.sipxconfig.common.LazyDaemon.run(LazyDaemon.java:44) Caused by: java.util.concurrent.TimeoutException at java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:258) at java.util.concurrent.FutureTask.get(FutureTask.java:119) at org.sipfoundry.sipxconfig.xmlrpc.XmlRpcClientInterceptor.executeWithTimeout(XmlRpcClientInterceptor.java:136) ... 21 more "2011-02-24T21:44:04.736000Z":2436:JAVA:ERR:expovoip.bc.local:Replication worker thread:00000000:ReplicationManagerImpl:"Data replication failed: userforward" org.sipfoundry.sipxconfig.xmlrpc.XmlRpcRemoteException at org.sipfoundry.sipxconfig.xmlrpc.XmlRpcClientInterceptor.executeWithTimeout(XmlRpcClientInterceptor.java:141) at org.sipfoundry.sipxconfig.xmlrpc.XmlRpcClientInterceptor.invoke(XmlRpcClientInterceptor.java:75) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202) at $Proxy74.replace(Unknown Source) at org.sipfoundry.sipxconfig.admin.commserver.imdb.ReplicationManagerImpl.replicateData(ReplicationManagerImpl.java:82) at org.sipfoundry.sipxconfig.admin.commserver.SipxReplicationContextImpl$1.replicate(SipxReplicationContextImpl.java:61) at org.sipfoundry.sipxconfig.admin.commserver.SipxReplicationContextImpl.doWithJob(SipxReplicationContextImpl.java:129) at org.sipfoundry.sipxconfig.admin.commserver.SipxReplicationContextImpl.generate(SipxReplicationContextImpl.java:64) at sun.reflect.GeneratedMethodAccessor683.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:616) 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.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202) at $Proxy9.generate(Unknown Source) at org.sipfoundry.sipxconfig.admin.commserver.LazySipxReplicationContextImpl$DataSetTask.replicate(LazySipxReplicationContextImpl.java:176) at org.sipfoundry.sipxconfig.admin.commserver.LazySipxReplicationContextImpl$Worker.work(LazySipxReplicationContextImpl.java:153) at org.sipfoundry.sipxconfig.common.LazyDaemon.run(LazyDaemon.java:44) Caused by: java.util.concurrent.TimeoutException at java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:258) at java.util.concurrent.FutureTask.get(FutureTask.java:119) at org.sipfoundry.sipxconfig.xmlrpc.XmlRpcClientInterceptor.executeWithTimeout(XmlRpcClientInterceptor.java:136) ... 21 more "2011-02-24T21:44:04.736000Z":2437:JAVA:WARNING:expovoip.bc.local:Replication worker thread:00000000:SipxReplicationContextImpl:"Replication failed: Data replication: userforward" -----Original Message----- From: George Niculae [mailto:[email protected]] Sent: Thursday, February 24, 2011 4:31 PM To: sipXecs developer discussions Cc: Josh M. Patten Subject: Re: [sipx-dev] 4.4 XML-RPC On Fri, Feb 25, 2011 at 12:23 AM, Josh M. Patten <[email protected]> wrote: > Seems that on a new install I'm doing any time I try to go over 61 > users XML-RPC stops responding and no further configuration is > possible. I need to know what log file to look in to troubleshoot the > XML-RPC service on sipXecs. > You can start with sipXconfig.log, see if any error there, you could also take a snapshot and post back so I'll have a look. George _______________________________________________ sipx-dev mailing list [email protected] List Archive: http://list.sipfoundry.org/archive/sipx-dev/
