[2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Allocating new SIP dialog for 5d911fa105d3a4a12fadac252685c1d2@127.0.0.1:0 - OPTIONS (No RTP) [2011-01-13 16:00:39] DEBUG[18629] acl.c: For destination '206.15.140.221', our source address is '99.192.227.172'. [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 99.192.227.172:5060 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Initializing initreq for method OPTIONS - callid 5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 0 [ 38]: OPTIONS sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK4846965c;rport [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 3 [ 59]: From: "Unknown" ;tag=as207abf58 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 4 [ 28]: To: [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 5 [ 42]: Contact: [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 6 [ 61]: Call-ID: 5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 8 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 9 [ 35]: Date: Thu, 13 Jan 2011 21:00:39 GMT [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [2011-01-13 16:00:39] VERBOSE[18629] chan_sip.c: Reliably Transmitting (NAT) to 206.15.140.221:5060: OPTIONS sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK4846965c;rport Max-Forwards: 70 From: "Unknown" ;tag=as207abf58 To: Contact: Call-ID: 5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060 CSeq: 102 OPTIONS User-Agent: FPBX-2.8.0(1.8.1.1) Date: Thu, 13 Jan 2011 21:00:39 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 --- [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 0 [ 38]: OPTIONS sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK4846965c;rport [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 3 [ 59]: From: "Unknown" ;tag=as207abf58 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 4 [ 28]: To: [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 5 [ 42]: Contact: [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 6 [ 61]: Call-ID: 5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 8 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 9 [ 35]: Date: Thu, 13 Jan 2011 21:00:39 GMT [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 13 [ 0]: [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #76 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:00:39] VERBOSE[18629] chan_sip.c: <--- SIP read from UDP:206.15.140.221:5060 ---> SIP/2.0 200 OK Call-ID: 5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060 CSeq: 102 OPTIONS From: "Unknown" ;tag=as207abf58 To: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK4846965c;rport=5060 Supported: 100rel Max-Forwards: 70 Allow: INVITE, BYE, ACK, OPTIONS, CANCEL, PRACK Accept: application/sdp Accept-Encoding: Accept-Language: en User-Agent: FPBX-2.8.0(1.8.1.1) Content-Length: 0 <-------------> [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 1 [ 61]: Call-ID: 5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 2 [ 17]: CSeq: 102 OPTIONS [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 3 [ 59]: From: "Unknown" ;tag=as207abf58 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 4 [ 28]: To: [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 5 [ 70]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK4846965c;rport=5060 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 6 [ 17]: Supported: 100rel [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 8 [ 47]: Allow: INVITE, BYE, ACK, OPTIONS, CANCEL, PRACK [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 9 [ 23]: Accept: application/sdp [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 10 [ 17]: Accept-Encoding: [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 11 [ 19]: Accept-Language: en [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 12 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 13 [ 20]: Content-Length: 0 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Header 14 [ 0]: [2011-01-13 16:00:39] VERBOSE[18629] chan_sip.c: --- (14 headers 0 lines) --- [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #76 [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Stopping retransmission on '5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060' of Request 102: Match Found [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: Destroying SIP dialog 5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060 [2011-01-13 16:00:39] VERBOSE[18629] chan_sip.c: Really destroying SIP dialog '5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060' Method: OPTIONS [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: ---------- SIP HISTORY for '5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060' [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: * SIP Call [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: 001. TxReqRel OPTIONS / 102 OPTIONS - OPTIONS [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: 002. Rx SIP/2.0 / 102 OPTIONS / 200 OK [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: 003. NeedDestroy Setting needdestroy because got OPTIONS response [2011-01-13 16:00:39] DEBUG[18629] chan_sip.c: ---------- END SIP HISTORY for '5a97e61f55fd82d55f4ff7681f466ac7@99.192.227.172:5060' [2011-01-13 16:00:58] NOTICE[18629] chan_sip.c: -- Re-registration for 2024488941@sip.broadvoice.com [2011-01-13 16:00:58] VERBOSE[18629] dnsmgr.c: > doing dnsmgr_lookup for 'sip.broadvoice.com' [2011-01-13 16:00:58] DEBUG[18629] netsock2.c: Splitting 'sip.broadvoice.com' gives... [2011-01-13 16:00:58] DEBUG[18629] netsock2.c: ...host 'sip.broadvoice.com' and port '(null)'. [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Allocating new SIP dialog for 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 - REGISTER (No RTP) [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: OBPROXY: Not applying OBproxy to this call [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 3 [2011-01-13 16:00:58] DEBUG[18629] acl.c: For destination '206.15.140.221', our source address is '99.192.227.172'. [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 99.192.227.172:5060 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 4 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Scheduled a registration timeout for sip.broadvoice.com id #79 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Initializing initreq for method REGISTER - callid 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 0 [ 39]: REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as221ee5dc [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 5 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 6 [ 18]: CSeq: 113 REGISTER [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 7 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 8 [ 12]: Expires: 120 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 9 [ 45]: Contact: [2011-01-13 16:00:58] VERBOSE[18629] chan_sip.c: REGISTER 10 headers, 0 lines [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: REGISTER attempt 1 to 2024488941@sip.broadvoice.com [2011-01-13 16:00:58] VERBOSE[18629] chan_sip.c: Reliably Transmitting (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 Max-Forwards: 70 From: ;tag=as221ee5dc To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 0 [ 39]: REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as221ee5dc [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 5 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 6 [ 18]: CSeq: 113 REGISTER [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 7 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 8 [ 12]: Expires: 120 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 9 [ 45]: Contact: [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 11 [ 0]: [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #80 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 3 [2011-01-13 16:00:58] VERBOSE[18629] chan_sip.c: <--- SIP read from UDP:206.15.140.221:5060 ---> SIP/2.0 401 Unauthorized Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER From: ;tag=as221ee5dc To: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 WWW-Authenticate: DIGEST realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXgiw59bppTeld4bvBW" Content-Length: 0 <-------------> [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 1 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 2 [ 18]: CSeq: 113 REGISTER [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as221ee5dc [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 5 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 6 [105]: WWW-Authenticate: DIGEST realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXgiw59bppTeld4bvBW" [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 7 [ 20]: Content-Length: 0 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 8 [ 0]: [2011-01-13 16:00:58] VERBOSE[18629] chan_sip.c: --- (8 headers 0 lines) --- [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Ignoring out of order response 113 (expecting 112) [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: SIP message could not be handled, bad request: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #80 (1) REGISTER - 2 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #80)) [2011-01-13 16:00:58] VERBOSE[18629] chan_sip.c: Retransmitting #1 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 Max-Forwards: 70 From: ;tag=as221ee5dc To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:00:58] VERBOSE[18629] chan_sip.c: <--- SIP read from UDP:206.15.140.221:5060 ---> SIP/2.0 401 Unauthorized Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER From: ;tag=as221ee5dc To: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 WWW-Authenticate: DIGEST realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXgiw59c3yTriqiajBW" Content-Length: 0 <-------------> [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 1 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 2 [ 18]: CSeq: 113 REGISTER [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as221ee5dc [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 5 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 6 [105]: WWW-Authenticate: DIGEST realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXgiw59c3yTriqiajBW" [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 7 [ 20]: Content-Length: 0 [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Header 8 [ 0]: [2011-01-13 16:00:58] VERBOSE[18629] chan_sip.c: --- (8 headers 0 lines) --- [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: Ignoring out of order response 113 (expecting 112) [2011-01-13 16:00:58] DEBUG[18629] chan_sip.c: SIP message could not be handled, bad request: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #80 (2) REGISTER - 2 [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #80)) [2011-01-13 16:00:59] VERBOSE[18629] chan_sip.c: Retransmitting #2 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 Max-Forwards: 70 From: ;tag=as221ee5dc To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:00:59] VERBOSE[18629] chan_sip.c: <--- SIP read from UDP:206.15.140.221:5060 ---> SIP/2.0 401 Unauthorized Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER From: ;tag=as221ee5dc To: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 WWW-Authenticate: DIGEST realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXgiw59cvwTsyjsu6BW" Content-Length: 0 <-------------> [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 1 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 2 [ 18]: CSeq: 113 REGISTER [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as221ee5dc [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 5 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 6 [105]: WWW-Authenticate: DIGEST realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXgiw59cvwTsyjsu6BW" [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 7 [ 20]: Content-Length: 0 [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Header 8 [ 0]: [2011-01-13 16:00:59] VERBOSE[18629] chan_sip.c: --- (8 headers 0 lines) --- [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: Ignoring out of order response 113 (expecting 112) [2011-01-13 16:00:59] DEBUG[18629] chan_sip.c: SIP message could not be handled, bad request: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #80 (3) REGISTER - 2 [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #80)) [2011-01-13 16:01:01] VERBOSE[18629] chan_sip.c: Retransmitting #3 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 Max-Forwards: 70 From: ;tag=as221ee5dc To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:01] VERBOSE[18629] chan_sip.c: <--- SIP read from UDP:206.15.140.221:5060 ---> SIP/2.0 401 Unauthorized Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER From: ;tag=as221ee5dc To: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 WWW-Authenticate: DIGEST realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXgiw59efhTepjb6fBW" Content-Length: 0 <-------------> [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 1 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 2 [ 18]: CSeq: 113 REGISTER [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as221ee5dc [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 5 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 6 [105]: WWW-Authenticate: DIGEST realm="BroadWorks",qop="auth",algorithm=MD5,nonce="BroadWorksXgiw59efhTepjb6fBW" [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 7 [ 20]: Content-Length: 0 [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Header 8 [ 0]: [2011-01-13 16:01:01] VERBOSE[18629] chan_sip.c: --- (8 headers 0 lines) --- [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: Ignoring out of order response 113 (expecting 112) [2011-01-13 16:01:01] DEBUG[18629] chan_sip.c: SIP message could not be handled, bad request: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:05] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #80 (4) REGISTER - 2 [2011-01-13 16:01:05] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #80)) [2011-01-13 16:01:05] VERBOSE[18629] chan_sip.c: Retransmitting #4 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 Max-Forwards: 70 From: ;tag=as221ee5dc To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:05] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: Auto destroying SIP dialog '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: Destroying SIP dialog 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:06] VERBOSE[18629] chan_sip.c: Really destroying SIP dialog '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' Method: REGISTER [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: ---------- SIP HISTORY for '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: * SIP Call [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 001. RegistryInit Account: 2024488941@sip.broadvoice.com [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 002. TxReqRel REGISTER / 112 REGISTER - REGISTER [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 003. ReTx 1000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 004. ReTx 2000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 005. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 006. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 007. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 008. Rx SIP/2.0 / 112 REGISTER / 200 OK [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 009. SchedDestroy 32000 ms [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 010. Rx SIP/2.0 / 113 REGISTER / 401 Unauthorized [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 011. Rx SIP/2.0 / 113 REGISTER / 401 Unauthorized [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 012. Rx SIP/2.0 / 113 REGISTER / 401 Unauthorized [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 013. Rx SIP/2.0 / 113 REGISTER / 401 Unauthorized [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: 014. AutoDestroy 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:06] DEBUG[18629] chan_sip.c: ---------- END SIP HISTORY for '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' [2011-01-13 16:01:09] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #80 (5) REGISTER - 2 [2011-01-13 16:01:09] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #80)) [2011-01-13 16:01:09] VERBOSE[18629] chan_sip.c: Retransmitting #5 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 Max-Forwards: 70 From: ;tag=as221ee5dc To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:09] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:13] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #80 (6) REGISTER - 2 [2011-01-13 16:01:13] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #80)) [2011-01-13 16:01:13] VERBOSE[18629] chan_sip.c: Retransmitting #6 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 Max-Forwards: 70 From: ;tag=as221ee5dc To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:13] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:17] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #80 (7) REGISTER - 2 [2011-01-13 16:01:17] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans id #80)) [2011-01-13 16:01:17] VERBOSE[18629] chan_sip.c: Retransmitting #7 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK5f0f0d32 Max-Forwards: 70 From: ;tag=as221ee5dc To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 113 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:17] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #80 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Stopping retransmission on '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' of Request 113: Match Found [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 2 [2011-01-13 16:01:18] VERBOSE[18629] dnsmgr.c: > doing dnsmgr_lookup for 'sip.broadvoice.com' [2011-01-13 16:01:18] DEBUG[18629] netsock2.c: Splitting 'sip.broadvoice.com' gives... [2011-01-13 16:01:18] DEBUG[18629] netsock2.c: ...host 'sip.broadvoice.com' and port '(null)'. [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Allocating new SIP dialog for 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 - REGISTER (No RTP) [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: OBPROXY: Not applying OBproxy to this call [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 3 [2011-01-13 16:01:18] DEBUG[18629] acl.c: For destination '206.15.140.221', our source address is '99.192.227.172'. [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 99.192.227.172:5060 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 4 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Scheduled a registration timeout for sip.broadvoice.com id #81 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Initializing initreq for method REGISTER - callid 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 0 [ 39]: REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as725784bf [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 5 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 6 [ 18]: CSeq: 114 REGISTER [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 7 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 8 [ 12]: Expires: 120 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 9 [ 45]: Contact: [2011-01-13 16:01:18] VERBOSE[18629] chan_sip.c: REGISTER 10 headers, 0 lines [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: REGISTER attempt 2 to 2024488941@sip.broadvoice.com [2011-01-13 16:01:18] VERBOSE[18629] chan_sip.c: Reliably Transmitting (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b Max-Forwards: 70 From: ;tag=as725784bf To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 114 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 0 [ 39]: REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as725784bf [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 5 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 6 [ 18]: CSeq: 114 REGISTER [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 7 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 8 [ 12]: Expires: 120 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 9 [ 45]: Contact: [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Header 11 [ 0]: [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #82 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:18] NOTICE[18629] chan_sip.c: -- Registration for '2024488941@sip.broadvoice.com' timed out, trying again (Attempt #2) [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 3 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Destroying SIP dialog 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:18] VERBOSE[18629] chan_sip.c: Really destroying SIP dialog '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' Method: REGISTER [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: ---------- SIP HISTORY for '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: * SIP Call [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 001. RegistryInit Account: 2024488941@sip.broadvoice.com [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 002. TxReqRel REGISTER / 113 REGISTER - REGISTER [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 003. ReTx 1000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 004. ReTx 2000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 005. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 006. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 007. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 008. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 009. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: 010. NeedDestroy Setting needdestroy because registration timeout [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: ---------- END SIP HISTORY for '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' [2011-01-13 16:01:18] DEBUG[18683] manager.c: Examining event: Event: Registry Privilege: system,all ChannelType: SIP Username: 2024488941 Domain: sip.broadvoice.com Status: Request Sent [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #82 (1) REGISTER - 2 [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #82)) [2011-01-13 16:01:18] VERBOSE[18629] chan_sip.c: Retransmitting #1 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b Max-Forwards: 70 From: ;tag=as725784bf To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 114 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:18] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:19] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #82 (2) REGISTER - 2 [2011-01-13 16:01:19] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #82)) [2011-01-13 16:01:19] VERBOSE[18629] chan_sip.c: Retransmitting #2 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b Max-Forwards: 70 From: ;tag=as725784bf To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 114 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:19] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:21] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #82 (3) REGISTER - 2 [2011-01-13 16:01:21] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #82)) [2011-01-13 16:01:21] VERBOSE[18629] chan_sip.c: Retransmitting #3 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b Max-Forwards: 70 From: ;tag=as725784bf To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 114 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:21] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:25] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #82 (4) REGISTER - 2 [2011-01-13 16:01:25] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #82)) [2011-01-13 16:01:25] VERBOSE[18629] chan_sip.c: Retransmitting #4 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b Max-Forwards: 70 From: ;tag=as725784bf To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 114 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:25] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:29] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #82 (5) REGISTER - 2 [2011-01-13 16:01:29] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #82)) [2011-01-13 16:01:29] VERBOSE[18629] chan_sip.c: Retransmitting #5 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b Max-Forwards: 70 From: ;tag=as725784bf To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 114 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:29] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:33] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #82 (6) REGISTER - 2 [2011-01-13 16:01:33] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #82)) [2011-01-13 16:01:33] VERBOSE[18629] chan_sip.c: Retransmitting #6 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b Max-Forwards: 70 From: ;tag=as725784bf To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 114 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:33] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:37] DEBUG[18629] chan_sip.c: SIP TIMER: Rescheduling retransmission #82 (7) REGISTER - 2 [2011-01-13 16:01:37] DEBUG[18629] chan_sip.c: ** SIP timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans id #82)) [2011-01-13 16:01:37] VERBOSE[18629] chan_sip.c: Retransmitting #7 (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK50c9511b Max-Forwards: 70 From: ;tag=as725784bf To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 114 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:37] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #82 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Stopping retransmission on '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' of Request 114: Match Found [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 2 [2011-01-13 16:01:38] VERBOSE[18629] dnsmgr.c: > doing dnsmgr_lookup for 'sip.broadvoice.com' [2011-01-13 16:01:38] DEBUG[18629] netsock2.c: Splitting 'sip.broadvoice.com' gives... [2011-01-13 16:01:38] DEBUG[18629] netsock2.c: ...host 'sip.broadvoice.com' and port '(null)'. [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Allocating new SIP dialog for 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 - REGISTER (No RTP) [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: OBPROXY: Not applying OBproxy to this call [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 3 [2011-01-13 16:01:38] DEBUG[18629] acl.c: For destination '206.15.140.221', our source address is '99.192.227.172'. [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 99.192.227.172:5060 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 4 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Scheduled a registration timeout for sip.broadvoice.com id #83 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Initializing initreq for method REGISTER - callid 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 0 [ 39]: REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK70968771 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as22fdb213 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 5 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 6 [ 18]: CSeq: 115 REGISTER [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 7 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 8 [ 12]: Expires: 120 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 9 [ 45]: Contact: [2011-01-13 16:01:38] VERBOSE[18629] chan_sip.c: REGISTER 10 headers, 0 lines [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: REGISTER attempt 3 to 2024488941@sip.broadvoice.com [2011-01-13 16:01:38] VERBOSE[18629] chan_sip.c: Reliably Transmitting (no NAT) to 206.15.140.221:5060: REGISTER sip:sip.broadvoice.com SIP/2.0 Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK70968771 Max-Forwards: 70 From: ;tag=as22fdb213 To: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 CSeq: 115 REGISTER User-Agent: FPBX-2.8.0(1.8.1.1) Expires: 120 Contact: Content-Length: 0 --- [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 0 [ 39]: REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP 99.192.227.172:5060;branch=z9hG4bK70968771 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 3 [ 56]: From: ;tag=as22fdb213 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 4 [ 39]: To: [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 5 [ 51]: Call-ID: 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 6 [ 18]: CSeq: 115 REGISTER [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 7 [ 31]: User-Agent: FPBX-2.8.0(1.8.1.1) [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 8 [ 12]: Expires: 120 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 9 [ 45]: Contact: [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Header 11 [ 0]: [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #84 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 206.15.140.221:5060 [2011-01-13 16:01:38] NOTICE[18629] chan_sip.c: -- Registration for '2024488941@sip.broadvoice.com' timed out, trying again (Attempt #3) [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: SIP Registry sip.broadvoice.com: refcount now 3 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: Destroying SIP dialog 73bc460f1cade6be099bfd2c53596b09@127.0.0.1 [2011-01-13 16:01:38] VERBOSE[18629] chan_sip.c: Really destroying SIP dialog '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' Method: REGISTER [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: ---------- SIP HISTORY for '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: * SIP Call [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 001. RegistryInit Account: 2024488941@sip.broadvoice.com [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 002. TxReqRel REGISTER / 114 REGISTER - REGISTER [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 003. ReTx 1000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 004. ReTx 2000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 005. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 006. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 007. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 008. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 009. ReTx 4000 REGISTER sip:sip.broadvoice.com SIP/2.0 [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: 010. NeedDestroy Setting needdestroy because registration timeout [2011-01-13 16:01:38] DEBUG[18629] chan_sip.c: ---------- END SIP HISTORY for '73bc460f1cade6be099bfd2c53596b09@127.0.0.1' [2011-01-13 16:01:38] DEBUG[18683] manager.c: Examining event: Event: Registry Privilege: system,all ChannelType: SIP Username: 2024488941 Domain: sip.broadvoice.com Status: Request Sent