I sent you a link to the snapshot just a moment ago. Below is an excerpt
from sipxconfig.log, at the conclusion of the import run. There is an
exception resulting from the LDAP connection closing. My first thought when
troubleshooting this was that maybe our LDAP was closing the connection
before the query completed, but I ruled that out, both because the CSV
preview contains complete data, and because I ran a network capture during
an import, and observed all the data coming over.
Now that I'm looking at this again, I'm wondering if the LDAP connection is
completing far in advance of the import, enough so that the LDAP is timing
out, and when the exception is raised, the import process dies. Not sure if
that makes sense or not..
Thanks again!
------------
"2011-06-09T19:22:01.713000Z":2433:JAVA:ERR:sip-1.cns.utexas.edu:background:00000000:BackgroundTaskQueue:"Exception
in background task."
org.sipfoundry.sipxconfig.common.UserException: LDAP search failed :
connection closed
at
org.sipfoundry.sipxconfig.bulk.ldap.LdapImportManagerImpl.runSearch(LdapImportManagerImpl.java:168)
at
org.sipfoundry.sipxconfig.bulk.ldap.LdapImportManagerImpl.insert(LdapImportManagerImpl.java:54)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
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.sipfoundry.sipxconfig.common.BackgroundTaskInterceptor$InvocationTask.run(BackgroundTaskInterceptor.java:55)
at
org.sipfoundry.sipxconfig.common.BackgroundTaskQueue$Worker.run(BackgroundTaskQueue.java:102)
"2011-06-09T19:22:09.779000Z":2434:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:SipxReplicationContextImpl:"Start replication: Data
replication: alias"
"2011-06-09T19:22:09.821000Z":2435:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker
thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet():
null:"
"2011-06-09T19:22:09.821000Z":2436:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker
thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet():
return set :[]:"
"2011-06-09T19:22:13.787000Z":2437:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace
with [sip-1.cns.utexas.edu, alias, [{contact=sip:*88@sip-...] on
https://sip-1.cns.utexas.edu:8092/RPC2";
"2011-06-09T19:22:13.999000Z":2439:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:auditlog:"Replicated alias to sip-1.cns.utexas.edu"
"2011-06-09T19:22:14.001000Z":2440:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker
thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet():
null:"
"2011-06-09T19:22:14.001000Z":2441:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker
thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet():
return set :[]:"
"2011-06-09T19:22:14.546000Z":2442:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace
with [sip-1.cns.utexas.edu, alias, [{contact=sip:*88@sip-...] on
https://sip-2.cns.utexas.edu:8092/RPC2";
"2011-06-09T19:22:14.655000Z":2444:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:auditlog:"Replicated alias to sip-2.cns.utexas.edu"
"2011-06-09T19:22:14.658000Z":2445:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:SipxReplicationContextImpl:"Start replication: File
replication: validusers.xml"
"2011-06-09T19:22:15.284000Z":2446:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker
thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet():
null:"
"2011-06-09T19:22:15.284000Z":2447:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker
thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet():
return set :[]:"
"2011-06-09T19:22:15.418000Z":2448:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC File.replace with [
sip-1.cns.utexas.edu, /etc/sipxpbx/validuser..., 420,
PD94bWwgdmVyc2lvbj0iMS...] on https://sip-1.cns.utexas.edu:8092/RPC2";
"2011-06-09T19:22:15.578000Z":2450:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:auditlog:"Replicated validusers.xml to
sip-1.cns.utexas.edu"
"2011-06-09T19:22:16.169000Z":2451:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker
thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet():
null:"
"2011-06-09T19:22:16.170000Z":2452:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker
thread:00000000:SipxAccCodeService:"SipxAccCodeService::getAliasesAsSet():
return set :[]:"
"2011-06-09T19:22:16.192000Z":2453:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC File.replace with [
sip-1.cns.utexas.edu, /etc/sipxpbx/validuser..., 420,
PD94bWwgdmVyc2lvbj0iMS...] on https://sip-2.cns.utexas.edu:8092/RPC2";
"2011-06-09T19:22:16.312000Z":2455:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:auditlog:"Replicated validusers.xml to
sip-2.cns.utexas.edu"
"2011-06-09T19:22:16.313000Z":2456:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:SipxReplicationContextImpl:"Start replication: Data
replication: credential"
"2011-06-09T19:22:17.512000Z":2457:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace
with [sip-1.cns.utexas.edu, credential, [{realm=cns.utexas.edu...] on
https://sip-1.cns.utexas.edu:8092/RPC2";
"2011-06-09T19:22:17.523000Z":2458:JAVA:INFO:sip-1.cns.utexas.edu:activate
config worker:00000000:SipxReplicationContextImpl:"Start replication: File
replication: resource-lists.xml"
"2011-06-09T19:22:17.765000Z":2460:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:auditlog:"Replicated credential to
sip-1.cns.utexas.edu"
"2011-06-09T19:22:18.114000Z":2461:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:XmlRpcClientInterceptor:"XML/RPC ImdbTable.replace
with [sip-1.cns.utexas.edu, credential, [{realm=cns.utexas.edu...] on
https://sip-2.cns.utexas.edu:8092/RPC2";
"2011-06-09T19:22:18.327000Z":2463:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:auditlog:"Replicated credential to
sip-2.cns.utexas.edu"
"2011-06-09T19:22:18.328000Z":2464:JAVA:INFO:sip-1.cns.utexas.edu:Replication
worker thread:00000000:SipxReplicationContextImpl:"Start replication: Data
replication: permission"
[Adrian Schmitz - Network Engineer - College of Natural Sciences -
512.537.5411]
On Thu, Jun 9, 2011 at 3:05 PM, George Niculae <[email protected]> wrote:
> On Thu, Jun 9, 2011 at 10:40 PM, Adrian Schmitz
> <[email protected]> wrote:
> > Thanks for your response. I deleted the ldap_imports group as suggested,
> and
> > re-ran the import a couple times. Each time I delete/re-run the import, I
> > get a different number of users. The last user to successfully import is
> > different every time (i.e. the import process does not appear to die on a
> > specific user).
> >
> > The first time I ran, I got 559 users. Deleted, ran again, and got 451
> > users. Deleted, ran again, and got 516 users. Deleted, rebooted, ran
> again
> > and got 485. Again, it ends on a different user each time. Very unusual.
> >
>
> Do you see any other activity in sipXconfig.log after last user
> import? I know that import tends to go slower and slower when #of user
> grow and I'm thinking that maybe it didn't finish to import but is
> struggling to import next user
> Can you share a snapshot?
>
> George
> _______________________________________________
> sipx-users mailing list
> [email protected]
> List Archive: http://list.sipfoundry.org/archive/sipx-users/
>
_______________________________________________
sipx-users mailing list
[email protected]
List Archive: http://list.sipfoundry.org/archive/sipx-users/
