Error while registering - unauthorized

Asterisk Asterisk Support
1

Running asterisk 1.8.7.1 on gentoo

echo -n ‘dex:asterisk:a’ | md5sum

b5520b27dab0a651cfcdf384ac9a8e76 -

mysql> update ast_sipfriends SET md5secret = MD5(‘dex:asterisk:a’) WHERE name = ‘dex’ LIMIT 1;
Query OK, 0 rows affected (0.00 sec)
Rows matched: 1 Changed: 0 Warnings: 0

mysql> select * from ast_sipfriends WHERE name = ‘dex’;
±—±-------±-----±---------------------------------±--------±--------±-------±-----±----±-----------±------------±------------±----------±----------±-------±-------+
| id | type | name | md5secret | context | host | ipaddr | port | nat | regseconds | defaultuser | fullcontact | regserver | useragent | lastms | status |
±—±-------±-----±---------------------------------±--------±--------±-------±-----±----±-----------±------------±------------±----------±----------±-------±-------+
| 72 | friend | dex | b5520b27dab0a651cfcdf384ac9a8e76 | users | dynamic | | | yes | 0 | | | | | 0 | 0 |
±—±-------±-----±---------------------------------±--------±--------±-------±-----±----±-----------±------------±------------±----------±----------±-------±-------+

cat /etc/asterisk/extconfig.conf

[settings]
sipusers => odbc,asterisk,ast_sipfriends
sippeers => odbc,asterisk,ast_sipfriends

cat /etc/asterisk/res_odbc.conf

[asterisk]
enabled => yes
dsn => myodbc-5.1-test
username => asterisk
password => 4st3r1sk
pre-connect => yes
limit => "0"
pooling => no

cat /etc/unixODBC/odbc.ini

[ODBC Data Sources]
myodbc-5.1-test=MySQL ODBC myodbc-5.1.6 Driver Testing DSN

[myodbc-5.1-test]
Description=MySQL ODBC myodbc-5.1.6 Driver Testing DSN
Driver=myodbc-5.1
Socket=/var/run/mysqld/mysqld.sock
Server=localhost
User=asterisk
Database=asterisk
Password=4st3r1sk
Port=3306
Option=3

cat /etc/unixODBC/odbcinst.ini

[myodbc-5.1]
Description=MySQL ODBC myodbc-5.1.6 Driver
Driver=/usr/lib/libmyodbc5.so
UsageCount=1

sip.conf has

udpbindaddr=192.168.1.2
nat = yes
externaddr = 86.106.164.128
localnet = 192.168.1.0/24

rtp.conf has

rtpstart=8000
rtpend=9000

sheevaplug-gentoo asterisk # netstat -plantu | grep -i asterisk
tcp 0 0 192.168.1.2:2000 0.0.0.0:* LISTEN 2608/asterisk
udp 0 0 192.168.1.2:5060 0.0.0.0:* 2608/asterisk
udp 0 0 192.168.1.2:4569 0.0.0.0:* 2608/asterisk
udp 0 0 0.0.0.0:5000 0.0.0.0:* 2608/asterisk
udp 0 0 192.168.1.2:4520 0.0.0.0:* 2608/asterisk

I do know that I don’t need all of them, but I’m not sure what skinny, unistim, iax or dundi do or if I should disable them :smile:

The following ports are forwarded to 192.168.1.2 ( asterisk box ) from 86.106.164.128 ( a wndr3700 running openwrt if that matters )

2000(tcp),4520(udp),4569(udp),5000(udp),5060(udp),8000-9000(udp)

Yet when I try to auth, I get this ( from inside the 192.168.1.1 network ):

[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:7519 sip_alloc: Allocating new SIP dialog for d0dab248-97fd-e011-8ec3-002710841b54@VPCF13M1E - REGISTER (No RTP)
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 19:53:28] DEBUG[2795]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 19:53:28] DEBUG[2795]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2051e0) called (obj->txf = (nil))
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319561608/0/1319561608)!
[Oct 25 19:53:28] DEBUG[2795]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 19:53:28] DEBUG[2795]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:5060

