[Apr 2 19:29:40] VERBOSE[15700][C-000003cc] pbx.c: -- Executing [+61892006061@perth-sip:4] Dial("SIP/internal-pbx-00000724", "SIP/61892006061@virtutel-mel") in new stack [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] netsock2.c: == Using SIP RTP CoS mark 5 [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] chan_sip.c: Audio is at 18928 [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] chan_sip.c: Adding codec 100004 (alaw) to SDP [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] chan_sip.c: Reliably Transmitting (no NAT) to 125.254.48.7:5060: INVITE sip:61892006061@125.254.48.7:5060 SIP/2.0 Via: SIP/2.0/UDP 110.173.224.97:5060;branch=z9hG4bK34cc4915 Max-Forwards: 70 From: ;tag=as022f3fcb To: Contact: Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 102 INVITE User-Agent: Asterisk PBX 11.7.0~dfsg-1ubuntu1 Date: Tue, 02 Apr 2019 11:29:40 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Type: application/sdp Content-Length: 253 v=0 o=root 1105185868 1105185868 IN IP4 110.173.224.97 s=Asterisk PBX 11.7.0~dfsg-1ubuntu1 c=IN IP4 110.173.224.97 t=0 0 m=audio 18928 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv --- [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] app_dial.c: -- Called SIP/61892006061@virtutel-mel [Apr 2 19:29:40] VERBOSE[29682] chan_sip.c: <--- SIP read from UDP:125.254.48.7:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 110.173.224.97:5060;branch=z9hG4bK34cc4915;received=110.173.224.97 From: ;tag=as022f3fcb To: Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 102 INVITE Server: VirtutelDevel-AST Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <-------------> [Apr 2 19:29:40] VERBOSE[29682] chan_sip.c: --- (12 headers 0 lines) --- [Apr 2 19:29:40] VERBOSE[29682] chan_sip.c: <--- SIP read from UDP:125.254.48.7:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 110.173.224.97:5060;branch=z9hG4bK34cc4915;received=110.173.224.97 From: ;tag=as022f3fcb To: ;tag=as42ac2d9f Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 102 INVITE Server: VirtutelDevel-AST Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 242 v=0 o=VirtutelDevel 1550880079 1550880079 IN IP4 125.254.48.7 s=VirtutelDevel-AST c=IN IP4 125.254.48.7 t=0 0 m=audio 11738 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <-------------> [Apr 2 19:29:40] VERBOSE[29682] chan_sip.c: --- (14 headers 11 lines) --- [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: list_route: hop: [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: Found RTP audio format 8 [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: Found RTP audio format 101 [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: Found audio description format PCMA for ID 8 [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: Found audio description format telephone-event for ID 101 [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: Capabilities: us - (alaw), peer - audio=(alaw)/video=(nothing)/text=(nothing), combined - (alaw) [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: Peer audio RTP is at port 125.254.48.7:11738 [Apr 2 19:29:40] VERBOSE[29682] chan_sip.c: <--- SIP read from UDP:125.254.48.7:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 110.173.224.97:5060;branch=z9hG4bK34cc4915;received=110.173.224.97 From: ;tag=as022f3fcb To: ;tag=as42ac2d9f Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 102 INVITE Server: VirtutelDevel-AST Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 242 v=0 o=VirtutelDevel 1550880079 1550880079 IN IP4 125.254.48.7 s=VirtutelDevel-AST c=IN IP4 125.254.48.7 t=0 0 m=audio 11738 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <-------------> [Apr 2 19:29:40] VERBOSE[29682] chan_sip.c: --- (14 headers 11 lines) --- [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: list_route: hop: [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: set_destination: Parsing for address/port to send to [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: set_destination: set destination to 125.254.48.7:5060 [Apr 2 19:29:40] VERBOSE[29682][C-000003cc] chan_sip.c: Transmitting (no NAT) to 125.254.48.7:5060: ACK sip:61892006061@125.254.48.7:5060 SIP/2.0 Via: SIP/2.0/UDP 110.173.224.97:5060;branch=z9hG4bK3a3bcb02 Max-Forwards: 70 From: ;tag=as022f3fcb To: ;tag=as42ac2d9f Contact: Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 102 ACK User-Agent: Asterisk PBX 11.7.0~dfsg-1ubuntu1 Content-Length: 0 --- [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] app_dial.c: -- SIP/virtutel-mel-00000725 is making progress passing it to SIP/internal-pbx-00000724 [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] app_dial.c: -- SIP/virtutel-mel-00000725 answered SIP/internal-pbx-00000724 [Apr 2 19:29:40] VERBOSE[15700][C-000003cc] rtp_engine.c: -- Locally bridging SIP/internal-pbx-00000724 and SIP/virtutel-mel-00000725 [Apr 2 19:29:43] VERBOSE[29682] chan_sip.c: <--- SIP read from UDP:125.254.48.7:5060 ---> INVITE sip:61894810366@110.173.224.97:5060 SIP/2.0 Via: SIP/2.0/UDP 125.254.48.7:5060;branch=z9hG4bK48989cac Max-Forwards: 70 From: ;tag=as42ac2d9f To: ;tag=as022f3fcb Contact: Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 102 INVITE User-Agent: VirtutelDevel-AST Session-Expires: 1800;refresher=uac Min-SE: 90 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Type: application/sdp Content-Length: 278 v=0 o=VirtutelDevel 1550880079 1550880080 IN IP4 125.254.48.7 s=VirtutelDevel-AST c=IN IP4 125.254.48.7 t=0 0 m=image 19435 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxDatagram:393 a=T38FaxUdpEC:t38UDPRedundancy <-------------> [Apr 2 19:29:43] VERBOSE[29682] chan_sip.c: --- (15 headers 11 lines) --- [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Sending to 125.254.48.7:5060 (no NAT) [Apr 2 19:29:43] WARNING[29682][C-000003cc] chan_sip.c: Failed to initialize UDPTL, declining image stream [Apr 2 19:29:43] WARNING[29682][C-000003cc] chan_sip.c: Failing due to no acceptable offer found [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: <--- Reliably Transmitting (no NAT) to 125.254.48.7:5060 ---> SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP 125.254.48.7:5060;branch=z9hG4bK48989cac;received=125.254.48.7 From: ;tag=as42ac2d9f To: ;tag=as022f3fcb Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 102 INVITE Server: Asterisk PBX 11.7.0~dfsg-1ubuntu1 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Session-Expires: 1800;refresher=uac X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 <------------> [Apr 2 19:29:43] VERBOSE[29682] chan_sip.c: <--- SIP read from UDP:125.254.48.7:5060 ---> ACK sip:61894810366@110.173.224.97:5060 SIP/2.0 Via: SIP/2.0/UDP 125.254.48.7:5060;branch=z9hG4bK48989cac Max-Forwards: 70 From: ;tag=as42ac2d9f To: ;tag=as022f3fcb Contact: Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 102 ACK User-Agent: VirtutelDevel-AST Content-Length: 0 <-------------> [Apr 2 19:29:43] VERBOSE[29682] chan_sip.c: --- (10 headers 0 lines) --- [Apr 2 19:29:43] VERBOSE[29682] chan_sip.c: <--- SIP read from UDP:125.254.48.7:5060 ---> INVITE sip:61894810366@110.173.224.97:5060 SIP/2.0 Via: SIP/2.0/UDP 125.254.48.7:5060;branch=z9hG4bK2cd3f413 Max-Forwards: 70 From: ;tag=as42ac2d9f To: ;tag=as022f3fcb Contact: Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 103 INVITE User-Agent: VirtutelDevel-AST Session-Expires: 1800;refresher=uac Min-SE: 90 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Type: application/sdp Content-Length: 242 v=0 o=VirtutelDevel 1550880079 1550880081 IN IP4 125.254.48.7 s=VirtutelDevel-AST c=IN IP4 125.254.48.7 t=0 0 m=audio 11738 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <-------------> [Apr 2 19:29:43] VERBOSE[29682] chan_sip.c: --- (15 headers 11 lines) --- [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Sending to 125.254.48.7:5060 (no NAT) [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Found RTP audio format 8 [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Found RTP audio format 101 [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Found audio description format PCMA for ID 8 [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Found audio description format telephone-event for ID 101 [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Capabilities: us - (alaw), peer - audio=(alaw)/video=(nothing)/text=(nothing), combined - (alaw) [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Peer audio RTP is at port 125.254.48.7:11738 [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: <--- Transmitting (no NAT) to 125.254.48.7:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 125.254.48.7:5060;branch=z9hG4bK2cd3f413;received=125.254.48.7 From: ;tag=as42ac2d9f To: ;tag=as022f3fcb Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 103 INVITE Server: Asterisk PBX 11.7.0~dfsg-1ubuntu1 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Session-Expires: 1800;refresher=uac Contact: Content-Length: 0 <------------> [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Audio is at 18928 [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Adding codec 100004 (alaw) to SDP [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Apr 2 19:29:43] VERBOSE[29682][C-000003cc] chan_sip.c: <--- Reliably Transmitting (no NAT) to 125.254.48.7:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 125.254.48.7:5060;branch=z9hG4bK2cd3f413;received=125.254.48.7 From: ;tag=as42ac2d9f To: ;tag=as022f3fcb Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 103 INVITE Server: Asterisk PBX 11.7.0~dfsg-1ubuntu1 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Session-Expires: 1800;refresher=uac Contact: Content-Type: application/sdp Require: timer Content-Length: 253 v=0 o=root 1105185868 1105185869 IN IP4 110.173.224.97 s=Asterisk PBX 11.7.0~dfsg-1ubuntu1 c=IN IP4 110.173.224.97 t=0 0 m=audio 18928 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <------------> [Apr 2 19:29:43] VERBOSE[29682] chan_sip.c: <--- SIP read from UDP:125.254.48.7:5060 ---> ACK sip:61894810366@110.173.224.97:5060 SIP/2.0 Via: SIP/2.0/UDP 125.254.48.7:5060;branch=z9hG4bK2cde55fd Max-Forwards: 70 From: ;tag=as42ac2d9f To: ;tag=as022f3fcb Contact: Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 103 ACK User-Agent: VirtutelDevel-AST Content-Length: 0 <-------------> [Apr 2 19:29:43] VERBOSE[29682] chan_sip.c: --- (10 headers 0 lines) --- [Apr 2 19:29:47] VERBOSE[15700][C-000003cc] chan_sip.c: Scheduling destruction of SIP dialog '6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au' in 6400 ms (Method: ACK) [Apr 2 19:29:47] VERBOSE[15700][C-000003cc] chan_sip.c: set_destination: Parsing for address/port to send to [Apr 2 19:29:47] VERBOSE[15700][C-000003cc] chan_sip.c: set_destination: set destination to 125.254.48.7:5060 [Apr 2 19:29:47] VERBOSE[15700][C-000003cc] chan_sip.c: Reliably Transmitting (no NAT) to 125.254.48.7:5060: BYE sip:61892006061@125.254.48.7:5060 SIP/2.0 Via: SIP/2.0/UDP 110.173.224.97:5060;branch=z9hG4bK19ae5234 Max-Forwards: 70 From: ;tag=as022f3fcb To: ;tag=as42ac2d9f Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 103 BYE User-Agent: Asterisk PBX 11.7.0~dfsg-1ubuntu1 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 --- [Apr 2 19:29:47] VERBOSE[15700][C-000003cc] pbx.c: == Spawn extension (perth-sip, +61892006061, 4) exited non-zero on 'SIP/internal-pbx-00000724' [Apr 2 19:29:47] VERBOSE[29682] chan_sip.c: <--- SIP read from UDP:125.254.48.7:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 110.173.224.97:5060;branch=z9hG4bK19ae5234;received=110.173.224.97 From: ;tag=as022f3fcb To: ;tag=as42ac2d9f Call-ID: 6995d65605b6fb8a06a9a2d4547661c8@sip.virtutel.com.au CSeq: 103 BYE Server: VirtutelDevel-AST Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0