[Sep 22 22:33:11] DEBUG[3602] config.c: Parsing /etc/asterisk/sip.conf [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: --------------- SIP reload started [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 1 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Destroying registry entry for uxxxxxxxxx@bxx.xxx.xx.xx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: --------------- Done destroying registry list [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Setting SIP channel User-Agent Name to xxxxxxx/xxx-x.x.xx [Sep 22 22:33:11] DEBUG[3602] config.c: extract inaddr from [0.0.0.0] gives [0.0.0.0:5060](0) [Sep 22 22:33:11] DEBUG[3602] config.c: extract inaddr from [exx.xxx.xxx.xxx] gives [exx.xxx.xxx.xxx:0](0) [Sep 22 22:33:11] DEBUG[3602] acl.c: 192.168.0.0/255.255.0.0 sense 0 appended to acl for peer [Sep 22 22:33:11] DEBUG[3602] acl.c: 10.0.0.0/255.0.0.0 sense 0 appended to acl for peer [Sep 22 22:33:11] DEBUG[3602] acl.c: xxx.xx.xxx.0/255.255.255.0 sense 0 appended to acl for peer [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 1 [Sep 22 22:33:11] DEBUG[3602] acl.c: Found one IP address based on local hostname hxxxxxxx. [Sep 22 22:33:11] DEBUG[3602] tcptls.c: Nothing changed in sip tcp server [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP reload_config done...Runtime= 0 sec [Sep 22 22:33:11] DEBUG[3602] sched.c: Asterisk Schedule Dump (0 in Q, 33 Total, 7 Cache, 7 high-water) [Sep 22 22:33:11] DEBUG[3602] sched.c: ============================================================= [Sep 22 22:33:11] DEBUG[3602] sched.c: |ID Callback Data Time (sec:ms) | [Sep 22 22:33:11] DEBUG[3602] sched.c: +-----+-----------------+-----------------+-----------------+ [Sep 22 22:33:11] DEBUG[3602] sched.c: ============================================================= [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: --------------- Done destroying pruned peers [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: do_reload finished. peer poke/prune reg contact time = 0 sec. [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: --------------- SIP reload done [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Allocating new SIP dialog for xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 - REGISTER (No RTP) [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 3 [Sep 22 22:33:11] DEBUG[3602] acl.c: Found IP address for this socket [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Setting SIP_TRANSPORT_UDP with address ax.xx.xxx.xxx:5060 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 4 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Scheduled a registration timeout for bxx.xxx.xx.xx id #41 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Initializing initreq for method REGISTER - callid xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 0 [ 34]: REGISTER sip:bxx.xxx.xx.xx SIP/2.0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP ax.xx.xxx.xxx:5060;branch=xxxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 3 [ 51]: From: ;tag=asxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 4 [ 34]: To: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 5 [ 51]: Call-ID: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 6 [ 18]: CSeq: 102 REGISTER [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 7 [ 31]: User-Agent: xxxxxxx/xxx-x.x.xx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 8 [ 13]: Expires: 3600 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 9 [ 40]: Contact: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: REGISTER attempt 1 to uxxxxxxxxx@bxx.xxx.xx.xx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 0 [ 34]: REGISTER sip:bxx.xxx.xx.xx SIP/2.0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP ax.xx.xxx.xxx:5060;branch=xxxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 3 [ 51]: From: ;tag=asxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 4 [ 34]: To: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 5 [ 51]: Call-ID: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 6 [ 18]: CSeq: 102 REGISTER [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 7 [ 31]: User-Agent: xxxxxxx/xxx-x.x.xx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 8 [ 13]: Expires: 3600 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 9 [ 40]: Contact: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 11 [ 0]: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #42 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for bxx.xxx.xx.xx:5060 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 3 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 0 [ 41]: SIP/2.0 407 Proxy Authentication Required [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 1 [158]: Proxy-Authenticate:Digest nonce="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx",opaque="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx",algorithm=md5,realm="xxxxxxxxx",qop="auth" [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 2 [ 50]: From:;tag=asxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 3 [ 62]: To:;tag=xxxxxxxxxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 4 [ 50]: Call-ID:xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 5 [ 17]: CSeq:102 REGISTER [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 6 [ 58]: Via:SIP/2.0/UDP ax.xx.xxx.xxx:5060;branch=xxxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 7 [ 16]: Content-Length:0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 8 [ 0]: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #42 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for bxx.xxx.xx.xx:5060 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 3 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 0 [ 41]: SIP/2.0 407 Proxy Authentication Required [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 1 [158]: Proxy-Authenticate:Digest nonce="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx",opaque="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx",algorithm=md5,realm="xxxxxxxxx",qop="auth" [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 2 [ 50]: From:;tag=asxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 3 [ 62]: To:;tag=xxxxxxxxxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 4 [ 50]: Call-ID:xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 5 [ 17]: CSeq:102 REGISTER [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 6 [ 58]: Via:SIP/2.0/UDP ax.xx.xxx.xxx:5060;branch=xxxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 7 [ 16]: Content-Length:0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 8 [ 0]: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #42 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Stopping retransmission on 'xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2' of Request 102: Match Found [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Initializing already initialized SIP dialog xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 (presumably reinvite) [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 0 [ 34]: REGISTER sip:bxx.xxx.xx.xx SIP/2.0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP ax.xx.xxx.xxx:5060;branch=2xxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 3 [ 51]: From: ;tag=as2xxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 4 [ 34]: To: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 5 [ 51]: Call-ID: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 6 [ 18]: CSeq: 103 REGISTER [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 7 [ 31]: User-Agent: xxxxxxx/xxx-x.x.xx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 8 [287]: Proxy-Authorization: Digest username="uxxxxxxxxx", realm="xxxxxxxxx", algorithm=MD5, uri="sip:bxx.xxx.xx.xx", nonce="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx", response="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx", opaque="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx", qop =auth, cnonce="xxxxxxxx", nc=xxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 9 [ 13]: Expires: 3600 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 10 [ 40]: Contact: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: REGISTER attempt 2 to uxxxxxxxxx@bxx.xxx.xx.xx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 0 [ 34]: REGISTER sip:bxx.xxx.xx.xx SIP/2.0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP ax.xx.xxx.xxx:5060;branch=2xxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 3 [ 51]: From: ;tag=as2xxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 4 [ 34]: To: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 5 [ 51]: Call-ID: xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 6 [ 18]: CSeq: 103 REGISTER [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 7 [ 31]: User-Agent: xxxxxxx/xxx-x.x.xx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 8 [287]: Proxy-Authorization: Digest username="uxxxxxxxxx", realm="xxxxxxxxx", algorithm=MD5, uri="sip:bxx.xxx.xx.xx", nonce="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx", response="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx", opaque="xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx", qop =auth, cnonce="xxxxxxxx", nc=xxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 9 [ 13]: Expires: 3600 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 10 [ 40]: Contact: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 12 [ 0]: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #43 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for bxx.xxx.xx.xx:5060 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 1 [ 50]: Contact:sip:uxxxxxxxxx@ax.xx.xxx.xxx;expires=3170 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 2 [ 50]: From:;tag=as2xxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 3 [ 62]: To:;tag=2xxxxxxxxxxxxxxxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 4 [ 50]: Call-ID:xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 5 [ 17]: CSeq:103 REGISTER [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 6 [ 58]: Via:SIP/2.0/UDP ax.xx.xxx.xxx:5060;branch=2xxxxxxxxxxxxxxx [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 7 [ 16]: Content-Length:0 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Header 8 [ 0]: [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #43 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Stopping retransmission on 'xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx@127.0.0.2' of Request 103: Match Found [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Registration successful [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: Cancelling timeout 41 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 2 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 1 [Sep 22 22:33:11] DEBUG[3602] chan_sip.c: SIP Registry bxx.xxx.xx.xx: refcount now 2