I certainly doubt that I typed the password ( ‘a’ ) wrong ! I tried it from my cellphone ( running csipsimple ) and from my computer ( running ekiga ) from some external network ( with asterisk still behind nat ) and from the internal network.

Can anyone point me how can I further debug this problem ?

Thanks in advance.

2

[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c
:7839 find_call: = Looking for Call ID: IHOgkEztMgzJvM2qobKSqB2z-VRuwnMi (Checking From) --From tag JE.e3KMrY2jJPtcd…ya86GK4EXmarFO --To-tag
[Oct 25 21:21:31] DEBUG[18096]: acl.c:725 ast_ouraddrfor: For destination ‘192.168.1.1’, our source address is ‘192.168.1.2’.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:3479 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.1.2:5060
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c
:7519 sip_alloc: Allocating new SIP dialog for IHOgkEztMgzJvM2qobKSqB2z-VRuwnMi - REGISTER (No RTP)
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:24477 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘192.168.1.147:41377’ into…
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘192.168.1.147’ and port ‘41377’.
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 21:21:31] DEBUG[18096]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2116a0) called (obj->txf = (nil))
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:26566 build_peer: -REALTIME- peer built. Name: dex. Peer objects: 1
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘178.138.33.220’ into…
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘178.138.33.220’ and port ‘’.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 21:21:31] DEBUG[18096]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 21:21:31] DEBUG[18096]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27136 build_peer: Bah, we’re expired (3042/1319563849/1319566891)!
[Oct 25 21:21:31] DEBUG[18096]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4811 realtime_peer: -REALTIME- loading peer from database to memory. Name: dex. Peer objects: 1
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:41377
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4561 sip_destroy_peer: Destroying SIP peer dex
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4590 sip_destroy_peer: -REALTIME- peer Destroyed. Name: dex. Realtime Peer objects: 0
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c
:7839 find_call: = Looking for Call ID: IHOgkEztMgzJvM2qobKSqB2z-VRuwnMi (Checking From) --From tag JE.e3KMrY2jJPtcd…ya86GK4EXmarFO --To-tag
[Oct 25 21:21:31] DEBUG[18096]: acl.c:725 ast_ouraddrfor: For destination ‘192.168.1.1’, our source address is ‘192.168.1.2’.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:3479 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.1.2:5060
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c
:7519 sip_alloc: Allocating new SIP dialog for IHOgkEztMgzJvM2qobKSqB2z-VRuwnMi - REGISTER (No RTP)
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:24477 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘192.168.1.147:41377’ into…
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘192.168.1.147’ and port ‘41377’.
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 21:21:31] DEBUG[18096]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2116a0) called (obj->txf = (nil))
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:26566 build_peer: -REALTIME- peer built. Name: dex. Peer objects: 1
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘178.138.33.220’ into…
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘178.138.33.220’ and port ‘’.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 21:21:31] DEBUG[18096]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 21:21:31] DEBUG[18096]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27136 build_peer: Bah, we’re expired (3042/1319563849/1319566891)!
[Oct 25 21:21:31] DEBUG[18096]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4811 realtime_peer: -REALTIME- loading peer from database to memory. Name: dex. Peer objects: 1
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:41377
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4561 sip_destroy_peer: Destroying SIP peer dex
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4590 sip_destroy_peer: -REALTIME- peer Destroyed. Name: dex. Realtime Peer objects: 0
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c
:7839 find_call: = Looking for Call ID: IHOgkEztMgzJvM2qobKSqB2z-VRuwnMi (Checking From) --From tag JE.e3KMrY2jJPtcd…ya86GK4EXmarFO --To-tag
[Oct 25 21:21:31] DEBUG[18096]: acl.c:725 ast_ouraddrfor: For destination ‘192.168.1.1’, our source address is ‘192.168.1.2’.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:3479 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.1.2:5060
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c
:7519 sip_alloc: Allocating new SIP dialog for IHOgkEztMgzJvM2qobKSqB2z-VRuwnMi - REGISTER (No RTP)
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:24477 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘192.168.1.147:41377’ into…
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘192.168.1.147’ and port ‘41377’.
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 21:21:31] DEBUG[18096]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2116a0) called (obj->txf = (nil))
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:26566 build_peer: -REALTIME- peer built. Name: dex. Peer objects: 1
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘178.138.33.220’ into…
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘178.138.33.220’ and port ‘’.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 21:21:31] DEBUG[18096]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 21:21:31] DEBUG[18096]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27136 build_peer: Bah, we’re expired (3042/1319563849/1319566891)!
[Oct 25 21:21:31] DEBUG[18096]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4811 realtime_peer: -REALTIME- loading peer from database to memory. Name: dex. Peer objects: 1
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:41377
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4561 sip_destroy_peer: Destroying SIP peer dex
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4590 sip_destroy_peer: -REALTIME- peer Destroyed. Name: dex. Realtime Peer objects: 0
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c
:7839 find_call: = Looking for Call ID: IHOgkEztMgzJvM2qobKSqB2z-VRuwnMi (Checking From) --From tag JE.e3KMrY2jJPtcd…ya86GK4EXmarFO --To-tag
[Oct 25 21:21:31] DEBUG[18096]: acl.c:725 ast_ouraddrfor: For destination ‘192.168.1.1’, our source address is ‘192.168.1.2’.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:3479 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.1.2:5060
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c
:7519 sip_alloc: Allocating new SIP dialog for IHOgkEztMgzJvM2qobKSqB2z-VRuwnMi - REGISTER (No RTP)
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:24477 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘192.168.1.147:41377’ into…
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘192.168.1.147’ and port ‘41377’.
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 21:21:31] DEBUG[18096]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 21:21:31] DEBUG[18096]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x2116a0) called (obj->txf = (nil))
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:26566 build_peer: -REALTIME- peer built. Name: dex. Peer objects: 1
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘178.138.33.220’ into…
[Oct 25 21:21:31] DEBUG[18096]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘178.138.33.220’ and port ‘’.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 21:21:31] DEBUG[18096]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 21:21:31] DEBUG[18096]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:27136 build_peer: Bah, we’re expired (3042/1319563849/1319566891)!
[Oct 25 21:21:31] DEBUG[18096]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4811 realtime_peer: -REALTIME- loading peer from database to memory. Name: dex. Peer objects: 1
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:41377
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4561 sip_destroy_peer: Destroying SIP peer dex
[Oct 25 21:21:31] DEBUG[18096]: chan_sip.c:4590 sip_destroy_peer: -REALTIME- peer Destroyed. Name: dex. Realtime Peer objects: 0

