[Dec 11 09:53:06] VERBOSE[8342] logger.c: <--- SIP read from 192.168.0.2:5060 ---> INVITE sip:1002@192.168.0.2:5068 SIP/2.0 Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-xgdb8d6pv4oovkz+gcixlw-UAC-odzvcj8qj1m4u2jqitlijdw Via: SIP/2.0/TCP skinner.hem.za.org:5090;branch=z9hG4bK-yxa-nxek_vqdjvb_3br941n1cg-UAC-og2ad1l2ihjz9oucgnf1tlw;received=[fdb1:a958:1911:0:202:44ff:fe1d:d021] Via: SIP/2.0/TCP 192.168.0.2:49760;branch=z9hG4bK1680707439;received=192.168.0.2;rport=49760 From: ;tag=1657306396 To: Call-ID: 1251964339@192.168.0.2 CSeq: 602 INVITE Record-Route: Record-Route: Max-Forwards: 68 Contact: ;expires=1000 User-Agent: Minisip Supported: 100rel, sdp-anat Proxy-Authorization: Digest algorithm=MD5,username="2002",realm="hem.za.org",nonce="671083db2b6c58a8d66179de30c6e54f",uri="sip:1002@192.168.0.2:5068",response="bd4a2593a1be57aa91851b5f8dfc1d4a",opaque="457d1c72" Content-Type: application/sdp Content-Length: 3102 v=0 o=- 3344 3344 IN IP4 192.168.0.2 s=Minisip Session t=0 0 a=key-mgmt:mikey AQQFgHsJ+vQIAAB/AMdMAAAAAAAAAAAAAAAAAAFt5g1zAAAAAAEAAAAAAAAAAAJ/AMdMAAAAAAIAAAAAAAAAAANt5g1zAAAAAAMAAAAAAAAAAAoAySeM4r2NzbMKAAAAJwABAQEBEAIBAQMBFAQBDgUBAAYBAAcBAQgBAQkBAAoBAQsBCgwBAAoBAAAnAAEBAQEQAgEBAwEUBAEOBQEABgEABwEBCAEBCQEACgEBCwEKDAEACgIAACcAAQEBARACAQEDARQEAQ4FAQAGAQAHAQEIAQEJAQAKAQELAQoMAQALAwAAJwABAQEBEAIBAQMBFAQBDgUBAAYBAAcBAQgBAQkBAAoBAQsBCgwBAAcQpbVaE6z5/Pk5wMFLvNa/4wMCBO8wggTrMIID06ADAgECAgEQMA0GCSqGSIb3DQEBBAUAMHgxCzAJBgNVBAYTAlNFMRUwEwYDVQQIFAzWc3Rlcmf2dGxhbmQxEjAQBgNVBAcUCUxpbmv2cGluZzETMBEGA1UEChMKaGVtLnphLm9yZzELMAkGA1UEAxMCQ0ExHDAaBgkqhkiG9w0BCQEWDWNhQGhlbS56YS5vcmcwHhcNMDYwNjI2MTI1MDI3WhcNMDcwNjE3MTI1MDI3WjCBoTELMAkGA1UEBhMCU0UxITAfBgNVBAgeGADWAHMAdABlAHIAZwD2AHQAbABhAG4AZDEbMBkGA1UEBx4SAEwAaQBuAGsA9gBwAGkAbgBnMRMwEQYDVQQKEwpoZW0uemEub3JnMRkwFwYDVQQDExB0ZXN0Mi5oZW0uemEub3JnMSIwIAYJKoZIhvcNAQkBFhNzZWN1cml0eUBoZW0uemEub3JnMIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEA0mclkTMjAzeHrhMaYt6ug3cTnNRW02T+dIwXU3EoLKyTd0ECd9A4wtbeBZb9XoYaucqrnXAjedo9wkQKB/zhh50Cxw/dmXUKN3V4jYe4k2gR044k0FjkuqKDbSQSd8xuqHQC19T75NNvIdt5yRSoCf2JbDXGUskSty64noUlwX4WtEg6h7APPMBRn29o2bHhL4G09iwsfEQtx/dQONYNZsji15so5cr6ZfDEuw7kgu0Ier6hVL0HQahWP9pU/BsXgQQ4luHnUz2AT16CQmb7VgWCEaEkKuIZ1Xy+Ujlq8aa2W3MepxEPhtNbu5yZPtYkC1MQIGL+vVnXPWKKmFujZQIDAQABo4IBVDCCAVAwDAYDVR0TAQH/BAIwADAdBgNVHQ4EFgQUDYChmHcFHKp9We3S1+L8FensK8MwgaIGA1UdIwSBmjCBl4AU74Rd/7D+BlDnatCxleXbE8qHUP2hfKR6MHgxCzAJBgNVBAYTAlNFMRUwEwYDVQQIFAzWc3Rlcmf2dGxhbmQxEjAQBgNVBAcUCUxpbmv2cGluZzETMBEGA1UEChMKaGVtLnphLm9yZzELMAkGA1UEAxMCQ0ExHDAaBgkqhkiG9w0BCQEWDWNhQGhlbS56YS5vcmeCAQAwCwYDVR0PBAQDAgXgMBsGA1UdEQQUMBKCEHRlc3QyLmhlbS56YS5vcmcwEQYJYIZIAYb4QgEBBAQDAgZAMB8GCWCGSAGG+EIBDAQSFhB0ZXN0Mi5oZW0uemEub3JnMB4GCWCGSAGG+EIBDQQRFg94Y2EgY2VydGlmaWNhdGUwDQYJKoZIhvcNAQEEBQADggEBACBrYyeNsPjHflvyNOu+hPoz8nM8e5b8H45FtFWGb1QXOVCycqkltmZ3P68gDNjgXj7LhutruTMakWlwk2hg975eUlkgvBVlAltl9ZOYxivoBH6M35v5dMbLxFNH7jRc4ooTiJIv9OsjAZsmhKwcBOhnbIk6MIAyb1GFiFxLGHUwBke1UN1wOiztA6+unHf2bmcrHn9jdLa9F15wDPFmgt+1ArNiZMNHoJeCttlecqEJhDZ+0WgqB3ElDBEwwPl4DoCbxeIaNU6ukhodBfY3eahIZ71kOtlj0ULXO6gtfP7FxIzRIOJoS6LoV7dSfgCUSs9DXVZb2FOUgfMWlGPH2cEEALZUgRFhx/a5smawwhizV2CqnayhOxczwia5tnyDGQsF2WNfP6I1u73wo5c/Tk+cDrJpgGTrOyzV57kL+CxNvsBmYt7luvpdjy9dSg/RJ2b1+8E/41NI9r00r39RR5zo8evevLiQB6Zda/1AfPGy9R/Z9NVsBJmFyi4dX3/dYo2YOJ1hYOpJhmlGZsWnpv9K5LVpbu5xAUJQZ61hpUgPtdQZgeYTUHasUtvdyNEOhrk7NyTl1achzswQ+QHwPK0sPwABAMGb0SW1Z/C8O/lpt1ODu1Fda5wkCh/lsnY/Ugu+b4MHTOIWHJlVD8Co1udAawpnm3Log2uf2zXWIgtE/fTZFAdDENU3e5AnX5X0PIefwQVqtGMFJe1OXZoEHTbsYSGQhbbypqDwwg9Z8yPmpNW3qRi9DN42U/fZ7M8VEoALFe+w58eXca9o2rC0sO2AHscsDLENLQO+/HBZQrn6/bMf/SyfwNz+Ij1MFgKvORBEjFts3m8xrKAGFjnvLGV6AhARGlBh+XKE1RaZ7BHGSlD0yfvv0nz1ACOExG88ASLQkfDKrcJdx+/uKXh92Wp0hPHG8flFKm6ObE9vqiKUiy3CDEU= m=audio 30460 RTP/SAVP 0 3 114 101 c=IN IP4 192.168.0.2 a=rtpmap:0 PCMU/8000/1 a=rtpmap:3 GSM/8000 a=rtpmap:114 speex/8000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 m=video 30630 RTP/SAVP 105 101 c=IN IP4 192.168.0.2 a=rtpmap:105 h263-1998/90000 a=rtpmap:101 telephone- <-------------> [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 0 [ 40]: INVITE sip:1002@192.168.0.2:5068 SIP/2.0 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 1 [110]: Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-xgdb8d6pv4oovkz+gcixlw-UAC-odzvcj8qj1m4u2jqitlijdw [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 2 [157]: Via: SIP/2.0/TCP skinner.hem.za.org:5090;branch=z9hG4bK-yxa-nxek_vqdjvb_3br941n1cg-UAC-og2ad1l2ihjz9oucgnf1tlw;received=[fdb1:a958:1911:0:202:44ff:fe1d:d021] [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 3 [ 92]: Via: SIP/2.0/TCP 192.168.0.2:49760;branch=z9hG4bK1680707439;received=192.168.0.2;rport=49760 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 4 [ 50]: From: ;tag=1657306396 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 5 [ 31]: To: [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 6 [ 31]: Call-ID: 1251964339@192.168.0.2 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 7 [ 16]: CSeq: 602 INVITE [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 8 [ 46]: Record-Route: [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 9 [ 51]: Record-Route: [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 10 [ 16]: Max-Forwards: 68 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 11 [ 64]: Contact: ;expires=1000 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 12 [ 19]: User-Agent: Minisip [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 13 [ 27]: Supported: 100rel, sdp-anat [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 14 [211]: Proxy-Authorization: Digest algorithm=MD5,username="2002",realm="hem.za.org",nonce="671083db2b6c58a8d66179de30c6e54f",uri="sip:1002@192.168.0.2:5068",response="bd4a2593a1be57aa91851b5f8dfc1d4a",opaque="457d1c72" [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 15 [ 29]: Content-Type: application/sdp [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 16 [ 20]: Content-Length: 3102 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 17 [ 0]: [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 0 [ 3]: v=0 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 1 [ 32]: o=- 3344 3344 IN IP4 192.168.0.2 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 2 [ 17]: s=Minisip Session [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 3 [ 5]: t=0 0 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 4 [2693]: a=key-mgmt:mikey AQQFgHsJ+vQIAAB/AMdMAAAAAAAAAAAAAAAAAAFt5g1zAAAAAAEAAAAAAAAAAAJ/AMdMAAAAAAIAAAAAAAAAAANt5g1zAAAAAAMAAAAAAAAAAAoAySeM4r2NzbMKAAAAJwABAQEBEAIBAQMBFAQBDgUBAAYBAAcBAQgBAQkBAAoBAQsBCgwBAAoBAAAnAAEBAQEQAgEBAwEUBAEOBQEABgEABwEBCAEBCQEACgEBCwEKDAEACgIAACcAAQEBARACAQEDARQEAQ4FAQAGAQAHAQEIAQEJAQAKAQELAQoMAQALAwAAJwABAQEBEAIBAQMBFAQBDgUBAAYBAAcBAQgBAQkBAAoBAQsBCgwBAAcQpbVaE6z5/Pk5wMFLvNa/4wMCBO8wggTrMIID06ADAgECAgEQMA0GCSqGSIb3DQEBBAUAMHgxCzAJBgNVBAYTAlNFMRUwEwYDVQQIFAzWc3Rlcmf2dGxhbmQxEjAQBgNVBAcUCUxpbmv2cGluZzETMBEGA1UEChMKaGVtLnphLm9yZzELMAkGA1UEAxMCQ0ExHDAaBgkqhkiG9w0BCQEWDWNhQGhlbS56YS5vcmcwHhcNMDYwNjI2MTI1MDI3WhcNMDcwNjE3MTI1MDI3WjCBoTELMAkGA1UEBhMCU0UxITAfBgNVBAgeGADWAHMAdABlAHIAZwD2AHQAbABhAG4AZDEbMBkGA1UEBx4SAEwAaQBuAGsA9gBwAGkAbgBnMRMwEQYDVQQKEwpoZW0uemEub3JnMRkwFwYDVQQDExB0ZXN0Mi5oZW0uemEub3JnMSIwIAYJKoZIhvcNAQkBFhNzZWN1cml0eUBoZW0uemEub3JnMIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEA0mclkTMjAzeHrhMaYt6ug3cTnNRW02T+dIwXU3EoLKyTd0ECd9A4wtbeBZb9XoYaucqrnXAjedo9wkQKB/zhh50Cxw/dmXUKN3V4jYe4k2gR044k0FjkuqKDbSQSd8xuqHQC19T75NNvIdt5yRSoCf2JbDXGUskSty64noUlwX4WtEg6h7APPMBRn29o2bHhL4G09iwsfEQtx/dQONYNZsji15so5cr6ZfDEuw7kgu0Ier6hVL0HQahWP9pU/BsXgQQ4luHnUz2AT16CQmb7VgWCEaEkKuIZ1Xy+Ujlq8aa2W3MepxEPhtNbu5yZPtYkC1MQIGL+vVnXPWKKmFujZQIDAQABo4IBVDCCAVAwDAYDVR0TAQH/BAIwADAdBgNVHQ4EFgQUDYChmHcFHKp9We3S1+L8FensK8MwgaIGA1UdIwSBmjCBl4AU74Rd/7D+BlDnatCxleXbE8qHUP2hfKR6MHgxCzAJBgNVBAYTAlNFMRUwEwYDVQQIFAzWc3Rlcmf2dGxhbmQxEjAQBgNVBAcUCUxpbmv2cGluZzETMBEGA1UEChMKaGVtLnphLm9yZzELMAkGA1UEAxMCQ0ExHDAaBgkqhkiG9w0BCQEWDWNhQGhlbS56YS5vcmeCAQAwCwYDVR0PBAQDAgXgMBsGA1UdEQQUMBKCEHRlc3QyLmhlbS56YS5vcmcwEQYJYIZIAYb4QgEBBAQDAgZAMB8GCWCGSAGG+EIBDAQSFhB0ZXN0Mi5oZW0uemEub3JnMB4GCWCGSAGG+EIBDQQRFg94Y2EgY2VydGlmaWNhdGUwDQYJKoZIhvcNAQEEBQADggEBACBrYyeNsPjHflvyNOu+hPoz8nM8e5b8H45FtFWGb1QXOVCycqkltmZ3P68gDNjgXj7LhutruTMakWlwk2hg975eUlkgvBVlAltl9ZOYxivoBH6M35v5dMbLxFNH7jRc4ooTiJIv9OsjAZsmhKwcBOhnbIk6MIAyb1GFiFxLGHUwBke1UN1wOiztA6+unHf2bmcrHn9jdLa9F15wDPFmgt+1ArNiZMNHoJeCttlecqEJhDZ+0WgqB3ElDBEwwPl4DoCbxeIaNU6ukhodBfY3eahIZ71kOtlj0ULXO6gtfP7FxIzRIOJoS6LoV7dSfgCUSs9DXVZb2FOUgfMWlGPH2cEEALZUgRFhx/a5smawwhizV2CqnayhOxczwia5tnyDGQsF2WNfP6I1u73wo5c/Tk+cDrJpgGTrOyzV57kL+CxNvsBmYt7luvpdjy9dSg/RJ2b1+8E/41NI9r00r39RR5zo8evevLiQB6Zda/1AfPGy9R/Z9NVsBJmFyi4dX3/dYo2YOJ1hYOpJhmlGZsWnpv9K5LVpbu5xAUJQZ61hpUgPtdQZgeYTUHasUtvdyNEOhrk7NyTl1achzswQ+QHwPK0sPwABAMGb0SW1Z/C8O/lpt1ODu1Fda5wkCh/lsnY/Ugu+b4MHTOIWHJlVD8Co1udAawpnm3Log2uf2zXWIgtE/fTZFAdDENU3e5AnX5X0PIefwQVqtGMFJe1OXZoEHTbsYSGQhbbypqDwwg9Z8yPmpNW3qRi9DN42U/fZ7M8VEoALFe+w58eXca9o2rC0sO2AHscsDLENLQO+/HBZQrn6/bMf/SyfwNz+Ij1MFgKvORBEjFts3m8xrKAGFjnvLGV6AhARGlBh+XKE1RaZ7BHGSlD0yfvv0nz1ACOExG88ASLQkfDKrcJdx+/uKXh92Wp0hPHG8flFKm6ObE9vqiKUiy3CDEU= [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 5 [ 34]: m=audio 30460 RTP/SAVP 0 3 114 101 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 6 [ 20]: c=IN IP4 192.168.0.2 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 7 [ 22]: a=rtpmap:0 PCMU/8000/1 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 8 [ 19]: a=rtpmap:3 GSM/8000 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 9 [ 25]: a=rtpmap:114 speex/8000/1 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 10 [ 33]: a=rtpmap:101 telephone-event/8000 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-15 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 12 [ 30]: m=video 30630 RTP/SAVP 105 101 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 13 [ 20]: c=IN IP4 192.168.0.2 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Body 14 [ 28]: a=rtpmap:105 h263-1998/90000 [Dec 11 09:53:06] VERBOSE[8342] logger.c: --- (17 headers 15 lines) --- [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Setting NAT on RTP to On [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Setting NAT on VRTP to On [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Allocating new SIP dialog for 1251964339@192.168.0.2 - INVITE (With RTP) [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Begin: parsing SIP "Supported: 100rel, sdp-anat" [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Found SIP option: -100rel- [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Matched SIP option: 100rel [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Found SIP option: -sdp-anat- [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Matched SIP option: sdp-anat [Dec 11 09:53:06] VERBOSE[8342] logger.c: Sending to 192.168.0.2 : 5060 (NAT) [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Initializing initreq for method INVITE - callid 1251964339@192.168.0.2 [Dec 11 09:53:06] VERBOSE[8342] logger.c: Using INVITE request as basis request - 1251964339@192.168.0.2 [Dec 11 09:53:06] VERBOSE[8342] logger.c: Found user '2002' for '2002' [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Setting NAT on RTP to On [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Setting NAT on VRTP to On [Dec 11 09:53:06] VERBOSE[8342] logger.c: <--- Reliably Transmitting (NAT) to 192.168.0.2:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-xgdb8d6pv4oovkz+gcixlw-UAC-odzvcj8qj1m4u2jqitlijdw;received=192.168.0.2 Via: SIP/2.0/TCP skinner.hem.za.org:5090;branch=z9hG4bK-yxa-nxek_vqdjvb_3br941n1cg-UAC-og2ad1l2ihjz9oucgnf1tlw;received=[fdb1:a958:1911:0:202:44ff:fe1d:d021] Via: SIP/2.0/TCP 192.168.0.2:49760;branch=z9hG4bK1680707439;received=192.168.0.2;rport=49760 From: ;tag=1657306396 To: ;tag=as1c2f9c5d Call-ID: 1251964339@192.168.0.2 CSeq: 602 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="6337d787" Content-Length: 0 <------------> [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #9 [Dec 11 09:53:06] VERBOSE[8342] logger.c: Scheduling destruction of SIP dialog '1251964339@192.168.0.2' in 32000 ms (Method: INVITE) [Dec 11 09:53:06] VERBOSE[8342] logger.c: <--- SIP read from 192.168.0.2:5060 ---> ACK sip:1002@192.168.0.2:5068 SIP/2.0 Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-xgdb8d6pv4oovkz+gcixlw-UAC-odzvcj8qj1m4u2jqitlijdw From: ;tag=1657306396 To: ;tag=as1c2f9c5d Call-ID: 1251964339@192.168.0.2 CSeq: 602 ACK Content-Length: 0 <-------------> [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 0 [ 37]: ACK sip:1002@192.168.0.2:5068 SIP/2.0 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 1 [110]: Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-xgdb8d6pv4oovkz+gcixlw-UAC-odzvcj8qj1m4u2jqitlijdw [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 2 [ 50]: From: ;tag=1657306396 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 3 [ 46]: To: ;tag=as1c2f9c5d [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 4 [ 31]: Call-ID: 1251964339@192.168.0.2 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 5 [ 13]: CSeq: 602 ACK [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Header 6 [ 17]: Content-Length: 0 [Dec 11 09:53:06] VERBOSE[8342] logger.c: --- (7 headers 0 lines) --- [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #9 [Dec 11 09:53:06] DEBUG[8342] chan_sip.c: Stopping retransmission on '1251964339@192.168.0.2' of Response 602: Match Found [Dec 11 09:53:07] VERBOSE[8342] logger.c: <--- SIP read from 192.168.0.2:5060 ---> INVITE sip:1002@192.168.0.2:5068 SIP/2.0 Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-9+f8pzusjv5g6ia5ugx7zg-UAC-oxxfjrsomu_xdh7vt57g9eg Via: SIP/2.0/TCP skinner.hem.za.org:5090;branch=z9hG4bK-yxa-hsnbdh_eussw+l+e7kg8vw-UAC-odhrayskzblgw3m5vfbo8da;received=[fdb1:a958:1911:0:202:44ff:fe1d:d021] Via: SIP/2.0/TCP 192.168.0.2:49760;branch=z9hG4bK805170910;received=192.168.0.2;rport=49760 From: ;tag=1657306396 To: Call-ID: 1251964339@192.168.0.2 CSeq: 603 INVITE Record-Route: Record-Route: Max-Forwards: 68 Contact: ;expires=1000 User-Agent: Minisip Supported: 100rel, sdp-anat Proxy-Authorization: Digest algorithm=MD5,username="2002",realm="hem.za.org",nonce="671083db2b6c58a8d66179de30c6e54f",uri="sip:1002@192.168.0.2:5068",response="bd4a2593a1be57aa91851b5f8dfc1d4a",opaque="457d1c72" Authorization: Digest algorithm=MD5,username="2002",realm="asterisk",nonce="6337d787",uri="sip:1002@192.168.0.2:5068",response="91267d4470953cf08ea35dee00deda5d" Content-Type: application/sdp Content-Length: 3266 v=0 o=- 3344 3344 IN IP4 192.168.0.2 s=Minisip Session t=0 0 a=key-mgmt:mikey AQQFgEmmYrwMAAB/AMdMAAAAAAAAAAAAAAAAAAFt5g1zAAAAAAEAAAAAAAAAAAJ/AMdMAAAAAAIAAAAAAAAAAANt5g1zAAAAAAMAAAAAAAAAAAR/AMdMAAAAAAQAAAAAAAAAAAVt5g1zAAAAAAUAAAAAAAAAAAoAySeM4vbfUF0KAAAAJwABAQEBEAIBAQMBFAQBDgUBAAYBAAcBAQgBAQkBAAoBAQsBCgwBAAoBAAAnAAEBAQEQAgEBAwEUBAEOBQEABgEABwEBCAEBCQEACgEBCwEKDAEACgIAACcAAQEBARACAQEDARQEAQ4FAQAGAQAHAQEIAQEJAQAKAQELAQoMAQAKAwAAJwABAQEBEAIBAQMBFAQBDgUBAAYBAAcBAQgBAQkBAAoBAQsBCgwBAAoEAAAnAAEBAQEQAgEBAwEUBAEOBQEABgEABwEBCAEBCQEACgEBCwEKDAEACwUAACcAAQEBARACAQEDARQEAQ4FAQAGAQAHAQEIAQEJAQAKAQELAQoMAQAHEGeaRISYe9QLSsZsd5JpOgwDAgTvMIIE6zCCA9OgAwIBAgIBEDANBgkqhkiG9w0BAQQFADB4MQswCQYDVQQGEwJTRTEVMBMGA1UECBQM1nN0ZXJn9nRsYW5kMRIwEAYDVQQHFAlMaW5r9nBpbmcxEzARBgNVBAoTCmhlbS56YS5vcmcxCzAJBgNVBAMTAkNBMRwwGgYJKoZIhvcNAQkBFg1jYUBoZW0uemEub3JnMB4XDTA2MDYyNjEyNTAyN1oXDTA3MDYxNzEyNTAyN1owgaExCzAJBgNVBAYTAlNFMSEwHwYDVQQIHhgA1gBzAHQAZQByAGcA9gB0AGwAYQBuAGQxGzAZBgNVBAceEgBMAGkAbgBrAPYAcABpAG4AZzETMBEGA1UEChMKaGVtLnphLm9yZzEZMBcGA1UEAxMQdGVzdDIuaGVtLnphLm9yZzEiMCAGCSqGSIb3DQEJARYTc2VjdXJpdHlAaGVtLnphLm9yZzCCASIwDQYJKoZIhvcNAQEBBQADggEPADCCAQoCggEBANJnJZEzIwM3h64TGmLeroN3E5zUVtNk/nSMF1NxKCysk3dBAnfQOMLW3gWW/V6GGrnKq51wI3naPcJECgf84YedAscP3Zl1Cjd1eI2HuJNoEdOOJNBY5Lqig20kEnfMbqh0AtfU++TTbyHbeckUqAn9iWw1xlLJErcuuJ6FJcF+FrRIOoewDzzAUZ9vaNmx4S+BtPYsLHxELcf3UDjWDWbI4tebKOXK+mXwxLsO5ILtCHq+oVS9B0GoVj/aVPwbF4EEOJbh51M9gE9egkJm+1YFghGhJCriGdV8vlI5avGmtltzHqcRD4bTW7ucmT7WJAtTECBi/r1Z1z1iiphbo2UCAwEAAaOCAVQwggFQMAwGA1UdEwEB/wQCMAAwHQYDVR0OBBYEFA2AoZh3BRyqfVnt0tfi/BXp7CvDMIGiBgNVHSMEgZowgZeAFO+EXf+w/gZQ52rQsZXl2xPKh1D9oXykejB4MQswCQYDVQQGEwJTRTEVMBMGA1UECBQM1nN0ZXJn9nRsYW5kMRIwEAYDVQQHFAlMaW5r9nBpbmcxEzARBgNVBAoTCmhlbS56YS5vcmcxCzAJBgNVBAMTAkNBMRwwGgYJKoZIhvcNAQkBFg1jYUBoZW0uemEub3JnggEAMAsGA1UdDwQEAwIF4DAbBgNVHREEFDASghB0ZXN0Mi5oZW0uemEub3JnMBEGCWCGSAGG+EIBAQQEAwIGQDAfBglghkgBhvhCAQwEEhYQdGVzdDIuaGVtLnphLm9yZzAeBglghkgBhvhCAQ0EERYPeGNhIGNlcnRpZmljYXRlMA0GCSqGSIb3DQEBBAUAA4IBAQAga2MnjbD4x35b8jTrvoT6M/JzPHuW/B+ORbRVhm9UFzlQsnKpJbZmdz+vIAzY4F4+y4bra7kzGpFpcJNoYPe+XlJZILwVZQJbZfWTmMYr6AR+jN+b+XTGy8RTR+40XOKKE4iSL/TrIwGbJoSsHAToZ2yJOjCAMm9RhYhcSxh1MAZHtVDdcDos7QOvrpx39m5nKx5/Y3S2vRdecAzxZoLftQKzYmTDR6CXgrbZXnKhCYQ2ftFoKgdxJQwRMMD5eA6Am8XiGjVOrpIaHQX2N3moSGe9ZDrZY9FC1zuoLXz+xcSM0SDiaEui6Fe3Un4AlErPQ11WW9hTlIHzFpRjx9nBBAC2VIERYcf2ubJmsMIYs1dgqp2soTsXM8ImubZ8gxkLBdljXz+iNbu98KOXP05PnA6yaYBk6zss1ee5C/gsTb7AZmLe5br6XY8vXUoP0Sdm9fvBP+NTSPa9NK9/UUec6PHr3ry4kAemXWv9QHzxsvUf2fTVbASZhcouHV9/3WKNmDidYWDqSYZpRmbFp6b/SuS1aW7ucQFCUGetYaVID7XUGYHmE1B2rFLb3cjRDoa5Ozck5dWnIc7MEPkB8DytLD8AAQCY7WgP5Cz325lX047LYPfHsu7EvufGBoZrMsvsczm6Q0WsWuhUYeMBzKP3FqlAkIySn4j/J5CSN5dteLwTmjIoYLht27fUsdQG1PR3TFpGtf+SoWi4kNYVw+3lMYbNzTxMwdj9sf66wyfadADT0s2Gdy7gs3t2Eh9cnrSH1436ZMRYqdm/6HvAjrzEagg3nd2/VCvsKhJbzKOtv6+ATYWa7f7H8n+3i8gAYV/MR7rCtNNP2H2ci+8sdiJvCDechWLhs99I3QDZl7ZqHVnLHGFbntOhrhBg2axHLyjjDUlq2bIRKBdeV <-------------> [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 0 [ 40]: INVITE sip:1002@192.168.0.2:5068 SIP/2.0 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 1 [110]: Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-9+f8pzusjv5g6ia5ugx7zg-UAC-oxxfjrsomu_xdh7vt57g9eg [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 2 [157]: Via: SIP/2.0/TCP skinner.hem.za.org:5090;branch=z9hG4bK-yxa-hsnbdh_eussw+l+e7kg8vw-UAC-odhrayskzblgw3m5vfbo8da;received=[fdb1:a958:1911:0:202:44ff:fe1d:d021] [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 3 [ 91]: Via: SIP/2.0/TCP 192.168.0.2:49760;branch=z9hG4bK805170910;received=192.168.0.2;rport=49760 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 4 [ 50]: From: ;tag=1657306396 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 5 [ 31]: To: [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 6 [ 31]: Call-ID: 1251964339@192.168.0.2 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 7 [ 16]: CSeq: 603 INVITE [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 8 [ 46]: Record-Route: [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 9 [ 51]: Record-Route: [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 10 [ 16]: Max-Forwards: 68 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 11 [ 64]: Contact: ;expires=1000 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 12 [ 19]: User-Agent: Minisip [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 13 [ 27]: Supported: 100rel, sdp-anat [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 14 [211]: Proxy-Authorization: Digest algorithm=MD5,username="2002",realm="hem.za.org",nonce="671083db2b6c58a8d66179de30c6e54f",uri="sip:1002@192.168.0.2:5068",response="bd4a2593a1be57aa91851b5f8dfc1d4a",opaque="457d1c72" [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 15 [161]: Authorization: Digest algorithm=MD5,username="2002",realm="asterisk",nonce="6337d787",uri="sip:1002@192.168.0.2:5068",response="91267d4470953cf08ea35dee00deda5d" [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 16 [ 29]: Content-Type: application/sdp [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 17 [ 20]: Content-Length: 3266 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 18 [ 0]: [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Body 0 [ 3]: v=0 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Body 1 [ 32]: o=- 3344 3344 IN IP4 192.168.0.2 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Body 2 [ 17]: s=Minisip Session [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Body 3 [ 5]: t=0 0 [Dec 11 09:53:07] VERBOSE[8342] logger.c: --- (18 headers 4 lines) --- [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 11 09:53:07] VERBOSE[8342] logger.c: Sending to 192.168.0.2 : 5060 (NAT) [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Initializing initreq for method INVITE - callid 1251964339@192.168.0.2 [Dec 11 09:53:07] VERBOSE[8342] logger.c: Using INVITE request as basis request - 1251964339@192.168.0.2 [Dec 11 09:53:07] VERBOSE[8342] logger.c: Found user '2002' for '2002' [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Setting NAT on RTP to On [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Setting NAT on VRTP to On [Dec 11 09:53:07] WARNING[8342] chan_sip.c: Insufficient information for SDP (m = '', c = '') [Dec 11 09:53:07] VERBOSE[8342] logger.c: <--- Reliably Transmitting (NAT) to 192.168.0.2:5060 ---> SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-9+f8pzusjv5g6ia5ugx7zg-UAC-oxxfjrsomu_xdh7vt57g9eg;received=192.168.0.2 Via: SIP/2.0/TCP skinner.hem.za.org:5090;branch=z9hG4bK-yxa-hsnbdh_eussw+l+e7kg8vw-UAC-odhrayskzblgw3m5vfbo8da;received=[fdb1:a958:1911:0:202:44ff:fe1d:d021] Via: SIP/2.0/TCP 192.168.0.2:49760;branch=z9hG4bK805170910;received=192.168.0.2;rport=49760 From: ;tag=1657306396 To: ;tag=as1c2f9c5d Call-ID: 1251964339@192.168.0.2 CSeq: 603 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #11 [Dec 11 09:53:07] VERBOSE[8342] logger.c: Scheduling destruction of SIP dialog '1251964339@192.168.0.2' in 32000 ms (Method: INVITE) [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: No compatible codecs for this SIP call. [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: SIP message could not be handled, bad request: 1251964339@192.168.0.2 [Dec 11 09:53:07] VERBOSE[8342] logger.c: <--- SIP read from 192.168.0.2:5060 ---> ACK sip:1002@192.168.0.2:5068 SIP/2.0 Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-9+f8pzusjv5g6ia5ugx7zg-UAC-oxxfjrsomu_xdh7vt57g9eg From: ;tag=1657306396 To: ;tag=as1c2f9c5d Call-ID: 1251964339@192.168.0.2 CSeq: 603 ACK Content-Length: 0 <-------------> [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 0 [ 37]: ACK sip:1002@192.168.0.2:5068 SIP/2.0 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 1 [110]: Via: SIP/2.0/UDP skinner.hem.za.org:5060;branch=z9hG4bK-yxa-9+f8pzusjv5g6ia5ugx7zg-UAC-oxxfjrsomu_xdh7vt57g9eg [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 2 [ 50]: From: ;tag=1657306396 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 3 [ 46]: To: ;tag=as1c2f9c5d [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 4 [ 31]: Call-ID: 1251964339@192.168.0.2 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 5 [ 13]: CSeq: 603 ACK [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Header 6 [ 17]: Content-Length: 0 [Dec 11 09:53:07] VERBOSE[8342] logger.c: --- (7 headers 0 lines) --- [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #11 [Dec 11 09:53:07] DEBUG[8342] chan_sip.c: Stopping retransmission on '1251964339@192.168.0.2' of Response 603: Match Found [Dec 11 09:53:39] DEBUG[8342] chan_sip.c: Auto destroying SIP dialog '1251964339@192.168.0.2' [Dec 11 09:53:39] DEBUG[8342] chan_sip.c: Destroying SIP dialog 1251964339@192.168.0.2 [Dec 11 09:53:39] VERBOSE[8342] logger.c: Really destroying SIP dialog '1251964339@192.168.0.2' Method: ACK