[May 30 11:01:16] VERBOSE[13368] chan_sip.c: [May 30 11:01:16] [May 30 11:01:16] <--- SIP read from UDP:64.254.249.42:5060 ---> [May 30 11:01:16] [May 30 11:01:16] [May 30 11:01:16] <-------------> [May 30 11:01:16] DEBUG[13368] chan_sip.c: Header 0 [ 0]: [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] [May 30 11:01:23] <--- SIP read from UDP:64.254.249.42:5060 ---> [May 30 11:01:23] REGISTER sip:sipdev1-mtl.ca.ubity.com SIP/2.0 [May 30 11:01:23] Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKd5503ff7B4AAAC28 [May 30 11:01:23] From: "Support" ;tag=FD4956CC-B7AE3679 [May 30 11:01:23] To: [May 30 11:01:23] CSeq: 1 REGISTER [May 30 11:01:23] Call-ID: 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:23] Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" [May 30 11:01:23] User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:01:23] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:01:23] Max-Forwards: 70 [May 30 11:01:23] Expires: 120 [May 30 11:01:23] Content-Length: 0 [May 30 11:01:23] [May 30 11:01:23] <-------------> [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 0 [ 45]: REGISTER sip:sipdev1-mtl.ca.ubity.com SIP/2.0 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKd5503ff7B4AAAC28 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 2 [ 76]: From: "Support" ;tag=FD4956CC-B7AE3679 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 3 [ 42]: To: [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 4 [ 16]: CSeq: 1 REGISTER [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 5 [ 49]: Call-ID: 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 6 [136]: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 7 [ 66]: User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 8 [ 40]: Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 9 [ 16]: Max-Forwards: 70 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 10 [ 12]: Expires: 120 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] --- (12 headers 0 lines) --- [May 30 11:01:23] DEBUG[13368] chan_sip.c: = Looking for Call ID: 690cee25-fc96757e-d1d3e483@192.168.0.119 (Checking From) --From tag FD4956CC-B7AE3679 --To-tag [May 30 11:01:23] DEBUG[13368] acl.c: For destination '64.254.249.42', our source address is '64.254.249.210'. [May 30 11:01:23] DEBUG[13368] chan_sip.c: Target address 64.254.249.42:5060 is not local, substituting externaddr [May 30 11:01:23] DEBUG[13368] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 64.254.249.210:5060 [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting '192.168.0.119' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host '192.168.0.119' and port ''. [May 30 11:01:23] DEBUG[13368] chan_sip.c: NAT detected for 192.168.0.119 / 64.254.249.42 [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Sending to 64.254.249.42:5060 (NAT) [May 30 11:01:23] DEBUG[13368] chan_sip.c: Allocating new SIP dialog for 690cee25-fc96757e-d1d3e483@192.168.0.119 - REGISTER (No RTP) [May 30 11:01:23] DEBUG[13368] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 30 11:01:23] DEBUG[13368] chan_sip.c: Initializing initreq for method REGISTER - callid 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting '192.168.0.119' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host '192.168.0.119' and port ''. [May 30 11:01:23] DEBUG[13368] chan_sip.c: NAT detected for 192.168.0.119 / 64.254.249.42 [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Sending to 64.254.249.42:5060 (NAT) [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting 'sipdev1-mtl.ca.ubity.com' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host 'sipdev1-mtl.ca.ubity.com' and port ''. [May 30 11:01:23] DEBUG[13368] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM sipusers WHERE name = ? AND host = ? [May 30 11:01:23] DEBUG[13368] res_config_odbc.c: Parameter 1 ('name') = 'u010122' [May 30 11:01:23] DEBUG[13368] res_config_odbc.c: Parameter 2 ('host') = 'dynamic' [May 30 11:01:23] DEBUG[13368] res_odbc.c: odbc_release_obj2(0x22629b8) called (obj->txf = (nil)) [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting '64.254.249.42' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host '64.254.249.42' and port ''. [May 30 11:01:23] DEBUG[13368] acl.c: 64.254.249.42:0/255.255.255.255:0 sense 1 appended to ACL [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting '0.0.0.0' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host '0.0.0.0' and port ''. [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting '0.0.0.0' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host '0.0.0.0' and port ''. [May 30 11:01:23] DEBUG[13368] acl.c: 0.0.0.0:0/0.0.0.0:0 sense 0 appended to ACL [May 30 11:01:23] DEBUG[13368] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [May 30 11:01:23] DEBUG[13368] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [May 30 11:01:23] DEBUG[13368] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [May 30 11:01:23] DEBUG[13368] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting '64.254.249.42:5060' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host '64.254.249.42' and port '5060'. [May 30 11:01:23] DEBUG[13368] chan_sip.c: SIP Seeding peer from astdb: 'u010122' at u010122@64.254.249.42 for 120 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Allocating new SIP dialog for 557b2fd02087fe2e3f7465ce77f97b65@127.0.1.1:5060 - NOTIFY (No RTP) [May 30 11:01:23] DEBUG[13368] chan_sip.c: OBPROXY: Not applying OBproxy to this call [May 30 11:01:23] DEBUG[13368] acl.c: For destination '64.254.249.42', our source address is '64.254.249.210'. [May 30 11:01:23] DEBUG[13368] chan_sip.c: Target address 64.254.249.42:5060 is not local, substituting externaddr [May 30 11:01:23] DEBUG[13368] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 64.254.249.210:5060 [May 30 11:01:23] DEBUG[13368] chan_sip.c: SIP call-id changed from '557b2fd02087fe2e3f7465ce77f97b65@127.0.1.1:5060' to '752f00bf266c8b32634b493673e399ec@64.254.249.210:5060' [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Scheduling destruction of SIP dialog '752f00bf266c8b32634b493673e399ec@64.254.249.210:5060' in 32000 ms (Method: NOTIFY) [May 30 11:01:23] DEBUG[13368] chan_sip.c: Initializing initreq for method NOTIFY - callid 752f00bf266c8b32634b493673e399ec@64.254.249.210:5060 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 0 [ 40]: NOTIFY sip:u010122@192.168.0.119 SIP/2.0 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 64.254.249.210:5060;branch=z9hG4bK35f60b35;rport [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 3 [ 47]: From: ;tag=as3ceb5b47 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 4 [ 31]: To: [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 5 [ 40]: Contact: [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 6 [ 61]: Call-ID: 752f00bf266c8b32634b493673e399ec@64.254.249.210:5060 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 7 [ 16]: CSeq: 102 NOTIFY [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 8 [ 20]: User-Agent: Ubity UC [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 9 [ 22]: Event: message-summary [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 10 [ 48]: Content-Type: application/simple-message-summary [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Reliably Transmitting (NAT) to 64.254.249.42:5060: [May 30 11:01:23] NOTIFY sip:u010122@192.168.0.119 SIP/2.0 [May 30 11:01:23] Via: SIP/2.0/UDP 64.254.249.210:5060;branch=z9hG4bK35f60b35;rport [May 30 11:01:23] Max-Forwards: 70 [May 30 11:01:23] From: ;tag=as3ceb5b47 [May 30 11:01:23] To: [May 30 11:01:23] Contact: [May 30 11:01:23] Call-ID: 752f00bf266c8b32634b493673e399ec@64.254.249.210:5060 [May 30 11:01:23] CSeq: 102 NOTIFY [May 30 11:01:23] User-Agent: Ubity UC [May 30 11:01:23] Event: message-summary [May 30 11:01:23] Content-Type: application/simple-message-summary [May 30 11:01:23] Content-Length: 90 [May 30 11:01:23] [May 30 11:01:23] Messages-Waiting: no [May 30 11:01:23] Message-Account: sip:8500@64.254.249.210 [May 30 11:01:23] Voice-Message: 0/0 (0/0) [May 30 11:01:23] [May 30 11:01:23] --- [May 30 11:01:23] DEBUG[13368] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #5 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Trying to put 'NOTIFY sip:' onto UDP socket destined for 64.254.249.42:5060 [May 30 11:01:23] DEBUG[13368] chan_sip.c: -REALTIME- loading peer from database to memory. Name: u010122. Peer objects: 0 [May 30 11:01:23] NOTICE[13368] acl.c: SIP Peer ACL: Rejecting '64.254.249.42' due to a failure to pass ACL '(BASELINE)' [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] [May 30 11:01:23] <--- Transmitting (NAT) to 64.254.249.42:5060 ---> [May 30 11:01:23] SIP/2.0 401 Unauthorized [May 30 11:01:23] Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKd5503ff7B4AAAC28;received=64.254.249.42;rport=5060 [May 30 11:01:23] From: "Support" ;tag=FD4956CC-B7AE3679 [May 30 11:01:23] To: ;tag=as3cef0ba0 [May 30 11:01:23] Call-ID: 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:23] CSeq: 1 REGISTER [May 30 11:01:23] Server: Ubity UC [May 30 11:01:23] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [May 30 11:01:23] Supported: replaces, timer [May 30 11:01:23] WWW-Authenticate: Digest algorithm=MD5, realm="sipdev1-mtl", nonce="4af6a3ba" [May 30 11:01:23] Content-Length: 0 [May 30 11:01:23] [May 30 11:01:23] [May 30 11:01:23] <------------> [May 30 11:01:23] DEBUG[13368] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 64.254.249.42:5060 [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Scheduling destruction of SIP dialog '690cee25-fc96757e-d1d3e483@192.168.0.119' in 32000 ms (Method: REGISTER) [May 30 11:01:23] NOTICE[13368] chan_sip.c: Registration from '' failed for '64.254.249.42:5060' - Device does not match ACL [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Scheduling destruction of SIP dialog '690cee25-fc96757e-d1d3e483@192.168.0.119' in 32000 ms (Method: REGISTER) [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] [May 30 11:01:23] <--- SIP read from UDP:64.254.249.42:5060 ---> [May 30 11:01:23] SIP/2.0 200 OK [May 30 11:01:23] Via: SIP/2.0/UDP 64.254.249.210:5060;branch=z9hG4bK35f60b35;rport [May 30 11:01:23] From: ;tag=as3ceb5b47 [May 30 11:01:23] To: "Support" ;tag=26DE9291-DEDF68FA [May 30 11:01:23] CSeq: 102 NOTIFY [May 30 11:01:23] Call-ID: 752f00bf266c8b32634b493673e399ec@64.254.249.210:5060 [May 30 11:01:23] Contact: [May 30 11:01:23] Event: message-summary [May 30 11:01:23] User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:01:23] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:01:23] Content-Length: 0 [May 30 11:01:23] [May 30 11:01:23] <-------------> [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 64.254.249.210:5060;branch=z9hG4bK35f60b35;rport [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 2 [ 47]: From: ;tag=as3ceb5b47 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 3 [ 63]: To: "Support" ;tag=26DE9291-DEDF68FA [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 4 [ 16]: CSeq: 102 NOTIFY [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 5 [ 61]: Call-ID: 752f00bf266c8b32634b493673e399ec@64.254.249.210:5060 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 6 [ 36]: Contact: [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 7 [ 22]: Event: message-summary [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 8 [ 66]: User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 9 [ 40]: Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] --- (11 headers 0 lines) --- [May 30 11:01:23] DEBUG[13368] chan_sip.c: = Looking for Call ID: 752f00bf266c8b32634b493673e399ec@64.254.249.210:5060 (Checking To) --From tag as3ceb5b47 --To-tag 26DE9291-DEDF68FA [May 30 11:01:23] DEBUG[13368] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #5 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Stopping retransmission on '752f00bf266c8b32634b493673e399ec@64.254.249.210:5060' of Request 102: Match Found [May 30 11:01:23] DEBUG[13368] chan_sip.c: Got 200 accepted on NOTIFY 752f00bf266c8b32634b493673e399ec@64.254.249.210:5060 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Destroying SIP dialog 752f00bf266c8b32634b493673e399ec@64.254.249.210:5060 [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Really destroying SIP dialog '752f00bf266c8b32634b493673e399ec@64.254.249.210:5060' Method: NOTIFY [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] [May 30 11:01:23] <--- SIP read from UDP:64.254.249.42:5060 ---> [May 30 11:01:23] REGISTER sip:sipdev1-mtl.ca.ubity.com SIP/2.0 [May 30 11:01:23] Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKabe213cf1DC82220 [May 30 11:01:23] From: "Support" ;tag=FD4956CC-B7AE3679 [May 30 11:01:23] To: [May 30 11:01:23] CSeq: 2 REGISTER [May 30 11:01:23] Call-ID: 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:23] Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" [May 30 11:01:23] User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:01:23] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:01:23] Authorization: Digest username="u010122", realm="sipdev1-mtl", nonce="4af6a3ba", uri="sip:sipdev1-mtl.ca.ubity.com", response="96b5be8fa599a076edf57299ab49a290", algorithm=MD5 [May 30 11:01:23] Max-Forwards: 70 [May 30 11:01:23] Expires: 120 [May 30 11:01:23] Content-Length: 0 [May 30 11:01:23] [May 30 11:01:23] <-------------> [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 0 [ 45]: REGISTER sip:sipdev1-mtl.ca.ubity.com SIP/2.0 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKabe213cf1DC82220 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 2 [ 76]: From: "Support" ;tag=FD4956CC-B7AE3679 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 3 [ 42]: To: [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 4 [ 16]: CSeq: 2 REGISTER [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 5 [ 49]: Call-ID: 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 6 [136]: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 7 [ 66]: User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 8 [ 40]: Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 9 [175]: Authorization: Digest username="u010122", realm="sipdev1-mtl", nonce="4af6a3ba", uri="sip:sipdev1-mtl.ca.ubity.com", response="96b5be8fa599a076edf57299ab49a290", algorithm=MD5 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 10 [ 16]: Max-Forwards: 70 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 11 [ 12]: Expires: 120 [May 30 11:01:23] DEBUG[13368] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] --- (13 headers 0 lines) --- [May 30 11:01:23] DEBUG[13368] chan_sip.c: = Looking for Call ID: 690cee25-fc96757e-d1d3e483@192.168.0.119 (Checking From) --From tag FD4956CC-B7AE3679 --To-tag [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting 'sipdev1-mtl.ca.ubity.com' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host 'sipdev1-mtl.ca.ubity.com' and port ''. [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting 'sipdev1-mtl.ca.ubity.com' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host 'sipdev1-mtl.ca.ubity.com' and port ''. [May 30 11:01:23] DEBUG[13368] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 30 11:01:23] DEBUG[13368] chan_sip.c: Initializing initreq for method REGISTER - callid 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting '192.168.0.119' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host '192.168.0.119' and port ''. [May 30 11:01:23] DEBUG[13368] chan_sip.c: NAT detected for 192.168.0.119 / 64.254.249.42 [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Sending to 64.254.249.42:5060 (NAT) [May 30 11:01:23] DEBUG[13368] netsock2.c: Splitting 'sipdev1-mtl.ca.ubity.com' into... [May 30 11:01:23] DEBUG[13368] netsock2.c: ...host 'sipdev1-mtl.ca.ubity.com' and port ''. [May 30 11:01:23] NOTICE[13368] acl.c: SIP Peer ACL: Rejecting '64.254.249.42' due to a failure to pass ACL '(BASELINE)' [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] [May 30 11:01:23] <--- Transmitting (NAT) to 64.254.249.42:5060 ---> [May 30 11:01:23] SIP/2.0 403 Forbidden [May 30 11:01:23] Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKabe213cf1DC82220;received=64.254.249.42;rport=5060 [May 30 11:01:23] From: "Support" ;tag=FD4956CC-B7AE3679 [May 30 11:01:23] To: ;tag=as3cef0ba0 [May 30 11:01:23] Call-ID: 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:23] CSeq: 2 REGISTER [May 30 11:01:23] Server: Ubity UC [May 30 11:01:23] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [May 30 11:01:23] Supported: replaces, timer [May 30 11:01:23] Content-Length: 0 [May 30 11:01:23] [May 30 11:01:23] [May 30 11:01:23] <------------> [May 30 11:01:23] DEBUG[13368] chan_sip.c: Trying to put 'SIP/2.0 403' onto UDP socket destined for 64.254.249.42:5060 [May 30 11:01:23] NOTICE[13368] chan_sip.c: Registration from '' failed for '64.254.249.42:5060' - Device does not match ACL [May 30 11:01:23] VERBOSE[13368] chan_sip.c: [May 30 11:01:23] Scheduling destruction of SIP dialog '690cee25-fc96757e-d1d3e483@192.168.0.119' in 32000 ms (Method: REGISTER) [May 30 11:01:53] VERBOSE[13368] chan_sip.c: [May 30 11:01:53] [May 30 11:01:53] <--- SIP read from UDP:64.254.249.42:5060 ---> [May 30 11:01:53] [May 30 11:01:53] [May 30 11:01:53] <-------------> [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 0 [ 0]: [May 30 11:01:53] DEBUG[13368] chan_sip.c: Allocating new SIP dialog for 1711b023650d78566fc90ca97714b26e@127.0.1.1:5060 - OPTIONS (No RTP) [May 30 11:01:53] DEBUG[13368] acl.c: For destination '64.254.249.42', our source address is '64.254.249.210'. [May 30 11:01:53] DEBUG[13368] chan_sip.c: Target address 64.254.249.42:5060 is not local, substituting externaddr [May 30 11:01:53] DEBUG[13368] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 64.254.249.210:5060 [May 30 11:01:53] DEBUG[13368] chan_sip.c: SIP call-id changed from '1711b023650d78566fc90ca97714b26e@127.0.1.1:5060' to '37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060' [May 30 11:01:53] DEBUG[13368] chan_sip.c: Initializing initreq for method OPTIONS - callid 37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 0 [ 41]: OPTIONS sip:u010122@192.168.0.119 SIP/2.0 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 64.254.249.210:5060;branch=z9hG4bK5102932f;rport [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 3 [ 61]: From: "asterisk" ;tag=as5f6f0bbc [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 4 [ 31]: To: [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 5 [ 43]: Contact: [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 6 [ 61]: Call-ID: 37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 8 [ 20]: User-Agent: Ubity UC [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 9 [ 35]: Date: Fri, 30 May 2014 15:01:53 GMT [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 11 [ 19]: Supported: replaces [May 30 11:01:53] VERBOSE[13368] chan_sip.c: [May 30 11:01:53] Reliably Transmitting (NAT) to 64.254.249.42:5060: [May 30 11:01:53] OPTIONS sip:u010122@192.168.0.119 SIP/2.0 [May 30 11:01:53] Via: SIP/2.0/UDP 64.254.249.210:5060;branch=z9hG4bK5102932f;rport [May 30 11:01:53] Max-Forwards: 70 [May 30 11:01:53] From: "asterisk" ;tag=as5f6f0bbc [May 30 11:01:53] To: [May 30 11:01:53] Contact: [May 30 11:01:53] Call-ID: 37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060 [May 30 11:01:53] CSeq: 102 OPTIONS [May 30 11:01:53] User-Agent: Ubity UC [May 30 11:01:53] Date: Fri, 30 May 2014 15:01:53 GMT [May 30 11:01:53] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [May 30 11:01:53] Supported: replaces [May 30 11:01:53] Content-Length: 0 [May 30 11:01:53] [May 30 11:01:53] [May 30 11:01:53] --- [May 30 11:01:53] DEBUG[13368] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #10 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 64.254.249.42:5060 [May 30 11:01:53] VERBOSE[13368] chan_sip.c: [May 30 11:01:53] [May 30 11:01:53] <--- SIP read from UDP:64.254.249.42:5060 ---> [May 30 11:01:53] SIP/2.0 200 OK [May 30 11:01:53] Via: SIP/2.0/UDP 64.254.249.210:5060;branch=z9hG4bK5102932f;rport [May 30 11:01:53] From: "asterisk" ;tag=as5f6f0bbc [May 30 11:01:53] To: "Support" ;tag=7F20F132-FA1C02A7 [May 30 11:01:53] CSeq: 102 OPTIONS [May 30 11:01:53] Call-ID: 37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060 [May 30 11:01:53] Contact: [May 30 11:01:53] Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER [May 30 11:01:53] Supported: 100rel,replaces,100rel,timer,replaces,norefersub [May 30 11:01:53] User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:01:53] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:01:53] Accept: application/sdp,text/plain,message/sipfrag,application/dialog-info+xml [May 30 11:01:53] Accept-Encoding: identity [May 30 11:01:53] Content-Length: 0 [May 30 11:01:53] [May 30 11:01:53] <-------------> [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 64.254.249.210:5060;branch=z9hG4bK5102932f;rport [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 2 [ 61]: From: "asterisk" ;tag=as5f6f0bbc [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 3 [ 63]: To: "Support" ;tag=7F20F132-FA1C02A7 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 4 [ 17]: CSeq: 102 OPTIONS [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 5 [ 61]: Call-ID: 37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 6 [ 36]: Contact: [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 7 [ 96]: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 8 [ 59]: Supported: 100rel,replaces,100rel,timer,replaces,norefersub [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 9 [ 66]: User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 10 [ 40]: Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 11 [ 78]: Accept: application/sdp,text/plain,message/sipfrag,application/dialog-info+xml [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 12 [ 25]: Accept-Encoding: identity [May 30 11:01:53] DEBUG[13368] chan_sip.c: Header 13 [ 17]: Content-Length: 0 [May 30 11:01:53] VERBOSE[13368] chan_sip.c: [May 30 11:01:53] --- (14 headers 0 lines) --- [May 30 11:01:53] DEBUG[13368] chan_sip.c: = Looking for Call ID: 37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060 (Checking To) --From tag as5f6f0bbc --To-tag 7F20F132-FA1C02A7 [May 30 11:01:53] DEBUG[13368] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #10 [May 30 11:01:53] DEBUG[13368] chan_sip.c: Stopping retransmission on '37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060' of Request 102: Match Found [May 30 11:01:53] NOTICE[13368] chan_sip.c: Peer 'u010122' is now Reachable. (104ms / 5000ms) [May 30 11:01:53] DEBUG[13368] chan_sip.c: Destroying SIP dialog 37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060 [May 30 11:01:53] VERBOSE[13368] chan_sip.c: [May 30 11:01:53] Really destroying SIP dialog '37cc72757d709ae53d3526d26a89a2c4@64.254.249.210:5060' Method: OPTIONS [May 30 11:01:53] DEBUG[13388] devicestate.c: No provider found, checking channel drivers for SIP - u010122 [May 30 11:01:53] DEBUG[13388] chan_sip.c: Checking device state for peer u010122 [May 30 11:01:53] DEBUG[13388] devicestate.c: Changing state for SIP/u010122 - state 1 (Not in use) [May 30 11:01:53] DEBUG[13388] devicestate.c: device 'SIP/u010122' state '1' [May 30 11:01:54] DEBUG[13368] chan_sip.c: Auto destroying SIP dialog 'd6ba48fa33374ccca84a21222ece8fbb' [May 30 11:01:54] DEBUG[13368] chan_sip.c: Destroying SIP dialog d6ba48fa33374ccca84a21222ece8fbb [May 30 11:01:54] VERBOSE[13368] chan_sip.c: [May 30 11:01:54] Really destroying SIP dialog 'd6ba48fa33374ccca84a21222ece8fbb' Method: OPTIONS [May 30 11:01:55] DEBUG[13368] chan_sip.c: Auto destroying SIP dialog '690cee25-fc96757e-d1d3e483@192.168.0.119' [May 30 11:01:55] DEBUG[13368] chan_sip.c: Destroying SIP dialog 690cee25-fc96757e-d1d3e483@192.168.0.119 [May 30 11:01:55] VERBOSE[13368] chan_sip.c: [May 30 11:01:55] Really destroying SIP dialog '690cee25-fc96757e-d1d3e483@192.168.0.119' Method: REGISTER [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] [May 30 11:02:01] <--- SIP read from UDP:64.254.249.42:5060 ---> [May 30 11:02:01] REGISTER sip:sipdev1-mtl.ca.ubity.com SIP/2.0 [May 30 11:02:01] Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bK7d57718C94E0455 [May 30 11:02:01] From: "Support" ;tag=7A8AFE36-5E7299DB [May 30 11:02:01] To: [May 30 11:02:01] CSeq: 1 REGISTER [May 30 11:02:01] Call-ID: 536ebdee-fc1f9a33-8135b334@192.168.0.119 [May 30 11:02:01] Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" [May 30 11:02:01] User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:02:01] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:02:01] Max-Forwards: 70 [May 30 11:02:01] Expires: 120 [May 30 11:02:01] Content-Length: 0 [May 30 11:02:01] [May 30 11:02:01] <-------------> [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 0 [ 45]: REGISTER sip:sipdev1-mtl.ca.ubity.com SIP/2.0 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 1 [ 60]: Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bK7d57718C94E0455 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 2 [ 76]: From: "Support" ;tag=7A8AFE36-5E7299DB [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 3 [ 42]: To: [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 4 [ 16]: CSeq: 1 REGISTER [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 5 [ 49]: Call-ID: 536ebdee-fc1f9a33-8135b334@192.168.0.119 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 6 [136]: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 7 [ 66]: User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 8 [ 40]: Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 9 [ 16]: Max-Forwards: 70 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 10 [ 12]: Expires: 120 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] --- (12 headers 0 lines) --- [May 30 11:02:01] DEBUG[13368] chan_sip.c: = Looking for Call ID: 536ebdee-fc1f9a33-8135b334@192.168.0.119 (Checking From) --From tag 7A8AFE36-5E7299DB --To-tag [May 30 11:02:01] DEBUG[13368] acl.c: For destination '64.254.249.42', our source address is '64.254.249.210'. [May 30 11:02:01] DEBUG[13368] chan_sip.c: Target address 64.254.249.42:5060 is not local, substituting externaddr [May 30 11:02:01] DEBUG[13368] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 64.254.249.210:5060 [May 30 11:02:01] DEBUG[13368] netsock2.c: Splitting '192.168.0.119' into... [May 30 11:02:01] DEBUG[13368] netsock2.c: ...host '192.168.0.119' and port ''. [May 30 11:02:01] DEBUG[13368] chan_sip.c: NAT detected for 192.168.0.119 / 64.254.249.42 [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] Sending to 64.254.249.42:5060 (NAT) [May 30 11:02:01] DEBUG[13368] chan_sip.c: Allocating new SIP dialog for 536ebdee-fc1f9a33-8135b334@192.168.0.119 - REGISTER (No RTP) [May 30 11:02:01] DEBUG[13368] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 30 11:02:01] DEBUG[13368] chan_sip.c: Initializing initreq for method REGISTER - callid 536ebdee-fc1f9a33-8135b334@192.168.0.119 [May 30 11:02:01] DEBUG[13368] netsock2.c: Splitting '192.168.0.119' into... [May 30 11:02:01] DEBUG[13368] netsock2.c: ...host '192.168.0.119' and port ''. [May 30 11:02:01] DEBUG[13368] chan_sip.c: NAT detected for 192.168.0.119 / 64.254.249.42 [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] Sending to 64.254.249.42:5060 (NAT) [May 30 11:02:01] DEBUG[13368] netsock2.c: Splitting 'sipdev1-mtl.ca.ubity.com' into... [May 30 11:02:01] DEBUG[13368] netsock2.c: ...host 'sipdev1-mtl.ca.ubity.com' and port ''. [May 30 11:02:01] NOTICE[13368] acl.c: SIP Peer ACL: Rejecting '64.254.249.42' due to a failure to pass ACL '(BASELINE)' [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] [May 30 11:02:01] <--- Transmitting (NAT) to 64.254.249.42:5060 ---> [May 30 11:02:01] SIP/2.0 401 Unauthorized [May 30 11:02:01] Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bK7d57718C94E0455;received=64.254.249.42;rport=5060 [May 30 11:02:01] From: "Support" ;tag=7A8AFE36-5E7299DB [May 30 11:02:01] To: ;tag=as372aad9e [May 30 11:02:01] Call-ID: 536ebdee-fc1f9a33-8135b334@192.168.0.119 [May 30 11:02:01] CSeq: 1 REGISTER [May 30 11:02:01] Server: Ubity UC [May 30 11:02:01] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [May 30 11:02:01] Supported: replaces, timer [May 30 11:02:01] WWW-Authenticate: Digest algorithm=MD5, realm="sipdev1-mtl", nonce="094bd308" [May 30 11:02:01] Content-Length: 0 [May 30 11:02:01] [May 30 11:02:01] [May 30 11:02:01] <------------> [May 30 11:02:01] DEBUG[13368] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 64.254.249.42:5060 [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] Scheduling destruction of SIP dialog '536ebdee-fc1f9a33-8135b334@192.168.0.119' in 32000 ms (Method: REGISTER) [May 30 11:02:01] NOTICE[13368] chan_sip.c: Registration from '' failed for '64.254.249.42:5060' - Device does not match ACL [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] Scheduling destruction of SIP dialog '536ebdee-fc1f9a33-8135b334@192.168.0.119' in 32000 ms (Method: REGISTER) [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] [May 30 11:02:01] <--- SIP read from UDP:64.254.249.42:5060 ---> [May 30 11:02:01] REGISTER sip:sipdev1-mtl.ca.ubity.com SIP/2.0 [May 30 11:02:01] Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKfe0813c12D83A06A [May 30 11:02:01] From: "Support" ;tag=7A8AFE36-5E7299DB [May 30 11:02:01] To: [May 30 11:02:01] CSeq: 2 REGISTER [May 30 11:02:01] Call-ID: 536ebdee-fc1f9a33-8135b334@192.168.0.119 [May 30 11:02:01] Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" [May 30 11:02:01] User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:02:01] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:02:01] Authorization: Digest username="u010122", realm="sipdev1-mtl", nonce="094bd308", uri="sip:sipdev1-mtl.ca.ubity.com", response="a0f433152f9dffab3b402ae8b7d40963", algorithm=MD5 [May 30 11:02:01] Max-Forwards: 70 [May 30 11:02:01] Expires: 120 [May 30 11:02:01] Content-Length: 0 [May 30 11:02:01] [May 30 11:02:01] <-------------> [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 0 [ 45]: REGISTER sip:sipdev1-mtl.ca.ubity.com SIP/2.0 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKfe0813c12D83A06A [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 2 [ 76]: From: "Support" ;tag=7A8AFE36-5E7299DB [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 3 [ 42]: To: [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 4 [ 16]: CSeq: 2 REGISTER [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 5 [ 49]: Call-ID: 536ebdee-fc1f9a33-8135b334@192.168.0.119 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 6 [136]: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 7 [ 66]: User-Agent: PolycomSpectraLink-SL_8440-UA/4.0.2.11307_00907a0d753f [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 8 [ 40]: Accept-Language: fr-fr,fr;q=0.9,en;q=0.8 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 9 [175]: Authorization: Digest username="u010122", realm="sipdev1-mtl", nonce="094bd308", uri="sip:sipdev1-mtl.ca.ubity.com", response="a0f433152f9dffab3b402ae8b7d40963", algorithm=MD5 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 10 [ 16]: Max-Forwards: 70 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 11 [ 12]: Expires: 120 [May 30 11:02:01] DEBUG[13368] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] --- (13 headers 0 lines) --- [May 30 11:02:01] DEBUG[13368] chan_sip.c: = Looking for Call ID: 536ebdee-fc1f9a33-8135b334@192.168.0.119 (Checking From) --From tag 7A8AFE36-5E7299DB --To-tag [May 30 11:02:01] DEBUG[13368] netsock2.c: Splitting 'sipdev1-mtl.ca.ubity.com' into... [May 30 11:02:01] DEBUG[13368] netsock2.c: ...host 'sipdev1-mtl.ca.ubity.com' and port ''. [May 30 11:02:01] DEBUG[13368] netsock2.c: Splitting 'sipdev1-mtl.ca.ubity.com' into... [May 30 11:02:01] DEBUG[13368] netsock2.c: ...host 'sipdev1-mtl.ca.ubity.com' and port ''. [May 30 11:02:01] DEBUG[13368] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 30 11:02:01] DEBUG[13368] chan_sip.c: Initializing initreq for method REGISTER - callid 536ebdee-fc1f9a33-8135b334@192.168.0.119 [May 30 11:02:01] DEBUG[13368] netsock2.c: Splitting '192.168.0.119' into... [May 30 11:02:01] DEBUG[13368] netsock2.c: ...host '192.168.0.119' and port ''. [May 30 11:02:01] DEBUG[13368] chan_sip.c: NAT detected for 192.168.0.119 / 64.254.249.42 [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] Sending to 64.254.249.42:5060 (NAT) [May 30 11:02:01] DEBUG[13368] netsock2.c: Splitting 'sipdev1-mtl.ca.ubity.com' into... [May 30 11:02:01] DEBUG[13368] netsock2.c: ...host 'sipdev1-mtl.ca.ubity.com' and port ''. [May 30 11:02:01] NOTICE[13368] acl.c: SIP Peer ACL: Rejecting '64.254.249.42' due to a failure to pass ACL '(BASELINE)' [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] [May 30 11:02:01] <--- Transmitting (NAT) to 64.254.249.42:5060 ---> [May 30 11:02:01] SIP/2.0 403 Forbidden [May 30 11:02:01] Via: SIP/2.0/UDP 192.168.0.119;branch=z9hG4bKfe0813c12D83A06A;received=64.254.249.42;rport=5060 [May 30 11:02:01] From: "Support" ;tag=7A8AFE36-5E7299DB [May 30 11:02:01] To: ;tag=as372aad9e [May 30 11:02:01] Call-ID: 536ebdee-fc1f9a33-8135b334@192.168.0.119 [May 30 11:02:01] CSeq: 2 REGISTER [May 30 11:02:01] Server: Ubity UC [May 30 11:02:01] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [May 30 11:02:01] Supported: replaces, timer [May 30 11:02:01] Content-Length: 0 [May 30 11:02:01] [May 30 11:02:01] [May 30 11:02:01] <------------> [May 30 11:02:01] DEBUG[13368] chan_sip.c: Trying to put 'SIP/2.0 403' onto UDP socket destined for 64.254.249.42:5060 [May 30 11:02:01] NOTICE[13368] chan_sip.c: Registration from '' failed for '64.254.249.42:5060' - Device does not match ACL [May 30 11:02:01] VERBOSE[13368] chan_sip.c: [May 30 11:02:01] Scheduling destruction of SIP dialog '536ebdee-fc1f9a33-8135b334@192.168.0.119' in 32000 ms (Method: REGISTER)