3

<— SIP read from UDP:192.168.1.1:47717 —>
REGISTER sip:sip.sms1.ro SIP/2.0
Via: SIP/2.0/UDP 192.168.1.147:47717;rport;branch=z9hG4bKPjvrxuYmpgeMnygXw4ilX0x-fqJvITnHxm
Route: sip:sip.sms1.ro;transport=udp;lr
Max-Forwards: 70
From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
To: sip:dex@sip.sms1.ro
Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
CSeq: 12092 REGISTER
User-Agent: CSipSimple r1041 / vision-10
Contact: sip:dex@192.168.1.147:47717;ob
Expires: 900
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length: 0

<------------->
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 0 [ 32]: REGISTER sip:sip.sms1.ro SIP/2.0
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 1 [ 91]: Via: SIP/2.0/UDP 192.168.1.147:47717;rport;branch=z9hG4bKPjvrxuYmpgeMnygXw4ilX0x-fqJvITnHxm
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 2 [ 41]: Route: sip:sip.sms1.ro;transport=udp;lr
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 3 [ 16]: Max-Forwards: 70
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 4 [ 64]: From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 5 [ 25]: To: sip:dex@sip.sms1.ro
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 6 [ 41]: Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 7 [ 20]: CSeq: 12092 REGISTER
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 8 [ 40]: User-Agent: CSipSimple r1041 / vision-10
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 9 [ 41]: Contact: sip:dex@192.168.1.147:47717;ob
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 10 [ 12]: Expires: 900
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 11 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 12 [ 17]: Content-Length: 0
— (13 headers 0 lines) —
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c
:7839 find_call: = Looking for Call ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV (Checking From) --From tag 5lsni6izGEvrdNsa0NFFwlC2.O9zGabu --To-tag
[Oct 25 22:34:05] DEBUG[19522]: acl.c:725 ast_ouraddrfor: For destination ‘192.168.1.1’, our source address is ‘192.168.1.2’.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:3479 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.1.2:5060
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c
:7519 sip_alloc: Allocating new SIP dialog for CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV - REGISTER (No RTP)
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:24477 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:24306 handle_request_register: Initializing initreq for method REGISTER - callid CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
[Oct 25 22:34:05] DEBUG[19522]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘192.168.1.147:47717’ into…
[Oct 25 22:34:05] DEBUG[19522]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘192.168.1.147’ and port ‘47717’.
Sending to 192.168.1.1:47717 (no NAT)
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 22:34:05] DEBUG[19522]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x210eb8) called (obj->txf = (nil))
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:26566 build_peer: -REALTIME- peer built. Name: dex. Peer objects: 1
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 22:34:05] DEBUG[19522]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 22:34:05] DEBUG[19522]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319571245/0/1319571245)!
[Oct 25 22:34:05] DEBUG[19522]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4811 realtime_peer: -REALTIME- loading peer from database to memory. Name: dex. Peer objects: 1

