[asterisk-users] Problem with user regsitration and ldap on SVN version

sylvain.desbureaux at orange-ftgroup.com sylvain.desbureaux at orange-ftgroup.com
Fri Mar 21 11:08:29 CDT 2008 

Hi guys,
I'm trying to use Asterisk with LDAP integration.
I created some schemas and it seems to work fine for sip.conf replacement.

When I try to register a softphone to test the service, it seems ok from the softphone point of view (user registred) but when I do a 
"sip show peers", no one is registered (nor sip show subrscriptions, users...)
I put my Asterisk on full debug and I see this trace when trying to register:

[Mar 21 16:53:54] DEBUG[12002] acl.c: Found IP address for this socket
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: Allocating new SIP dialog for OWY3OTAwNzFhNDZhYWU5NTU0YTU1MGY4MzYwOTdlZjQ. - REGISTER (No RTP)
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: substituted: string: 'dc=example, dc=com' => 'dc=example, dc=com'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: basedn: 'dc=example, dc=com' => 'dc=example, dc=com'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: Everything seems fine.
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: name='name' value='Pierre'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: name='host' value='dynamic'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: canreinvite LDAP value: no
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: regserver LDAP value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: objectClass LDAP value: AsteriskObject
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: objectClass LDAP value: AsteriskExtension
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: objectClass LDAP value: AsteriskSIPUser
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: objectClass LDAP value: top
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: context LDAP value: from-sip
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: context LDAP value: internal
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: type LDAP value: friend
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: callerid LDAP value: Pierre Bachelet <2001>
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: fullcontact LDAP value: Pierre Bachelet
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: AstAccountSecret LDAP value: 1234
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: host LDAP value: dynamic
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: name LDAP value: Pierre
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: qualify LDAP value: no
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: language LDAP value: fr
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: ipaddr LDAP value: 0.0.0.0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: port LDAP value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: regseconds LDAP value: 1206118346
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: defaultuser LDAP value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: canreinvite value: no
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: regserver value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: objectClass value: AsteriskObject
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: objectClass value: AsteriskExtension
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: objectClass value: AsteriskSIPUser
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: objectClass value: top
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: context value: from-sip
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: context value: internal
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: type value: friend
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: callerid value: Pierre Bachelet <2001>
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: fullcontact value: Pierre Bachelet
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: AstAccountSecret value: 1234
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: host value: dynamic
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: name value: Pierre
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: qualify value: no
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: language value: fr
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: ipaddr value: 0.0.0.0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: port value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: regseconds value: 1206118346
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: defaultuser value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(517) Added to vars - non static
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: -REALTIME- peer built. Name: Pierre. Peer objects: 1
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: -REALTIME- loading peer from database to memory. Name: Pierre. Peer objects: 1
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: Everything seems fine.
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: substituted: string: 'dc=example, dc=com' => 'dc=example, dc=com'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: basedn: 'dc=example, dc=com' => 'dc=example, dc=com'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: name='name' value='Pierre'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(1281) Modifying name=Pierre hits: 1
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(1283) AstAccountIPAddr=10.193.35.180
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(1283) ASTAccountPort=8134
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(1283) ASTAccountExpirationTimestamp=1206118434
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(1283) AstAccountDefaultUser=0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(1293) Modification of AstAccountIPAddr on dn:cn=Pierre,ou=users,ou=asterisk,dc=example,dc=com succeeded[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: Trying to put 'SIP/2.0 20' onto UDP socket...
[Mar 21 16:53:54] DEBUG[12002] devicestate.c: Notification of state change to be queued on device/channel SIP/Pierre
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: Destroying SIP peer Pierre
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: -REALTIME- peer Destroyed. Name: Pierre. Realtime Peer objects: 0
[Mar 21 16:53:54] DEBUG[12002] devicestate.c: No provider found, checking channel drivers for SIP - Pierre
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: Checking device state for peer Pierre
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: substituted: string: 'dc=example, dc=com' => 'dc=example, dc=com'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: basedn: 'dc=example, dc=com' => 'dc=example, dc=com'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: Everything seems fine.
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: name='name' value='Pierre'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: name='host' value='dynamic'
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: canreinvite LDAP value: no
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: regserver LDAP value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: objectClass LDAP value: AsteriskObject
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: objectClass LDAP value: AsteriskExtension
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: objectClass LDAP value: AsteriskSIPUser
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: objectClass LDAP value: top
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: context LDAP value: from-sip
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: context LDAP value: internal
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: type LDAP value: friend
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: callerid LDAP value: Pierre Bachelet <2001>
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: fullcontact LDAP value: Pierre Bachelet
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: AstAccountSecret LDAP value: 1234
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: host LDAP value: dynamic
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: name LDAP value: Pierre
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: qualify LDAP value: no
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: language LDAP value: fr
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: ipaddr LDAP value: 10.193.35.180
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: port LDAP value: 8134
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: regseconds LDAP value: 1206118434
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(289) attribute_name: defaultuser LDAP value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: canreinvite value: no
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: regserver value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: objectClass value: AsteriskObject
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: objectClass value: AsteriskExtension
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: objectClass value: AsteriskSIPUser
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: objectClass value: top
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: context value: from-sip
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: context value: internal
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: type value: friend
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: callerid value: Pierre Bachelet <2001>
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: fullcontact value: Pierre Bachelet
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: AstAccountSecret value: 1234
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: host value: dynamic
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: name value: Pierre
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: qualify value: no
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: language value: fr
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: ipaddr value: 10.193.35.180
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: port value: 8134
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: regseconds value: 1206118434
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(482) attribute_name: defaultuser value: 0
[Mar 21 16:53:54] DEBUG[12002] res_config_ldap.c: LINE(517) Added to vars - non static
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: -REALTIME- peer built. Name: Pierre. Peer objects: 1
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: -REALTIME- loading peer from database to memory. Name: Pierre. Peer objects: 1
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: Destroying SIP peer Pierre
[Mar 21 16:53:54] DEBUG[12002] chan_sip.c: -REALTIME- peer Destroyed. Name: Pierre. Realtime Peer objects: 0
[Mar 21 16:53:54] DEBUG[12002] devicestate.c: Changing state for SIP/Pierre - state 1 (Not in use)
[Mar 21 16:53:54] DEBUG[12002] app_queue.c: Device 'SIP/Pierre' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Mar 21 16:54:26] DEBUG[12002] chan_sip.c: Auto destroying SIP dialog 'OWY3OTAwNzFhNDZhYWU5NTU0YTU1MGY4MzYwOTdlZjQ.'
[Mar 21 16:54:26] DEBUG[12002] chan_sip.c: Destroying SIP dialog OWY3OTAwNzFhNDZhYWU5NTU0YTU1MGY4MzYwOTdlZjQ.
[Mar 21 16:54:26] DEBUG[12002] sched.c: Attempted to delete nonexistent schedule entry -1!

As you can see, the peer is created the destroyed just after...
Any reasons why?

Thanks in advance,


Sylvain Desbureaux
Recherche et Développement, Service aux entreprises
Ingénieur concepteur développeur de services réseaux pour les entreprises
sylvain.desbureaux at orange-ftgroup.com

More information about the asterisk-users mailing list