-- Called PJSIP/012345678910@012345678911 [2017-09-08 16:58:25] DEBUG[5346][C-00000001]: channel.c:5549 set_format: Channel PJSIP/012345678911-00000004 setting read format path: g722 -> g722 [2017-09-08 16:58:25] DEBUG[5346][C-00000001]: channel.c:5549 set_format: Channel Local/012345678910@from-internal-00000005;2 setting write format path: g722 -> g722 [2017-09-08 16:58:25] DEBUG[5346][C-00000001]: channel.c:5549 set_format: Channel Local/012345678910@from-internal-00000005;2 setting read format path: g722 -> g722 [2017-09-08 16:58:25] DEBUG[5346][C-00000001]: channel.c:5549 set_format: Channel PJSIP/012345678911-00000004 setting write format path: g722 -> g722 [2017-09-08 16:58:25] DEBUG[4700]: res_pjsip_sdp_rtp.c:187 create_rtp: Transport 0.0.0.0-udp bound to 0.0.0.0: Using it for RTP media. [2017-09-08 16:58:25] DEBUG[4700]: rtp_engine.c:471 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x131d008' [2017-09-08 16:58:25] DEBUG[4700]: res_rtp_asterisk.c:3142 rtp_allocate_transport: Allocated port 18280 for RTP instance '0x131d008' [2017-09-08 16:58:25] DEBUG[4700]: rtp_engine.c:488 ast_rtp_instance_new: RTP instance '0x131d008' is setup and ready to go [2017-09-08 16:58:25] DEBUG[4700]: res_rtp_asterisk.c:5805 ast_rtp_prop_set: Setup RTCP on RTP instance '0x131d008' -- Connected line update to Local/2002@from-queue-00000001;2 prevented. <--- Transmitting SIP request (920 bytes) to UDP:217.0.23.132:5060 ---> INVITE sip:012345678910@tel.t-online.de:5060 SIP/2.0 Via: SIP/2.0/UDP 91.33.123.456:5060;rport;branch=z9hG4bKPj83a8c949-4b51-4b84-986b-0fc1923d7796 From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 To: Contact: Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10765 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Max-Forwards: 70 User-Agent: FPBX-14.0.1.4(14) Content-Type: application/sdp Content-Length: 233 v=0 o=- 695602536 695602536 IN IP4 10.1.2.3 s=Asterisk c=IN IP4 91.33.123.456 t=0 0 m=audio 18280 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv <--- Received SIP response (567 bytes) from UDP:217.0.23.132:5060 ---> SIP/2.0 407 Proxy Authentication Required 02035034C Via: SIP/2.0/UDP 91.33.123.456:5060;received=91.33.123.456;rport=5060;branch=z9hG4bKPj83a8c949-4b51-4b84-986b-0fc1923d7796 To: ;tag=h7g4Esbg_00e4a9e50f80732c40cb6e68b7f71920 From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10765 INVITE Content-Length: 0 Proxy-Authenticate: Digest nonce="28C700831DB0B2600000000040950E0C",realm="tel.t-online.de",algorithm=MD5,qop="auth",stale=true <--- Transmitting SIP request (454 bytes) to UDP:217.0.23.132:5060 ---> ACK sip:012345678910@tel.t-online.de:5060 SIP/2.0 Via: SIP/2.0/UDP 91.33.123.456:5060;rport;branch=z9hG4bKPj83a8c949-4b51-4b84-986b-0fc1923d7796 From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 To: ;tag=h7g4Esbg_00e4a9e50f80732c40cb6e68b7f71920 Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10765 ACK Max-Forwards: 70 User-Agent: FPBX-14.0.1.4(14) Content-Length: 0 [2017-09-08 16:58:25] DEBUG[4700]: res_pjsip_session.c:2256 outbound_invite_auth: Initial INVITE is being challenged. <--- Transmitting SIP request (1214 bytes) to UDP:217.0.23.132:5060 ---> INVITE sip:012345678910@tel.t-online.de:5060 SIP/2.0 Via: SIP/2.0/UDP 91.33.123.456:5060;rport;branch=z9hG4bKPj092fb743-ee8f-49b0-a448-715e730b767c From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 To: Contact: Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10766 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Max-Forwards: 70 User-Agent: FPBX-14.0.1.4(14) Proxy-Authorization: Digest username="012345678911", realm="tel.t-online.de", nonce="28C700831DB0B2600000000040950E0C", uri="sip:012345678910@tel.t-online.de:5060", response="1242bcf972a4b99aada2730a70935b2d", algorithm=MD5, cnonce="f2654017-8758-4b2f-8e51-13fda1350c82", qop=auth, nc=00000001 Content-Type: application/sdp Content-Length: 233 v=0 o=- 695602536 695602536 IN IP4 10.1.2.3 s=Asterisk c=IN IP4 91.33.123.456 t=0 0 m=audio 18280 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv <--- Received SIP response (359 bytes) from UDP:217.0.23.132:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 91.33.123.456:5060;received=91.33.123.456;rport=5060;branch=z9hG4bKPj092fb743-ee8f-49b0-a448-715e730b767c To: From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10766 INVITE Content-Length: 0 [2017-09-08 16:58:26] DEBUG[4971][C-00000001]: res_rtp_asterisk.c:4786 ast_rtcp_interpret: Got RTCP report of 84 bytes <--- Received SIP response (669 bytes) from UDP:217.0.23.132:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 91.33.123.456:5060;received=91.33.123.456;rport=5060;branch=z9hG4bKPj092fb743-ee8f-49b0-a448-715e730b767c To: ;tag=h7g4Esbg_p65543t1504882705m812824c352549895s1_3474707275-997376043 From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10766 INVITE Contact: Record-Route: Supported: timer Content-Length: 0 Allow: UPDATE, REFER, PRACK, OPTIONS, BYE, ACK, CANCEL, INVITE, REGISTER Alert-Info: -- PJSIP/012345678911-00000004 is ringing -- Local/012345678910@from-internal-00000005;1 is ringing -- Local/RG-2002-012345678910#@from-internal-00000004;1 is ringing <--- Transmitting SIP request (463 bytes) to UDP:217.0.23.132:5060 ---> OPTIONS sip:012345678912@tel.t-online.de:5060 SIP/2.0 Via: SIP/2.0/UDP 91.33.123.456:5060;rport;branch=z9hG4bKPjd9a7f553-043a-4c14-a3ee-27051d00dffe From: ;tag=b02416e6-d20f-4ffd-aad5-d06bf8d2092c To: Contact: Call-ID: 4a59b89d-a9a8-4ec1-88ff-1bf85ec84e64 CSeq: 52227 OPTIONS Max-Forwards: 70 User-Agent: FPBX-14.0.1.4(14) Content-Length: 0 <--- Received SIP response (479 bytes) from UDP:217.0.23.132:5060 ---> SIP/2.0 200 In Ordnung Via: SIP/2.0/UDP 91.33.123.456:5060;received=91.33.123.456;rport=5060;branch=z9hG4bKPjd9a7f553-043a-4c14-a3ee-27051d00dffe To: ;tag=h7g4Esbg_whhqpoosxnc74erfijwr5g5yh1y41zzd From: ;tag=b02416e6-d20f-4ffd-aad5-d06bf8d2092c Call-ID: 4a59b89d-a9a8-4ec1-88ff-1bf85ec84e64 CSeq: 52227 OPTIONS Content-Length: 0 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,UPDATE,REFER -- Contact 012345678912/sip:012345678912@tel.t-online.de:5060 is now Reachable. RTT: 17.050 msec == Endpoint 012345678912 is now Reachable <--- Received SIP response (639 bytes) from UDP:217.0.23.132:5060 ---> SIP/2.0 181 Call Is Being Forwarded Via: SIP/2.0/UDP 91.33.123.456:5060;received=91.33.123.456;rport=5060;branch=z9hG4bKPj092fb743-ee8f-49b0-a448-715e730b767c To: ;tag=h7g4Esbg_p65543t1504882705m812824c352549895s1_3474707275-997376043 From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10766 INVITE Contact: Record-Route: Supported: timer Content-Length: 0 Allow: UPDATE, REFER, PRACK, OPTIONS, BYE, ACK, CANCEL, INVITE, REGISTER <--- Received SIP response (932 bytes) from UDP:217.0.23.132:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 91.33.123.456:5060;received=91.33.123.456;rport=5060;branch=z9hG4bKPj092fb743-ee8f-49b0-a448-715e730b767c To: ;tag=h7g4Esbg_p65543t1504882705m812824c352549895s1_3474707275-997376043 From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10766 INVITE Contact: Record-Route: Require: 100rel RSeq: 2 Supported: timer Content-Type: application/sdp Content-Length: 216 Allow: REGISTER, REFER, NOTIFY, SUBSCRIBE, INFO, PRACK, UPDATE, INVITE, ACK, OPTIONS, CANCEL, BYE v=0 o=- 1892629071 3477640225 IN IP4 217.0.23.132 s=- c=IN IP4 217.0.5.68 t=0 0 m=audio 51600 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=ptime:20 [2017-09-08 16:58:28] DEBUG[4700]: res_pjsip_session.c:710 handle_negotiated_sdp_session_media: Applying negotiated SDP media stream 'audio' using audio SDP handler [2017-09-08 16:58:28] DEBUG[4700]: res_rtp_asterisk.c:5704 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0x131d008' [2017-09-08 16:58:28] DEBUG[4700]: res_rtp_asterisk.c:5871 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x131d008' [2017-09-08 16:58:28] DEBUG[4700]: rtp_engine.c:1032 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 9 based on m type on 0x7fdbcc2ddf20 [2017-09-08 16:58:28] DEBUG[4700]: rtp_engine.c:1032 ast_rtp_codecs_payloads_set_m_type: Setting tx payload type 101 based on m type on 0x7fdbcc2ddf20 [2017-09-08 16:58:28] DEBUG[4700]: channel.c:5549 set_format: Channel PJSIP/012345678911-00000004 setting read format path: g722 -> g722 [2017-09-08 16:58:28] DEBUG[4700]: channel.c:5549 set_format: Channel PJSIP/012345678911-00000004 setting write format path: g722 -> g722 [2017-09-08 16:58:28] DEBUG[4700]: res_pjsip_session.c:715 handle_negotiated_sdp_session_media: Applied negotiated SDP media stream 'audio' using audio SDP handler <--- Transmitting SIP request (548 bytes) to UDP:217.0.23.132:5060 ---> PRACK sip:sgc_c@217.0.23.132;transport=udp SIP/2.0 Via: SIP/2.0/UDP 91.33.123.456:5060;rport;branch=z9hG4bKPj009be255-5335-4fe1-a98f-30917aafbb61 From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 To: ;tag=h7g4Esbg_p65543t1504882705m812824c352549895s1_3474707275-997376043 Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10767 PRACK Route: RAck: 2 10766 INVITE Max-Forwards: 70 User-Agent: FPBX-14.0.1.4(14) Content-Length: 0 -- PJSIP/012345678911-00000004 is making progress passing it to Local/012345678910@from-internal-00000005;2 -- Local/012345678910@from-internal-00000005;1 is making progress passing it to Local/RG-2002-012345678910#@from-internal-00000004;2 <--- Received SIP response (630 bytes) from UDP:217.0.23.132:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 91.33.123.456:5060;received=91.33.123.456;rport=5060;branch=z9hG4bKPj009be255-5335-4fe1-a98f-30917aafbb61 To: ;tag=h7g4Esbg_p65543t1504882705m812824c352549895s1_3474707275-997376043 From: ;tag=e975a90c-d2ad-4746-a3c3-c831a84f3073 Call-ID: 0a003aa4-0c1b-4ddb-9ed0-67a62881e097 CSeq: 10767 PRACK Supported: timer Supported: 100rel Supported: histinfo Supported: norefersub Supported: precondition Content-Length: 0 Allow: REGISTER, REFER, NOTIFY, SUBSCRIBE, INFO, PRACK, UPDATE, INVITE, ACK, OPTIONS, CANCEL, BYE [2017-09-08 16:58:28] DEBUG[4700]: res_pjsip_session.c:3494 session_inv_on_tsx_state_changed: PRACK received final response code 200 asterisk*CLI> Disconnected from Asterisk server Asterisk cleanly ending (0). Executing last minute cleanups