<— Transmitting (no NAT) to 192.168.1.1:47717 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.147:47717;branch=z9hG4bKPjvrxuYmpgeMnygXw4ilX0x-fqJvITnHxm;received=192.168.1.1;rport=47717
From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
To: sip:dex@sip.sms1.ro;tag=as30dbc6ad
Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
CSeq: 12092 REGISTER
Server: Asterisk PBX 1.8.7.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="2fe73865"
Content-Length: 0

<------------>
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:47717
Scheduling destruction of SIP dialog ‘CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfO’ in 32000 ms (Method: REGISTER)
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4561 sip_destroy_peer: Destroying SIP peer dex
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4590 sip_destroy_peer: -REALTIME- peer Destroyed. Name: dex. Realtime Peer objects: 0

<— SIP read from UDP:192.168.1.1:47717 —>
REGISTER sip:sip.sms1.ro SIP/2.0
Via: SIP/2.0/UDP 192.168.1.147:47717;rport;branch=z9hG4bKPjt6UlfKqCCxmLUOZApEGF3CK529s1FNpO
Route: sip:sip.sms1.ro;transport=udp;lr
Max-Forwards: 70
From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
To: sip:dex@sip.sms1.ro
Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
CSeq: 12093 REGISTER
User-Agent: CSipSimple r1041 / vision-10
Contact: sip:dex@192.168.1.147:47717;ob
Expires: 900
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username=“dex”, realm=“asterisk”, nonce=“2fe73865”, uri=“sip:sip.sms1.ro”, response=“bac0fe2bc2f067ef8c96406777f09e71”, algorithm=MD5
Content-Length: 0

<------------->
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 0 [ 32]: REGISTER sip:sip.sms1.ro SIP/2.0
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 1 [ 91]: Via: SIP/2.0/UDP 192.168.1.147:47717;rport;branch=z9hG4bKPjt6UlfKqCCxmLUOZApEGF3CK529s1FNpO
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 2 [ 41]: Route: sip:sip.sms1.ro;transport=udp;lr
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 3 [ 16]: Max-Forwards: 70
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 4 [ 64]: From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 5 [ 25]: To: sip:dex@sip.sms1.ro
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 6 [ 41]: Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 7 [ 20]: CSeq: 12093 REGISTER
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 8 [ 40]: User-Agent: CSipSimple r1041 / vision-10
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 9 [ 41]: Contact: sip:dex@192.168.1.147:47717;ob
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 10 [ 12]: Expires: 900
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 11 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 12 [155]: Authorization: Digest username=“dex”, realm=“asterisk”, nonce=“2fe73865”, uri=“sip:sip.sms1.ro”, response=“bac0fe2bc2f067ef8c96406777f09e71”, algorithm=MD5
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 13 [ 17]: Content-Length: 0
— (14 headers 0 lines) —
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c
:7839 find_call: = Looking for Call ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV (Checking From) --From tag 5lsni6izGEvrdNsa0NFFwlC2.O9zGabu --To-tag
[Oct 25 22:34:05] DEBUG[19522]: acl.c:725 ast_ouraddrfor: For destination ‘192.168.1.1’, our source address is ‘192.168.1.2’.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:3479 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.1.2:5060
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c
:7519 sip_alloc: Allocating new SIP dialog for CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV - REGISTER (No RTP)
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:24477 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:24306 handle_request_register: Initializing initreq for method REGISTER - callid CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
[Oct 25 22:34:05] DEBUG[19522]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘192.168.1.147:47717’ into…
[Oct 25 22:34:05] DEBUG[19522]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘192.168.1.147’ and port ‘47717’.
Sending to 192.168.1.1:47717 (no NAT)
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 22:34:05] DEBUG[19522]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x210eb8) called (obj->txf = (nil))
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:26566 build_peer: -REALTIME- peer built. Name: dex. Peer objects: 1
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 22:34:05] DEBUG[19522]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 22:34:05] DEBUG[19522]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319571245/0/1319571245)!
[Oct 25 22:34:05] DEBUG[19522]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4811 realtime_peer: -REALTIME- loading peer from database to memory. Name: dex. Peer objects: 1

