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