root@pbx:~ $ asterisk -vvvr
Asterisk 11.6.0, Copyright (C) 1999 - 2013 Digium, Inc. and others.
Created by Mark Spencer <[email protected]>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'core show license' for details.
=========================================================================
Connected to Asterisk 11.6.0 currently running on pbx (pid = 1848)
[2013-11-11 14:40:37] DEBUG[1964]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:37] DEBUG[1960]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:37] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 15 for 127.0.0.1
[2013-11-11 14:40:37] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 14 for 127.0.0.1
[2013-11-11 14:40:37] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 13 for 127.0.0.1
[2013-11-11 14:40:37] DEBUG[1929]: chan_iax2.c:2500 peercnt_remove: ip callno count decremented to 12 for 127.0.0.1
[2013-11-11 14:40:37] DEBUG[1961]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:37] DEBUG[1958]: chan_iax2.c:2824 sched_delay_remove: schedule decrement of callno used for 127.0.0.1 in 60 seconds
[2013-11-11 14:40:37] DEBUG[1957]: chan_iax2.c:1635 __send_lagrq: I was supposed to send a LAGRQ with callno 13128, but no such call exists.
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 3
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 2
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:8764 sip_alloc: Allocating new SIP dialog for 6047e6842b085669449c485b057472e3@[::1] - REGISTER (No RTP)
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 3
[2013-11-11 14:40:37] DEBUG[1977]: acl.c:979 ast_ouraddrfor: For destination '208.100.39.52', our source address is '172.16.6.8'.
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:4031 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 172.16.6.8:5060
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 4
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:15268 transmit_register: Scheduled a registration timeout for chicago.voip.ms id #19975
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:37] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3517 initialize_initreq: Initializing initreq for method REGISTER - callid 6047e6842b085669449c485b057472e3@[::1]
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:15343 transmit_register: REGISTER attempt 1 to [email protected]
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'REGISTER si' onto UDP socket destined for 208.100.39.52:5060
[2013-11-11 14:40:37] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 3
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:4138 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #19976))
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'REGISTER si' onto UDP socket destined for 208.100.39.52:5060
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for Call ID: 6047e6842b085669449c485b057472e3@[::1] (Checking To) --From tag as77ee7150 --To-tag as2139cb81
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:4566 __sip_ack: Stopping retransmission on '6047e6842b085669449c485b057472e3@[::1]' of Request 360: Match Found
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 4
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 3
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'chicago.voip.ms' into...
[2013-11-11 14:40:38] DEBUG[1977]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'chicago.voip.ms' and port ''.
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3515 initialize_initreq: Initializing already initialized SIP dialog 6047e6842b085669449c485b057472e3@[::1] (presumably reinvite)
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:15343 transmit_register: REGISTER attempt 2 to [email protected]
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3874 __sip_xmit: Trying to put 'REGISTER si' onto UDP socket destined for 208.100.39.52:5060
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:9161 find_call: = Looking for Call ID: 6047e6842b085669449c485b057472e3@[::1] (Checking To) --From tag as48157849 --To-tag as2139cb81
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:4566 __sip_ack: Stopping retransmission on '6047e6842b085669449c485b057472e3@[::1]' of Request 361: Match Found
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:23373 handle_response_register: Registration successful
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:23375 handle_response_register: Cancelling timeout 19975
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 2
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3468 registry_unref: SIP Registry chicago.voip.ms: refcount now 1
[2013-11-11 14:40:38] DEBUG[1977]: chan_sip.c:3476 registry_addref: SIP Registry chicago.voip.ms: refcount now 2
[2013-11-11 14:40:47] DEBUG[1960]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 13 for 127.0.0.1
[2013-11-11 14:40:47] DEBUG[1964]: chan_iax2.c:2468 peercnt_add: ip callno count incremented to 14 for 127.0.0.1