<— Transmitting (no NAT) to 192.168.1.1:47717 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.147:47717;branch=z9hG4bKPjt6UlfKqCCxmLUOZApEGF3CK529s1FNpO;received=192.168.1.1;rport=47717
From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
To: sip:dex@sip.sms1.ro;tag=as7c61a6a1
Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
CSeq: 12093 REGISTER
Server: Asterisk PBX 1.8.7.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="01355051"
Content-Length: 0

<------------>
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:47717
Scheduling destruction of SIP dialog ‘CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfO’ in 32000 ms (Method: REGISTER)
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4561 sip_destroy_peer: Destroying SIP peer dex
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4590 sip_destroy_peer: -REALTIME- peer Destroyed. Name: dex. Realtime Peer objects: 0

<— SIP read from UDP:192.168.1.1:47717 —>
REGISTER sip:sip.sms1.ro SIP/2.0
Via: SIP/2.0/UDP 192.168.1.147:47717;rport;branch=z9hG4bKPjyRrq9mmK11LZMXGYIiitiiyWCs5PdiHM
Route: sip:sip.sms1.ro;transport=udp;lr
Max-Forwards: 70
From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
To: sip:dex@sip.sms1.ro
Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
CSeq: 12094 REGISTER
User-Agent: CSipSimple r1041 / vision-10
Contact: sip:dex@192.168.1.147:47717;ob
Expires: 900
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username=“dex”, realm=“asterisk”, nonce=“01355051”, uri=“sip:sip.sms1.ro”, response=“3cc8b2751068d1ea9e9f55c14ce37fe5”, algorithm=MD5
Content-Length: 0

<------------->
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 0 [ 32]: REGISTER sip:sip.sms1.ro SIP/2.0
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 1 [ 91]: Via: SIP/2.0/UDP 192.168.1.147:47717;rport;branch=z9hG4bKPjyRrq9mmK11LZMXGYIiitiiyWCs5PdiHM
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 2 [ 41]: Route: sip:sip.sms1.ro;transport=udp;lr
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 3 [ 16]: Max-Forwards: 70
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 4 [ 64]: From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 5 [ 25]: To: sip:dex@sip.sms1.ro
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 6 [ 41]: Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 7 [ 20]: CSeq: 12094 REGISTER
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 8 [ 40]: User-Agent: CSipSimple r1041 / vision-10
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 9 [ 41]: Contact: sip:dex@192.168.1.147:47717;ob
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 10 [ 12]: Expires: 900
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 11 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 12 [155]: Authorization: Digest username=“dex”, realm=“asterisk”, nonce=“01355051”, uri=“sip:sip.sms1.ro”, response=“3cc8b2751068d1ea9e9f55c14ce37fe5”, algorithm=MD5
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 13 [ 17]: Content-Length: 0
— (14 headers 0 lines) —
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c
:7839 find_call: = Looking for Call ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV (Checking From) --From tag 5lsni6izGEvrdNsa0NFFwlC2.O9zGabu --To-tag
[Oct 25 22:34:05] DEBUG[19522]: acl.c:725 ast_ouraddrfor: For destination ‘192.168.1.1’, our source address is ‘192.168.1.2’.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:3479 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.1.2:5060
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c
:7519 sip_alloc: Allocating new SIP dialog for CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV - REGISTER (No RTP)
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:24477 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:24306 handle_request_register: Initializing initreq for method REGISTER - callid CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
[Oct 25 22:34:05] DEBUG[19522]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘192.168.1.147:47717’ into…
[Oct 25 22:34:05] DEBUG[19522]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘192.168.1.147’ and port ‘47717’.
Sending to 192.168.1.1:47717 (no NAT)
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 22:34:05] DEBUG[19522]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x210eb8) called (obj->txf = (nil))
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:26566 build_peer: -REALTIME- peer built. Name: dex. Peer objects: 1
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 22:34:05] DEBUG[19522]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 22:34:05] DEBUG[19522]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319571245/0/1319571245)!
[Oct 25 22:34:05] DEBUG[19522]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4811 realtime_peer: -REALTIME- loading peer from database to memory. Name: dex. Peer objects: 1

<— Transmitting (no NAT) to 192.168.1.1:47717 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.147:47717;branch=z9hG4bKPjyRrq9mmK11LZMXGYIiitiiyWCs5PdiHM;received=192.168.1.1;rport=47717
From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
To: sip:dex@sip.sms1.ro;tag=as6fd0ea68
Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
CSeq: 12094 REGISTER
Server: Asterisk PBX 1.8.7.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="17216ba2"
Content-Length: 0

<------------>
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:47717
Scheduling destruction of SIP dialog ‘CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfO’ in 32000 ms (Method: REGISTER)
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4561 sip_destroy_peer: Destroying SIP peer dex
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4590 sip_destroy_peer: -REALTIME- peer Destroyed. Name: dex. Realtime Peer objects: 0

<— SIP read from UDP:192.168.1.1:47717 —>
REGISTER sip:sip.sms1.ro SIP/2.0
Via: SIP/2.0/UDP 192.168.1.147:47717;rport;branch=z9hG4bKPjGazjcjteHj5E95yqNr2OvsXUGGoGH7LO
Route: sip:sip.sms1.ro;transport=udp;lr
Max-Forwards: 70
From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
To: sip:dex@sip.sms1.ro
Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
CSeq: 12095 REGISTER
User-Agent: CSipSimple r1041 / vision-10
Contact: sip:dex@192.168.1.147:47717;ob
Expires: 900
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username=“dex”, realm=“asterisk”, nonce=“17216ba2”, uri=“sip:sip.sms1.ro”, response=“28e8e8cdb3a807867699fd957d809abe”, algorithm=MD5
Content-Length: 0

<------------->
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 0 [ 32]: REGISTER sip:sip.sms1.ro SIP/2.0
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 1 [ 91]: Via: SIP/2.0/UDP 192.168.1.147:47717;rport;branch=z9hG4bKPjGazjcjteHj5E95yqNr2OvsXUGGoGH7LO
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 2 [ 41]: Route: sip:sip.sms1.ro;transport=udp;lr
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 3 [ 16]: Max-Forwards: 70
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 4 [ 64]: From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 5 [ 25]: To: sip:dex@sip.sms1.ro
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 6 [ 41]: Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 7 [ 20]: CSeq: 12095 REGISTER
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 8 [ 40]: User-Agent: CSipSimple r1041 / vision-10
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 9 [ 41]: Contact: sip:dex@192.168.1.147:47717;ob
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 10 [ 12]: Expires: 900
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 11 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 12 [155]: Authorization: Digest username=“dex”, realm=“asterisk”, nonce=“17216ba2”, uri=“sip:sip.sms1.ro”, response=“28e8e8cdb3a807867699fd957d809abe”, algorithm=MD5
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:8246 parse_request: Header 13 [ 17]: Content-Length: 0
— (14 headers 0 lines) —
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c
:7839 find_call: = Looking for Call ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV (Checking From) --From tag 5lsni6izGEvrdNsa0NFFwlC2.O9zGabu --To-tag
[Oct 25 22:34:05] DEBUG[19522]: acl.c:725 ast_ouraddrfor: For destination ‘192.168.1.1’, our source address is ‘192.168.1.2’.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:3479 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.1.2:5060
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c
:7519 sip_alloc: Allocating new SIP dialog for CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV - REGISTER (No RTP)
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:24477 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:24306 handle_request_register: Initializing initreq for method REGISTER - callid CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
[Oct 25 22:34:05] DEBUG[19522]: netsock2.c:134 ast_sockaddr_split_hostport: Splitting ‘192.168.1.147:47717’ into…
[Oct 25 22:34:05] DEBUG[19522]: netsock2.c:188 ast_sockaddr_split_hostport: …host ‘192.168.1.147’ and port ‘47717’.
Sending to 192.168.1.1:47717 (no NAT)
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:89 custom_prepare: Skip: 0; SQL: SELECT * FROM ast_sipfriends WHERE name = ? AND host = ?
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:104 custom_prepare: Parameter 1 (‘name’) = ‘dex’
[Oct 25 22:34:05] DEBUG[19522]: res_config_odbc.c:104 custom_prepare: Parameter 2 (‘host’) = ‘dynamic’
[Oct 25 22:34:05] DEBUG[19522]: res_odbc.c:1035 odbc_release_obj2: odbc_release_obj2(0x210eb8) called (obj->txf = (nil))
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:26566 build_peer: -REALTIME- peer built. Name: dex. Peer objects: 1
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27115 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27118 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27120 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27123 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[Oct 25 22:34:05] DEBUG[19522]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 22:34:05] DEBUG[19522]: db.c:337 ast_db_del: Unable to find key ‘dex’ in family ‘SIP/PeerMethods’
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:27136 build_peer: Bah, we’re expired (1319571245/0/1319571245)!
[Oct 25 22:34:05] DEBUG[19522]: db.c:293 ast_db_get: Unable to find key ‘dex’ in family ‘SIP/Registry’
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4811 realtime_peer: -REALTIME- loading peer from database to memory. Name: dex. Peer objects: 1

<— Transmitting (no NAT) to 192.168.1.1:47717 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.147:47717;branch=z9hG4bKPjGazjcjteHj5E95yqNr2OvsXUGGoGH7LO;received=192.168.1.1;rport=47717
From: sip:dex@sip.sms1.ro;tag=5lsni6izGEvrdNsa0NFFwlC2.O9zGabu
To: sip:dex@sip.sms1.ro;tag=as364dc77c
Call-ID: CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfOV
CSeq: 12095 REGISTER
Server: Asterisk PBX 1.8.7.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="3b8fabf3"
Content-Length: 0

<------------>
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:3325 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.1.1:47717
Scheduling destruction of SIP dialog ‘CWgrKPG4gyed1GyFk-d-Jv6Ibk1HQfO’ in 32000 ms (Method: REGISTER)
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4561 sip_destroy_peer: Destroying SIP peer dex
[Oct 25 22:34:05] DEBUG[19522]: chan_sip.c:4590 sip_destroy_peer: -REALTIME- peer Destroyed. Name: dex. Realtime Peer objects: 0

4

After trying out the sip_calc_auth tool, noticed I can’t really reproduce what it was trying to send ! :neutral_face: Also noticed some memory leaks…

despair got me to trying everything from scratch and just adding the a simple user ( hello world ) in users.conf and try to auth, no luck…

so … long story short … : issues.asterisk.org/jira/browse/ASTERISK-17310

the 2 lines that look like
main/utils.c:#ifdef sparc
should be
main/utils.c:#if defined(sparc) || defined(arm)

include/asterisk/compat.h:#ifdef sparc
becomes
include/asterisk/compat.h:#if defined(sparc) || defined(arm)

include/asterisk/unaligned.h:#elif defined(SOLARIS) && defined(sparc)
becomes
include/asterisk/unaligned.h:#elif (defined(SOLARIS) &&
defined(sparc)) || defined(arm)

each of these 3 files should also include some #define arm 1 at
the top of the file/defines section :smile:

problem solved !