Created
May 14, 2021 11:57
-
-
Save andrius/a680f27fd8c36ca48439eda058bd8d5a to your computer and use it in GitHub Desktop.
Revisions
-
andrius created this gist
May 14, 2021 .There are no files selected for viewing
This file contains hidden or bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters. Learn more about bidirectional Unicode charactersOriginal file line number Diff line number Diff line change @@ -0,0 +1,684 @@ freeswitch@dev-voice> error: tport_logging: capture socket error: No error information 2021-05-14 11:49:22.883128 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [e2a313f6-36a4-4c4f-b246-6663dbe6772f] 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Running State Change CS_NEW (Cur 1 Tot 24) 2021-05-14 11:49:22.883128 [DEBUG] sofia.c:10279 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io receiving invite from 172.18.0.2:41880 version: 1.10.3 -release 64bit 2021-05-14 11:49:22.883128 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 172.18.0.2:0. 2021-05-14 11:49:22.883128 [DEBUG] sofia.c:10402 IP 172.18.0.2 Approved by acl "domains[]". Access Granted. 2021-05-14 11:49:22.883128 [DEBUG] sofia.c:11476 Setting NAT mode based on via received 2021-05-14 11:49:22.883128 [DEBUG] sofia.c:7325 Channel sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io entering state [received][100] 2021-05-14 11:49:22.883128 [DEBUG] sofia.c:7335 Remote SDP: v=0 o=- 5556665221804708149 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE 0 a=extmap-allow-mixed a=msid-semantic: WMS zdwT5yOWtU8z0zZPjXaVsPxMLuRF71NQFfMi m=audio 46512 UDP/TLS/RTP/SAVPF 111 103 9 0 8 105 13 110 113 126 c=IN IP4 89.216.102.123 a=rtpmap:111 opus/48000/2 a=fmtp:111 minptime=10;useinbandfec=1 a=rtpmap:103 ISAC/16000 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:110 telephone-event/48000 a=rtpmap:113 telephone-event/16000 a=rtpmap:126 telephone-event/8000 a=rtcp:37888 IN IP4 89.216.102.123 a=candidate:3836366835 1 udp 2113937151 192.168.15.143 46512 typ host generation 0 network-cost 999 a=candidate:3836366835 2 udp 2113937150 192.168.15.143 37888 typ host generation 0 network-cost 999 a=candidate:842163049 1 udp 1677729535 89.216.102.123 46512 typ srflx raddr 192.168.15.143 rport 46512 generation 0 network-cost 999 a=candidate:842163049 2 udp 1677729534 89.216.102.123 37888 typ srflx raddr 192.168.15.143 rport 37888 generation 0 network-cost 999 a=ice-ufrag:X1XX a=ice-pwd:zzpsg1w8JABIBEwi/XN9frj+ a=ice-options:trickle a=fingerprint:sha-256 34:9B:4C:32:79:D3:03:78:17:16:B8:D2:7F:32:63:1A:20:1E:7A:5D:70:D6:03:7D:7A:95:38:F2:EC:D9:0D:C0 a=setup:actpass a=mid:0 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id a=msid:zdwT5yOWtU8z0zZPjXaVsPxMLuRF71NQFfMi acb603a5-0bbf-4c69-8cdd-8807579c6afd a=rtcp-mux a=rtcp-fb:111 transport-cc a=ssrc:771062369 cname:SrGzb+avpDKtP3Ax a=ssrc:771062369 msid:zdwT5yOWtU8z0zZPjXaVsPxMLuRF71NQFfMi acb603a5-0bbf-4c69-8cdd-8807579c6afd a=ssrc:771062369 mslabel:zdwT5yOWtU8z0zZPjXaVsPxMLuRF71NQFfMi a=ssrc:771062369 label:acb603a5-0bbf-4c69-8cdd-8807579c6afd 2021-05-14 11:49:22.883128 [DEBUG] sofia.c:7738 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State Change CS_NEW -> CS_INIT 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State NEW 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Running State Change CS_INIT (Cur 1 Tot 24) 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State INIT 2021-05-14 11:49:22.883128 [DEBUG] mod_sofia.c:93 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io SOFIA INIT 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:40 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Standard INIT 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State Change CS_INIT -> CS_ROUTING 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State INIT going to sleep 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Running State Change CS_ROUTING (Cur 1 Tot 24) 2021-05-14 11:49:22.883128 [DEBUG] switch_channel.c:2332 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Callstate Change DOWN -> RINGING 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State ROUTING 2021-05-14 11:49:22.883128 [DEBUG] mod_sofia.c:154 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io SOFIA ROUTING 2021-05-14 11:49:22.883128 [DEBUG] switch_core_state_machine.c:236 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Standard ROUTING 2021-05-14 11:49:22.883128 [INFO] mod_dialplan_xml.c:637 Processing USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f <USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f>->publish-CID-16065804-d44f-4648-91a3-a9001a41e0b9 in context public Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io parsing [public->unloop] continue=false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io parsing [public->outside_call] continue=true Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Absolute Condition [outside_call] Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action set(outside_call=true) Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io parsing [public->call_debug] continue=true Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io parsing [public->public_extensions] continue=false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (FAIL) [public_extensions] destination_number(publish-CID-16065804-d44f-4648-91a3-a9001a41e0b9) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io parsing [public->public_conference_extensions] continue=false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (FAIL) [public_conference_extensions] destination_number(publish-CID-16065804-d44f-4648-91a3-a9001a41e0b9) =~ /^(3[5-8][01][0-9])$/ break=on-false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io parsing [public->public_did] continue=false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (FAIL) [public_did] destination_number(publish-CID-16065804-d44f-4648-91a3-a9001a41e0b9) =~ /^(5551212)$/ break=on-false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io parsing [public->mobitalk_conference] continue=true Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (PASS) [mobitalk_conference] destination_number(publish-CID-16065804-d44f-4648-91a3-a9001a41e0b9) =~ /^(publish|subscribe|)-?(CID-.*)$/ break=on-false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action log(INFO >>> dialplan match conference uuid pattern) Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action set(mobiluslabs_join_mode=publish) INLINE EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io set(mobiluslabs_join_mode=publish) 2021-05-14 11:49:22.963169 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [mobiluslabs_join_mode]=[publish] Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action set(conference_id=CID-16065804-d44f-4648-91a3-a9001a41e0b9) INLINE EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io set(conference_id=CID-16065804-d44f-4648-91a3-a9001a41e0b9) 2021-05-14 11:49:22.963169 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [conference_id]=[CID-16065804-d44f-4648-91a3-a9001a41e0b9] Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action set(domain_name=dev-voice.app.mobitalk.io) Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action set(session_in_hangup_hook=true) Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action export(api_hangup_hook=curl http://localhost:3001/mobitalk/freeswitch/call/terminate/${uuid} post content-type 'application/json') Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action set(post={"call_id":"${uuid}","member_id":"${username}","conference_id":"${conference_id}","join_mode":"${mobiluslabs_join_mode}"}) INLINE EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io set(post={"call_id":"e2a313f6-36a4-4c4f-b246-6663dbe6772f","member_id":"USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f","conference_id":"CID-16065804-d44f-4648-91a3-a9001a41e0b9","join_mode":"publish"}) 2021-05-14 11:49:22.983182 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [post]=[{"call_id":"e2a313f6-36a4-4c4f-b246-6663dbe6772f","member_id":"USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f","conference_id":"CID-16065804-d44f-4648-91a3-a9001a41e0b9","join_mode":"publish"}] Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action curl(http://localhost:3001/mobitalk/freeswitch/call/join json content-type 'application/json' post ${post}) INLINE EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io curl(http://localhost:3001/mobitalk/freeswitch/call/join json content-type 'application/json' post {"call_id":"e2a313f6-36a4-4c4f-b246-6663dbe6772f","member_id":"USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f","conference_id":"CID-16065804-d44f-4648-91a3-a9001a41e0b9","join_mode":"publish"}) 2021-05-14 11:49:23.003374 [DEBUG] mod_curl.c:192 method: post, url: http://localhost:3001/mobitalk/freeswitch/call/join, content-type: application/json 2021-05-14 11:49:23.003374 [DEBUG] mod_curl.c:230 Post data: {"call_id":"e2a313f6-36a4-4c4f-b246-6663dbe6772f","member_id":"USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f","conference_id":"CID-16065804-d44f-4648-91a3-a9001a41e0b9","join_mode":"publish"} Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action log(INFO >>> Return value from the API '${curl_response_data}') Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action log(INFO >>> receiving mobiluslabs_status_code) Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action set(mobiluslabs_status_code=${system(echo '${curl_response_data}' | jq -j -r '.status_code' 2>/dev/null || echo 500)}) INLINE EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io set(mobiluslabs_status_code=200) 2021-05-14 11:49:23.103087 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [mobiluslabs_status_code]=[200] Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (PASS) [mobitalk_conference] ${mobiluslabs_status_code}(200) =~ /^200$/ break=never Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action log(INFO >>> receiving mobiluslabs_recording bool) Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action set(mobiluslabs_recording=${system(echo '${curl_response_data}' | jq -j -r '.body | fromjson .recording' 2>/dev/null || echo false)}) INLINE EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io set(mobiluslabs_recording=null) 2021-05-14 11:49:23.163093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [mobiluslabs_recording]=[null] Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (FAIL) [mobitalk_conference] ${mobiluslabs_recording}(null) =~ /^true$/ break=never Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (FAIL) [mobitalk_conference] ${mobiluslabs_recording_filename}() =~ /^.*\.wav$/ break=never Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Regex (PASS) [mobitalk_conference] destination_number(publish-CID-16065804-d44f-4648-91a3-a9001a41e0b9) =~ /^(publish|subscribe|)-?(CID-.*)$/ break=on-false Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action log(INFO >>> entering conference ${conference_id}) Dialplan: sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Action conference(${conference_id}) 2021-05-14 11:49:23.163093 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State Change CS_ROUTING -> CS_EXECUTE 2021-05-14 11:49:23.163093 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State ROUTING going to sleep 2021-05-14 11:49:23.163093 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Running State Change CS_EXECUTE (Cur 1 Tot 24) 2021-05-14 11:49:23.163093 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State EXECUTE 2021-05-14 11:49:23.163093 [DEBUG] mod_sofia.c:209 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io SOFIA EXECUTE 2021-05-14 11:49:23.163093 [DEBUG] switch_core_state_machine.c:329 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Standard EXECUTE EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io set(outside_call=true) 2021-05-14 11:49:23.163093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [outside_call]=[true] EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io export(RFC2822_DATE=Fri, 14 May 2021 11:49:23 +0000) 2021-05-14 11:49:23.163093 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 14 May 2021 11:49:23 +0000] EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io log(INFO >>> dialplan match conference uuid pattern) 2021-05-14 11:49:23.163093 [INFO] mod_dptools.c:1866 >>> dialplan match conference uuid pattern EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io set(domain_name=dev-voice.app.mobitalk.io) 2021-05-14 11:49:23.163093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [domain_name]=[dev-voice.app.mobitalk.io] EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io set(session_in_hangup_hook=true) 2021-05-14 11:49:23.163093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [session_in_hangup_hook]=[true] EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io export(api_hangup_hook=curl http://localhost:3001/mobitalk/freeswitch/call/terminate/e2a313f6-36a4-4c4f-b246-6663dbe6772f post content-type 'application/json') 2021-05-14 11:49:23.163093 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [api_hangup_hook]=[curl http://localhost:3001/mobitalk/freeswitch/call/terminate/e2a313f6-36a4-4c4f-b246-6663dbe6772f post content-type 'application/json'] EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io log(INFO >>> Return value from the API '{"status_code":"200","body":"{}","version":"HTTP/1.1","phrase":"OK","headers":[{"key":"X-Request-ID","value":"cac1c1e7-8e48-4514-af82-60cb9023a617"},{"key":"Content-Type","value":"application/json; charset=utf-8"},{"key":"Date","value":"Fri, 14 May 2021 11:49:23 GMT"},{"key":"Connection","value":"keep-alive"},{"key":"Keep-Alive","value":"timeout=5"},{"key":"Content-Length","value":"2"}]}') 2021-05-14 11:49:23.163093 [INFO] mod_dptools.c:1866 >>> Return value from the API '{"status_code":"200","body":"{}","version":"HTTP/1.1","phrase":"OK","headers":[{"key":"X-Request-ID","value":"cac1c1e7-8e48-4514-af82-60cb9023a617"},{"key":"Content-Type","value":"application/json; charset=utf-8"},{"key":"Date","value":"Fri, 14 May 2021 11:49:23 GMT"},{"key":"Connection","value":"keep-alive"},{"key":"Keep-Alive","value":"timeout=5"},{"key":"Content-Length","value":"2"}]}' EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io log(INFO >>> receiving mobiluslabs_status_code) 2021-05-14 11:49:23.163093 [INFO] mod_dptools.c:1866 >>> receiving mobiluslabs_status_code EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io log(INFO >>> receiving mobiluslabs_recording bool) 2021-05-14 11:49:23.163093 [INFO] mod_dptools.c:1866 >>> receiving mobiluslabs_recording bool EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io log(INFO >>> entering conference CID-16065804-d44f-4648-91a3-a9001a41e0b9) 2021-05-14 11:49:23.163093 [INFO] mod_dptools.c:1866 >>> entering conference CID-16065804-d44f-4648-91a3-a9001a41e0b9 2021-05-14 11:49:23.183081 [DEBUG] switch_core_session.c:2722 Application conference Requires media! pre_answering channel sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io 2021-05-14 11:49:23.183081 [INFO] switch_core_session.c:2724 Sending early media 2021-05-14 11:49:23.243093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:111:48000:20:0:1]/[opus:116:48000:20:0:1] 2021-05-14 11:49:23.243093 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match 2021-05-14 11:49:23.243093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:111:48000:20:0:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:49:23.243093 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:111:48000:20:0:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 110@48000 2021-05-14 11:49:23.263165 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [72000bps] 2021-05-14 11:49:23.263165 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [72000bps] 2021-05-14 11:49:23.263165 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io opus/48000 20 ms 960 samples 0 bits 1 channels 2021-05-14 11:49:23.263165 [DEBUG] switch_core_codec.c:111 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Original read codec set to opus:116 2021-05-14 11:49:23.283111 [WARNING] switch_core_media.c:4252 NO candidate ACL defined, Defaulting to wan.auto 2021-05-14 11:49:23.283111 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.15.143:46512 2021-05-14 11:49:23.283111 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.15.143:37888 2021-05-14 11:49:23.283111 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: srflx addr: 89.216.102.123:46512 2021-05-14 11:49:23.283111 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 2 proto: udp type: srflx addr: 89.216.102.123:37888 2021-05-14 11:49:23.283111 [DEBUG] switch_core_media.c:4327 Searching for rtp candidate. 2021-05-14 11:49:23.303203 [DEBUG] switch_core_media.c:4336 Choose rtp candidate, index 1, 89.216.102.123:46512 2021-05-14 11:49:23.303203 [DEBUG] switch_core_media.c:4102 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io choosing family v4 2021-05-14 11:49:23.303203 [DEBUG] switch_core_media.c:4347 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute 89.216.102.123:46512 2021-05-14 11:49:23.303203 [DEBUG] switch_core_media.c:4399 setting remote audio ice addr to index 1 89.216.102.123:46512 based on candidate 2021-05-14 11:49:23.303203 [DEBUG] switch_core_media.c:4434 Setting remote rtcp audio addr to 89.216.102.123:46512 based on candidate 2021-05-14 11:49:23.303203 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 110@48000 2021-05-14 11:49:23.303203 [DEBUG] switch_core_media.c:5909 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Set 2833 dtmf send payload to 110 recv payload to 110 2021-05-14 11:49:23.303203 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io] 165.22.124.94 port 16522 -> 89.216.102.123 port 46512 codec: 111 ms: 20 2021-05-14 11:49:23.303203 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 960 bytes per 20ms 2021-05-14 11:49:23.303203 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [72000bps] 2021-05-14 11:49:23.323532 [DEBUG] switch_rtp.c:8810 Activate VAD codec opus 20ms 2021-05-14 11:49:23.323532 [DEBUG] switch_core_media.c:8834 AUDIO RTP Engage VAD for sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io ( in out ) 2021-05-14 11:49:23.323532 [INFO] switch_core_media.c:8843 Activating Audio ICE 2021-05-14 11:49:23.323532 [NOTICE] switch_rtp.c:4915 Activating RTP audio ICE: X1XX:uTqQOwb4wedfqxKH 89.216.102.123:46512 2021-05-14 11:49:23.323532 [DEBUG] switch_core_media.c:8883 Activating RTCP PORT 46512 2021-05-14 11:49:23.323532 [DEBUG] switch_rtp.c:4811 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 46512 2021-05-14 11:49:23.323532 [INFO] switch_core_media.c:8894 Skipping RTCP ICE (Same as RTP) 2021-05-14 11:49:23.323532 [INFO] switch_rtp.c:3741 Activate RTP/RTCP audio DTLS client 2021-05-14 11:49:23.343115 [INFO] switch_rtp.c:3908 Changing audio DTLS state from OFF to HANDSHAKE 2021-05-14 11:49:23.343115 [DEBUG] switch_core_media.c:8975 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Set 2833 dtmf send payload to 110 2021-05-14 11:49:23.343115 [DEBUG] switch_core_media.c:8982 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Set 2833 dtmf receive payload to 110 2021-05-14 11:49:23.343115 [DEBUG] switch_core_media.c:9005 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Set rtp dtmf delay to 40 2021-05-14 11:49:23.343115 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io! 2021-05-14 11:49:23.363426 [DEBUG] switch_channel.c:3565 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Callstate Change RINGING -> EARLY 2021-05-14 11:49:23.363426 [DEBUG] switch_core_media.c:8643 Audio params are unchanged for sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io. 2021-05-14 11:49:23.363426 [DEBUG] mod_sofia.c:2593 Ring SDP: v=0 o=FreeSWITCH 1620976441 1620976442 IN IP4 165.22.124.94 s=FreeSWITCH c=IN IP4 165.22.124.94 t=0 0 a=msid-semantic: WMS axaISE3sLWIn7JYQtotXXk0IP4fSpQNR m=audio 16522 UDP/TLS/RTP/SAVPF 111 110 a=rtpmap:111 opus/48000/2 a=fmtp:111 useinbandfec=1; minptime=10 a=rtpmap:110 telephone-event/48000 a=ptime:20 a=sendrecv a=fingerprint:sha-256 9E:63:E6:79:2E:CB:0C:5A:65:8F:F4:B7:B3:92:6D:7E:A0:9F:4D:BB:29:FB:B1:23:0D:D6:A9:81:74:9C:63:C4 a=setup:active a=rtcp-mux a=rtcp:16522 IN IP4 165.22.124.94 a=ice-ufrag:uTqQOwb4wedfqxKH a=ice-pwd:s4EN9ftUaCV5ebRP7IDV5crG a=candidate:4220541010 1 udp 659136 165.22.124.94 16522 typ host generation 0 a=end-of-candidates a=ssrc:1639661530 cname:BaF8xBbCBlMGQF0u a=ssrc:1639661530 msid:axaISE3sLWIn7JYQtotXXk0IP4fSpQNR a0 a=ssrc:1639661530 mslabel:axaISE3sLWIn7JYQtotXXk0IP4fSpQNR a=ssrc:1639661530 label:axaISE3sLWIn7JYQtotXXk0IP4fSpQNRa0 error: tport_logging: capture socket error: No error information 2021-05-14 11:49:23.363426 [DEBUG] sofia.c:7325 Channel sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io entering state [early][183] EXECUTE [depth=0] sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io conference(CID-16065804-d44f-4648-91a3-a9001a41e0b9) 2021-05-14 11:49:23.403104 [ERR] mod_xml_curl.c:315 Received HTTP error 400 trying to fetch http://localhost:3001/mobitalk/sipauth data: [hostname=dev-voice§ion=configuration&tag_name=configuration&key_name=name&key_value=conference.conf&Event-Name=COMMAND&Core-UUID=09863f97-160e-4bcf-b0a4-c78fa7f88a2d&FreeSWITCH-Hostname=dev-voice&FreeSWITCH-Switchname=dev-voice&FreeSWITCH-IPv4=165.22.124.94&FreeSWITCH-IPv6=%3A%3A1&Event-Date-Local=2021-05-14%2011%3A49%3A23&Event-Date-GMT=Fri,%2014%20May%202021%2011%3A49%3A23%20GMT&Event-Date-Timestamp=1620992963363426&Event-Calling-File=mod_conference.c&Event-Calling-Function=conference_function&Event-Calling-Line-Number=2016&Event-Sequence=3367&conference_name=CID-16065804-d44f-4648-91a3-a9001a41e0b9&profile_name=default&Fetch-Call-UUID=e2a313f6-36a4-4c4f-b246-6663dbe6772f] 2021-05-14 11:49:23.403104 [DEBUG] mod_conference.c:3414 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie 2021-05-14 11:49:23.403104 [DEBUG] switch_core_media.c:8643 Audio params are unchanged for sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io. 2021-05-14 11:49:23.403104 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io: v=0 o=FreeSWITCH 1620976441 1620976443 IN IP4 165.22.124.94 s=FreeSWITCH c=IN IP4 165.22.124.94 t=0 0 a=msid-semantic: WMS axaISE3sLWIn7JYQtotXXk0IP4fSpQNR m=audio 16522 UDP/TLS/RTP/SAVPF 111 110 a=rtpmap:111 opus/48000/2 a=fmtp:111 useinbandfec=1; minptime=10 a=rtpmap:110 telephone-event/48000 a=ptime:20 a=sendrecv a=fingerprint:sha-256 9E:63:E6:79:2E:CB:0C:5A:65:8F:F4:B7:B3:92:6D:7E:A0:9F:4D:BB:29:FB:B1:23:0D:D6:A9:81:74:9C:63:C4 a=setup:active a=rtcp-mux a=rtcp:16522 IN IP4 165.22.124.94 a=ice-ufrag:uTqQOwb4wedfqxKH a=ice-pwd:s4EN9ftUaCV5ebRP7IDV5crG a=candidate:0079231855 1 udp 659136 165.22.124.94 16522 typ host generation 0 a=end-of-candidates a=ssrc:1639661530 cname:BaF8xBbCBlMGQF0u a=ssrc:1639661530 msid:axaISE3sLWIn7JYQtotXXk0IP4fSpQNR a0 a=ssrc:1639661530 mslabel:axaISE3sLWIn7JYQtotXXk0IP4fSpQNR a=ssrc:1639661530 label:axaISE3sLWIn7JYQtotXXk0IP4fSpQNRa0 2021-05-14 11:49:23.403104 [DEBUG] sofia.c:7325 Channel sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io entering state [completed][200] 2021-05-14 11:49:23.403104 [DEBUG] mod_conference.c:228 Setup timer success interval: 20 samples: 160 error: tport_logging: capture socket error: No error information 2021-05-14 11:49:23.523125 [DEBUG] sofia.c:7325 Channel sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io entering state [ready][200] 2021-05-14 11:49:23.623089 [INFO] switch_rtp.c:3278 Changing audio DTLS state from HANDSHAKE to SETUP 2021-05-14 11:49:23.643105 [INFO] switch_rtp.c:3185 audio Fingerprint Verified. 2021-05-14 11:49:23.643105 [INFO] switch_rtp.c:4217 Activating audio Secure RTP SEND 2021-05-14 11:49:23.643105 [DEBUG] switch_core_sqldb.c:2836 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2021-05-14 11:49:23.643105 [INFO] switch_rtp.c:4195 Activating audio Secure RTP RECV 2021-05-14 11:49:23.643105 [DEBUG] switch_core_sqldb.c:2836 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2021-05-14 11:49:23.643105 [INFO] switch_rtp.c:3227 Changing audio DTLS state from SETUP to READY 2021-05-14 11:49:23.643105 [NOTICE] mod_conference.c:2236 Channel [sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io] has been answered 2021-05-14 11:49:23.643105 [DEBUG] switch_channel.c:3865 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Callstate Change EARLY -> ACTIVE 2021-05-14 11:49:23.643105 [DEBUG] conference_member.c:1764 Raw Codec Activation Success L16@48000hz 1 channel 20ms 2021-05-14 11:49:23.643105 [DEBUG] conference_member.c:1811 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2021-05-14 11:49:23.643105 [DEBUG] switch_core_codec.c:223 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Push codec L16:100 2021-05-14 11:49:23.643105 [ERR] switch_core_video.c:3044 This function is not available, libpng not installed 2021-05-14 11:49:23.663125 [DEBUG] conference_loop.c:1338 Setup timer soft success interval: 20 samples: 960 from codec opus 2021-05-14 11:49:23.663125 [NOTICE] switch_core_media.c:15843 Activating write resampler 2021-05-14 11:49:23.843106 [DEBUG] conference_member.c:1764 Raw Codec Activation Success L16@48000hz 1 channel 20ms 2021-05-14 11:49:23.843106 [DEBUG] conference_member.c:1811 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2021-05-14 11:49:23.843106 [DEBUG] conference_loop.c:1338 Setup timer soft success interval: 20 samples: 960 from codec opus error: tport_logging: capture socket error: No error information 2021-05-14 11:49:40.343133 [DEBUG] sofia.c:6414 Ping to sip user 'USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io' succeeded with code 200 - count 1, state Reachable 2021-05-14 11:49:40.363098 [NOTICE] sofia.c:1089 Hangup sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [CS_EXECUTE] [NORMAL_CLEARING] error: tport_logging: capture socket error: No error information 2021-05-14 11:49:40.383097 [INFO] conference_loop.c:1665 Channel leaving conference, cause: NORMAL_CLEARING 2021-05-14 11:49:40.383097 [DEBUG] mod_conference.c:2501 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io skip receive message [UNBRIDGE] (channel is hungup already) 2021-05-14 11:49:40.383097 [DEBUG] switch_core_media.c:12370 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io skip receive message [HARD_MUTE] (channel is hungup already) 2021-05-14 11:49:40.383097 [DEBUG] switch_core_codec.c:260 Restore original codec. 2021-05-14 11:49:40.383097 [DEBUG] switch_core_session.c:2905 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2021-05-14 11:49:40.383097 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State EXECUTE going to sleep 2021-05-14 11:49:40.383097 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Running State Change CS_HANGUP (Cur 1 Tot 24) 2021-05-14 11:49:40.383097 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Callstate Change ACTIVE -> HANGUP 2021-05-14 11:49:40.383097 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State HANGUP 2021-05-14 11:49:40.383097 [DEBUG] mod_sofia.c:453 Channel sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io hanging up, cause: NORMAL_CLEARING 2021-05-14 11:49:40.383097 [DEBUG] switch_core_state_machine.c:60 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Standard HANGUP, cause: NORMAL_CLEARING 2021-05-14 11:49:40.383097 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State HANGUP going to sleep 2021-05-14 11:49:40.383097 [DEBUG] mod_conference.c:835 Write Lock ON 2021-05-14 11:49:40.383097 [DEBUG] mod_conference.c:838 Write Lock OFF 2021-05-14 11:49:40.423157 [DEBUG] mod_curl.c:192 method: post, url: http://localhost:3001/mobitalk/freeswitch/call/terminate/e2a313f6-36a4-4c4f-b246-6663dbe6772f, content-type: (null) 2021-05-14 11:49:40.423157 [DEBUG] mod_curl.c:230 Post data: content-type 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:781 Hangup Command with Session curl(http://localhost:3001/mobitalk/freeswitch/call/terminate/e2a313f6-36a4-4c4f-b246-6663dbe6772f post content-type 'application/json'): {"name":"ServiceUnavailableError","message":"Service unavailable","code":503} 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State Change CS_HANGUP -> CS_REPORTING 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Running State Change CS_REPORTING (Cur 1 Tot 24) 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State REPORTING 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:174 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Standard REPORTING, cause: NORMAL_CLEARING 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State REPORTING going to sleep 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State Change CS_REPORTING -> CS_DESTROY 2021-05-14 11:49:40.448925 [DEBUG] switch_core_session.c:1726 Session 24 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Locked, Waiting on external entities 2021-05-14 11:49:40.448925 [NOTICE] switch_core_session.c:1744 Session 24 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Ended 2021-05-14 11:49:40.448925 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io [CS_DESTROY] 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) Running State Change CS_DESTROY (Cur 0 Tot 24) 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State DESTROY 2021-05-14 11:49:40.448925 [DEBUG] mod_sofia.c:364 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io SOFIA DESTROY 2021-05-14 11:49:40.448925 [DEBUG] mod_opus.c:719 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2021-05-14 11:49:40.448925 [DEBUG] mod_opus.c:734 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2021-05-14 11:49:40.448925 [DEBUG] mod_opus.c:719 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2021-05-14 11:49:40.448925 [DEBUG] mod_opus.c:734 Opus encoder stats: Frames[835] Bytes encoded[26768] Encoded length ms[16700] Average encoded bitrate bps[13384] 2021-05-14 11:49:40.448925 [DEBUG] mod_opus.c:719 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2021-05-14 11:49:40.448925 [DEBUG] mod_opus.c:734 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:181 sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io Standard DESTROY 2021-05-14 11:49:40.448925 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io) State DESTROY going to sleep error: tport_logging: capture socket error: No error information 2021-05-14 11:50:12.043118 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [b9b475f5-019e-4da6-a83d-4061c273f724] 2021-05-14 11:50:12.043118 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Running State Change CS_NEW (Cur 1 Tot 25) 2021-05-14 11:50:12.043118 [DEBUG] sofia.c:10279 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io receiving invite from 172.18.0.2:41944 version: 1.10.3 -release 64bit 2021-05-14 11:50:12.043118 [DEBUG] sofia.c:10373 verifying acl "domains" for ip/port 172.18.0.2:0. 2021-05-14 11:50:12.043118 [DEBUG] sofia.c:10402 IP 172.18.0.2 Approved by acl "domains[]". Access Granted. 2021-05-14 11:50:12.063100 [DEBUG] sofia.c:11476 Setting NAT mode based on via received 2021-05-14 11:50:12.063100 [DEBUG] sofia.c:7325 Channel sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io entering state [received][100] 2021-05-14 11:50:12.063100 [DEBUG] sofia.c:7335 Remote SDP: v=0 o=- 5496979672980832327 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE 0 a=extmap-allow-mixed a=msid-semantic: WMS M9JujXdcmyISKWfgrYvGKGx6EjR2rPO3Wiuy m=audio 47216 UDP/TLS/RTP/SAVPF 111 103 9 0 8 105 13 110 113 126 c=IN IP4 89.216.102.123 a=rtpmap:111 opus/48000/2 a=fmtp:111 minptime=10;useinbandfec=1 a=rtpmap:103 ISAC/16000 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:110 telephone-event/48000 a=rtpmap:113 telephone-event/16000 a=rtpmap:126 telephone-event/8000 a=rtcp:39350 IN IP4 89.216.102.123 a=candidate:2038265674 1 udp 2113937151 192.168.15.144 47216 typ host generation 0 network-cost 999 a=candidate:2038265674 2 udp 2113937150 192.168.15.144 39350 typ host generation 0 network-cost 999 a=candidate:842163049 1 udp 1677729535 89.216.102.123 47216 typ srflx raddr 192.168.15.144 rport 47216 generation 0 network-cost 999 a=candidate:842163049 2 udp 1677729534 89.216.102.123 39350 typ srflx raddr 192.168.15.144 rport 39350 generation 0 network-cost 999 a=ice-ufrag:dtep a=ice-pwd:73lh+EwhupsM3Vfm/Rc3bCqy a=ice-options:trickle a=fingerprint:sha-256 75:07:75:54:28:0D:3F:20:5E:18:C3:F2:4B:BC:D4:61:51:84:59:37:85:77:30:22:49:9A:31:43:AB:BF:A6:89 a=setup:actpass a=mid:0 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id a=msid:M9JujXdcmyISKWfgrYvGKGx6EjR2rPO3Wiuy 6eb06353-417c-4f3a-9997-05d7a2dc1afd a=rtcp-mux a=rtcp-fb:111 transport-cc a=ssrc:2062873529 cname:UdkpgixAAI8yH2FW a=ssrc:2062873529 msid:M9JujXdcmyISKWfgrYvGKGx6EjR2rPO3Wiuy 6eb06353-417c-4f3a-9997-05d7a2dc1afd a=ssrc:2062873529 mslabel:M9JujXdcmyISKWfgrYvGKGx6EjR2rPO3Wiuy a=ssrc:2062873529 label:6eb06353-417c-4f3a-9997-05d7a2dc1afd 2021-05-14 11:50:12.063100 [DEBUG] sofia.c:7738 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State Change CS_NEW -> CS_INIT 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State NEW 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Running State Change CS_INIT (Cur 1 Tot 25) 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State INIT 2021-05-14 11:50:12.063100 [DEBUG] mod_sofia.c:93 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io SOFIA INIT 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:40 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Standard INIT 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State Change CS_INIT -> CS_ROUTING 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State INIT going to sleep 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Running State Change CS_ROUTING (Cur 1 Tot 25) 2021-05-14 11:50:12.063100 [DEBUG] switch_channel.c:2332 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Callstate Change DOWN -> RINGING 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State ROUTING 2021-05-14 11:50:12.063100 [DEBUG] mod_sofia.c:154 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io SOFIA ROUTING 2021-05-14 11:50:12.063100 [DEBUG] switch_core_state_machine.c:236 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Standard ROUTING 2021-05-14 11:50:12.063100 [INFO] mod_dialplan_xml.c:637 Processing USR-4618ebb4-278e-4848-a577-26ae1d5424e3 <USR-4618ebb4-278e-4848-a577-26ae1d5424e3>->CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7 in context public Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io parsing [public->unloop] continue=false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io parsing [public->outside_call] continue=true Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Absolute Condition [outside_call] Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(outside_call=true) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io parsing [public->call_debug] continue=true Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io parsing [public->public_extensions] continue=false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (FAIL) [public_extensions] destination_number(CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io parsing [public->public_conference_extensions] continue=false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (FAIL) [public_conference_extensions] destination_number(CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) =~ /^(3[5-8][01][0-9])$/ break=on-false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io parsing [public->public_did] continue=false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (FAIL) [public_did] destination_number(CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) =~ /^(5551212)$/ break=on-false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io parsing [public->mobitalk_conference] continue=true Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (PASS) [mobitalk_conference] destination_number(CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) =~ /^(publish|subscribe|)-?(CID-.*)$/ break=on-false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action log(INFO >>> dialplan match conference uuid pattern) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(mobiluslabs_join_mode=) INLINE EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(mobiluslabs_join_mode=) 2021-05-14 11:50:12.103773 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [mobiluslabs_join_mode]=[UNDEF] Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(conference_id=CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) INLINE EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(conference_id=CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) 2021-05-14 11:50:12.123491 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [conference_id]=[CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7] Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(domain_name=dev-voice.app.mobitalk.io) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(session_in_hangup_hook=true) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action export(api_hangup_hook=curl http://localhost:3001/mobitalk/freeswitch/call/terminate/${uuid} post content-type 'application/json') Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(post={"call_id":"${uuid}","member_id":"${username}","conference_id":"${conference_id}","join_mode":"${mobiluslabs_join_mode}"}) INLINE EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(post={"call_id":"b9b475f5-019e-4da6-a83d-4061c273f724","member_id":"USR-4618ebb4-278e-4848-a577-26ae1d5424e3","conference_id":"CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7","join_mode":""}) 2021-05-14 11:50:12.123491 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [post]=[{"call_id":"b9b475f5-019e-4da6-a83d-4061c273f724","member_id":"USR-4618ebb4-278e-4848-a577-26ae1d5424e3","conference_id":"CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7","join_mode":""}] Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action curl(http://localhost:3001/mobitalk/freeswitch/call/join json content-type 'application/json' post ${post}) INLINE EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io curl(http://localhost:3001/mobitalk/freeswitch/call/join json content-type 'application/json' post {"call_id":"b9b475f5-019e-4da6-a83d-4061c273f724","member_id":"USR-4618ebb4-278e-4848-a577-26ae1d5424e3","conference_id":"CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7","join_mode":""}) 2021-05-14 11:50:12.143347 [DEBUG] mod_curl.c:192 method: post, url: http://localhost:3001/mobitalk/freeswitch/call/join, content-type: application/json 2021-05-14 11:50:12.143347 [DEBUG] mod_curl.c:230 Post data: {"call_id":"b9b475f5-019e-4da6-a83d-4061c273f724","member_id":"USR-4618ebb4-278e-4848-a577-26ae1d5424e3","conference_id":"CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7","join_mode":""} Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action log(INFO >>> Return value from the API '${curl_response_data}') Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action log(INFO >>> receiving mobiluslabs_status_code) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(mobiluslabs_status_code=${system(echo '${curl_response_data}' | jq -j -r '.status_code' 2>/dev/null || echo 500)}) INLINE EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(mobiluslabs_status_code=200) 2021-05-14 11:50:12.263088 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [mobiluslabs_status_code]=[200] Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (PASS) [mobitalk_conference] ${mobiluslabs_status_code}(200) =~ /^200$/ break=never Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action log(INFO >>> receiving mobiluslabs_recording bool) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(mobiluslabs_recording=${system(echo '${curl_response_data}' | jq -j -r '.body | fromjson .recording' 2>/dev/null || echo false)}) INLINE EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(mobiluslabs_recording=true) 2021-05-14 11:50:12.323093 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [mobiluslabs_recording]=[true] Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (PASS) [mobitalk_conference] ${mobiluslabs_recording}(true) =~ /^true$/ break=never Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action log(INFO >>> receiving mobiluslabs_recording_filename) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(mobiluslabs_recording_filename=${system(echo '${curl_response_data}' | jq -j -r '.body | fromjson .recording_filename' 2>/dev/null || echo '')}) INLINE EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(mobiluslabs_recording_filename=b9b475f5-019e-4da6-a83d-4061c273f724.wav) 2021-05-14 11:50:12.383088 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [mobiluslabs_recording_filename]=[b9b475f5-019e-4da6-a83d-4061c273f724.wav] Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (PASS) [mobitalk_conference] ${mobiluslabs_recording_filename}(b9b475f5-019e-4da6-a83d-4061c273f724.wav) =~ /^.*\.wav$/ break=never Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action log(INFO >>> enabling individual channel recording) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(record_post_process_exec_api=curl:http://127.0.0.1:1500?recording=${mobiluslabs_recording_filename}&freeswitch_host=165.22.124.94) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action set(RECORD_READ_ONLY=true) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action export(execute_on_answer=record_session /var/lib/freeswitch/recordings/${mobiluslabs_recording_filename}) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Regex (PASS) [mobitalk_conference] destination_number(CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) =~ /^(publish|subscribe|)-?(CID-.*)$/ break=on-false Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action log(INFO >>> entering conference ${conference_id}) Dialplan: sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Action conference(${conference_id}) 2021-05-14 11:50:12.383088 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State Change CS_ROUTING -> CS_EXECUTE 2021-05-14 11:50:12.383088 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State ROUTING going to sleep 2021-05-14 11:50:12.383088 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Running State Change CS_EXECUTE (Cur 1 Tot 25) 2021-05-14 11:50:12.383088 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State EXECUTE 2021-05-14 11:50:12.383088 [DEBUG] mod_sofia.c:209 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io SOFIA EXECUTE 2021-05-14 11:50:12.383088 [DEBUG] switch_core_state_machine.c:329 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Standard EXECUTE EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(outside_call=true) 2021-05-14 11:50:12.383088 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [outside_call]=[true] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io export(RFC2822_DATE=Fri, 14 May 2021 11:50:12 +0000) 2021-05-14 11:50:12.383088 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 14 May 2021 11:50:12 +0000] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io log(INFO >>> dialplan match conference uuid pattern) 2021-05-14 11:50:12.383088 [INFO] mod_dptools.c:1866 >>> dialplan match conference uuid pattern EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(domain_name=dev-voice.app.mobitalk.io) 2021-05-14 11:50:12.383088 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [domain_name]=[dev-voice.app.mobitalk.io] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(session_in_hangup_hook=true) 2021-05-14 11:50:12.383088 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [session_in_hangup_hook]=[true] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io export(api_hangup_hook=curl http://localhost:3001/mobitalk/freeswitch/call/terminate/b9b475f5-019e-4da6-a83d-4061c273f724 post content-type 'application/json') 2021-05-14 11:50:12.383088 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [api_hangup_hook]=[curl http://localhost:3001/mobitalk/freeswitch/call/terminate/b9b475f5-019e-4da6-a83d-4061c273f724 post content-type 'application/json'] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io log(INFO >>> Return value from the API '{"status_code":"200","body":"{\"recording\":true,\"recording_filename\":\"b9b475f5-019e-4da6-a83d-4061c273f724.wav\"}","version":"HTTP/1.1","phrase":"OK","headers":[{"key":"X-Request-ID","value":"ede0b4ea-51b9-4e60-a3dc-59d8ae5003ef"},{"key":"Content-Type","value":"application/json; charset=utf-8"},{"key":"Date","value":"Fri, 14 May 2021 11:50:12 GMT"},{"key":"Connection","value":"keep-alive"},{"key":"Keep-Alive","value":"timeout=5"},{"key":"Content-Length","value":"82"}]}') 2021-05-14 11:50:12.383088 [INFO] mod_dptools.c:1866 >>> Return value from the API '{"status_code":"200","body":"{\"recording\":true,\"recording_filename\":\"b9b475f5-019e-4da6-a83d-4061c273f724.wav\"}","version":"HTTP/1.1","phrase":"OK","headers":[{"key":"X-Request-ID","value":"ede0b4ea-51b9-4e60-a3dc-59d8ae5003ef"},{"key":"Content-Type","value":"application/json; charset=utf-8"},{"key":"Date","value":"Fri, 14 May 2021 11:50:12 GMT"},{"key":"Connection","value":"keep-alive"},{"key":"Keep-Alive","value":"timeout=5"},{"key":"Content-Length","value":"82"}]}' EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io log(INFO >>> receiving mobiluslabs_status_code) 2021-05-14 11:50:12.463210 [INFO] mod_dptools.c:1866 >>> receiving mobiluslabs_status_code EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io log(INFO >>> receiving mobiluslabs_recording bool) 2021-05-14 11:50:12.463210 [INFO] mod_dptools.c:1866 >>> receiving mobiluslabs_recording bool EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io log(INFO >>> receiving mobiluslabs_recording_filename) 2021-05-14 11:50:12.483643 [INFO] mod_dptools.c:1866 >>> receiving mobiluslabs_recording_filename EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io log(INFO >>> enabling individual channel recording) 2021-05-14 11:50:12.483643 [INFO] mod_dptools.c:1866 >>> enabling individual channel recording EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(record_post_process_exec_api=curl:http://127.0.0.1:1500?recording=b9b475f5-019e-4da6-a83d-4061c273f724.wav&freeswitch_host=165.22.124.94) 2021-05-14 11:50:12.483643 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [record_post_process_exec_api]=[curl:http://127.0.0.1:1500?recording=b9b475f5-019e-4da6-a83d-4061c273f724.wav&freeswitch_host=165.22.124.94] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io set(RECORD_READ_ONLY=true) 2021-05-14 11:50:12.483643 [DEBUG] mod_dptools.c:1672 SET sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [RECORD_READ_ONLY]=[true] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io export(execute_on_answer=record_session /var/lib/freeswitch/recordings/b9b475f5-019e-4da6-a83d-4061c273f724.wav) 2021-05-14 11:50:12.483643 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/b9b475f5-019e-4da6-a83d-4061c273f724.wav] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io log(INFO >>> entering conference CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) 2021-05-14 11:50:12.503085 [INFO] mod_dptools.c:1866 >>> entering conference CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7 2021-05-14 11:50:12.503085 [DEBUG] switch_core_session.c:2722 Application conference Requires media! pre_answering channel sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io 2021-05-14 11:50:12.503085 [INFO] switch_core_session.c:2724 Sending early media 2021-05-14 11:50:12.503085 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:111:48000:20:0:1]/[opus:116:48000:20:0:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:111:48000:20:0:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:111:48000:20:0:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2021-05-14 11:50:12.523885 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 110@48000 2021-05-14 11:50:12.523885 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [72000bps] 2021-05-14 11:50:12.543324 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [72000bps] 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io opus/48000 20 ms 960 samples 0 bits 1 channels 2021-05-14 11:50:12.543324 [DEBUG] switch_core_codec.c:111 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Original read codec set to opus:116 2021-05-14 11:50:12.543324 [WARNING] switch_core_media.c:4252 NO candidate ACL defined, Defaulting to wan.auto 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.15.144:47216 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.15.144:39350 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: srflx addr: 89.216.102.123:47216 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 2 proto: udp type: srflx addr: 89.216.102.123:39350 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4327 Searching for rtp candidate. 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4336 Choose rtp candidate, index 1, 89.216.102.123:47216 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4102 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io choosing family v4 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4347 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute 89.216.102.123:47216 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4399 setting remote audio ice addr to index 1 89.216.102.123:47216 based on candidate 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:4434 Setting remote rtcp audio addr to 89.216.102.123:47216 based on candidate 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 110@48000 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:5909 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Set 2833 dtmf send payload to 110 recv payload to 110 2021-05-14 11:50:12.543324 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io] 165.22.124.94 port 16480 -> 89.216.102.123 port 47216 codec: 111 ms: 20 2021-05-14 11:50:12.543324 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 960 bytes per 20ms 2021-05-14 11:50:12.563219 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [72000bps] 2021-05-14 11:50:12.563219 [DEBUG] switch_rtp.c:8810 Activate VAD codec opus 20ms 2021-05-14 11:50:12.563219 [DEBUG] switch_core_media.c:8834 AUDIO RTP Engage VAD for sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io ( in out ) 2021-05-14 11:50:12.563219 [INFO] switch_core_media.c:8843 Activating Audio ICE 2021-05-14 11:50:12.563219 [NOTICE] switch_rtp.c:4915 Activating RTP audio ICE: dtep:LMy9FzoFCLatPl0Y 89.216.102.123:47216 2021-05-14 11:50:12.563219 [DEBUG] switch_core_media.c:8883 Activating RTCP PORT 47216 2021-05-14 11:50:12.563219 [DEBUG] switch_rtp.c:4811 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 47216 2021-05-14 11:50:12.563219 [INFO] switch_core_media.c:8894 Skipping RTCP ICE (Same as RTP) 2021-05-14 11:50:12.583100 [INFO] switch_rtp.c:3741 Activate RTP/RTCP audio DTLS client 2021-05-14 11:50:12.583100 [INFO] switch_rtp.c:3908 Changing audio DTLS state from OFF to HANDSHAKE 2021-05-14 11:50:12.583100 [DEBUG] switch_core_media.c:8975 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Set 2833 dtmf send payload to 110 2021-05-14 11:50:12.583100 [DEBUG] switch_core_media.c:8982 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Set 2833 dtmf receive payload to 110 2021-05-14 11:50:12.583100 [DEBUG] switch_core_media.c:9005 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Set rtp dtmf delay to 40 2021-05-14 11:50:12.583100 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io! 2021-05-14 11:50:12.603797 [DEBUG] switch_channel.c:3565 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Callstate Change RINGING -> EARLY 2021-05-14 11:50:12.603797 [DEBUG] switch_core_media.c:8643 Audio params are unchanged for sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io. 2021-05-14 11:50:12.603797 [DEBUG] mod_sofia.c:2593 Ring SDP: v=0 o=FreeSWITCH 1620976532 1620976533 IN IP4 165.22.124.94 s=FreeSWITCH c=IN IP4 165.22.124.94 t=0 0 a=msid-semantic: WMS wNWDRYe3i0z9D770VGbaZwW04cSvRWOG m=audio 16480 UDP/TLS/RTP/SAVPF 111 110 a=rtpmap:111 opus/48000/2 a=fmtp:111 useinbandfec=1; minptime=10 a=rtpmap:110 telephone-event/48000 a=ptime:20 a=sendrecv a=fingerprint:sha-256 9E:63:E6:79:2E:CB:0C:5A:65:8F:F4:B7:B3:92:6D:7E:A0:9F:4D:BB:29:FB:B1:23:0D:D6:A9:81:74:9C:63:C4 a=setup:active a=rtcp-mux a=rtcp:16480 IN IP4 165.22.124.94 a=ice-ufrag:LMy9FzoFCLatPl0Y a=ice-pwd:HuJqR7u7TJNp8rNQWJa8I4Tv a=candidate:0808256261 1 udp 659136 165.22.124.94 16480 typ host generation 0 a=end-of-candidates a=ssrc:1638392748 cname:30gO5zG2WOdOiSO2 a=ssrc:1638392748 msid:wNWDRYe3i0z9D770VGbaZwW04cSvRWOG a0 a=ssrc:1638392748 mslabel:wNWDRYe3i0z9D770VGbaZwW04cSvRWOG a=ssrc:1638392748 label:wNWDRYe3i0z9D770VGbaZwW04cSvRWOGa0 error: tport_logging: capture socket error: No error information 2021-05-14 11:50:12.603797 [DEBUG] sofia.c:7325 Channel sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io entering state [early][183] EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io conference(CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7) 2021-05-14 11:50:12.629869 [ERR] mod_xml_curl.c:315 Received HTTP error 400 trying to fetch http://localhost:3001/mobitalk/sipauth data: [hostname=dev-voice§ion=configuration&tag_name=configuration&key_name=name&key_value=conference.conf&Event-Name=COMMAND&Core-UUID=09863f97-160e-4bcf-b0a4-c78fa7f88a2d&FreeSWITCH-Hostname=dev-voice&FreeSWITCH-Switchname=dev-voice&FreeSWITCH-IPv4=165.22.124.94&FreeSWITCH-IPv6=%3A%3A1&Event-Date-Local=2021-05-14%2011%3A50%3A12&Event-Date-GMT=Fri,%2014%20May%202021%2011%3A50%3A12%20GMT&Event-Date-Timestamp=1620993012603797&Event-Calling-File=mod_conference.c&Event-Calling-Function=conference_function&Event-Calling-Line-Number=2016&Event-Sequence=3465&conference_name=CID-8c03e34a-ba6d-44d6-8723-c166fc675ce7&profile_name=default&Fetch-Call-UUID=b9b475f5-019e-4da6-a83d-4061c273f724] 2021-05-14 11:50:12.629869 [DEBUG] mod_conference.c:3414 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie 2021-05-14 11:50:12.643176 [DEBUG] switch_core_media.c:8643 Audio params are unchanged for sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io. 2021-05-14 11:50:12.643176 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io: v=0 o=FreeSWITCH 1620976532 1620976534 IN IP4 165.22.124.94 s=FreeSWITCH c=IN IP4 165.22.124.94 t=0 0 a=msid-semantic: WMS wNWDRYe3i0z9D770VGbaZwW04cSvRWOG m=audio 16480 UDP/TLS/RTP/SAVPF 111 110 a=rtpmap:111 opus/48000/2 a=fmtp:111 useinbandfec=1; minptime=10 a=rtpmap:110 telephone-event/48000 a=ptime:20 a=sendrecv a=fingerprint:sha-256 9E:63:E6:79:2E:CB:0C:5A:65:8F:F4:B7:B3:92:6D:7E:A0:9F:4D:BB:29:FB:B1:23:0D:D6:A9:81:74:9C:63:C4 a=setup:active a=rtcp-mux a=rtcp:16480 IN IP4 165.22.124.94 a=ice-ufrag:LMy9FzoFCLatPl0Y a=ice-pwd:HuJqR7u7TJNp8rNQWJa8I4Tv a=candidate:7220723235 1 udp 659136 165.22.124.94 16480 typ host generation 0 a=end-of-candidates a=ssrc:1638392748 cname:30gO5zG2WOdOiSO2 a=ssrc:1638392748 msid:wNWDRYe3i0z9D770VGbaZwW04cSvRWOG a0 a=ssrc:1638392748 mslabel:wNWDRYe3i0z9D770VGbaZwW04cSvRWOG a=ssrc:1638392748 label:wNWDRYe3i0z9D770VGbaZwW04cSvRWOGa0 2021-05-14 11:50:12.643176 [DEBUG] sofia.c:7325 Channel sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io entering state [completed][200] 2021-05-14 11:50:12.643176 [DEBUG] mod_conference.c:228 Setup timer success interval: 20 samples: 160 error: tport_logging: capture socket error: No error information 2021-05-14 11:50:12.803208 [DEBUG] sofia.c:7325 Channel sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io entering state [ready][200] 2021-05-14 11:50:12.923095 [INFO] switch_rtp.c:3278 Changing audio DTLS state from HANDSHAKE to SETUP 2021-05-14 11:50:12.923095 [INFO] switch_rtp.c:3185 audio Fingerprint Verified. 2021-05-14 11:50:12.923095 [INFO] switch_rtp.c:4217 Activating audio Secure RTP SEND 2021-05-14 11:50:12.923095 [DEBUG] switch_core_sqldb.c:2836 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2021-05-14 11:50:12.923095 [INFO] switch_rtp.c:4195 Activating audio Secure RTP RECV 2021-05-14 11:50:12.923095 [DEBUG] switch_core_sqldb.c:2836 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2021-05-14 11:50:12.923095 [INFO] switch_rtp.c:3227 Changing audio DTLS state from SETUP to READY 2021-05-14 11:50:12.923095 [NOTICE] mod_conference.c:2236 Channel [sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io] has been answered EXECUTE [depth=0] sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io record_session(/var/lib/freeswitch/recordings/b9b475f5-019e-4da6-a83d-4061c273f724.wav) 2021-05-14 11:50:12.943100 [DEBUG] switch_ivr_async.c:1351 Record session sample rate: 48000 -> 48000 2021-05-14 11:50:12.943100 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io 2021-05-14 11:50:12.943100 [DEBUG] switch_channel.c:3865 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Callstate Change EARLY -> ACTIVE 2021-05-14 11:50:12.943100 [DEBUG] conference_member.c:1764 Raw Codec Activation Success L16@48000hz 1 channel 20ms 2021-05-14 11:50:12.943100 [DEBUG] conference_member.c:1811 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2021-05-14 11:50:12.943100 [DEBUG] switch_core_codec.c:223 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Push codec L16:100 2021-05-14 11:50:12.943100 [ERR] switch_core_video.c:3044 This function is not available, libpng not installed 2021-05-14 11:50:12.943100 [DEBUG] conference_loop.c:1338 Setup timer soft success interval: 20 samples: 960 from codec opus 2021-05-14 11:50:12.943100 [NOTICE] switch_core_media.c:15843 Activating write resampler 2021-05-14 11:50:12.943100 [DEBUG] switch_rtp.c:1917 rtcp_stats_init: audio ssrc[2062873529] base_seq[14864] 2021-05-14 11:50:12.943100 [DEBUG] switch_ivr_async.c:1640 No silence detection configured; assuming start of speech 2021-05-14 11:50:13.143095 [DEBUG] conference_member.c:1764 Raw Codec Activation Success L16@48000hz 1 channel 20ms 2021-05-14 11:50:13.143095 [DEBUG] conference_member.c:1811 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2021-05-14 11:50:13.163220 [DEBUG] conference_loop.c:1338 Setup timer soft success interval: 20 samples: 960 from codec opus error: tport_logging: capture socket error: No error information 2021-05-14 11:50:15.423129 [DEBUG] sofia.c:6414 Ping to sip user 'USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io' succeeded with code 200 - count 1, state Reachable 2021-05-14 11:50:17.585527 [NOTICE] sofia.c:1089 Hangup sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [CS_EXECUTE] [NORMAL_CLEARING] error: tport_logging: capture socket error: No error information 2021-05-14 11:50:17.603414 [INFO] conference_loop.c:1665 Channel leaving conference, cause: NORMAL_CLEARING 2021-05-14 11:50:17.603414 [DEBUG] mod_conference.c:2501 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io skip receive message [UNBRIDGE] (channel is hungup already) 2021-05-14 11:50:17.603414 [DEBUG] switch_core_media.c:12370 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io skip receive message [HARD_MUTE] (channel is hungup already) 2021-05-14 11:50:17.603414 [DEBUG] switch_core_codec.c:260 Restore original codec. 2021-05-14 11:50:17.603414 [DEBUG] switch_core_session.c:2905 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2021-05-14 11:50:17.603414 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State EXECUTE going to sleep 2021-05-14 11:50:17.603414 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Running State Change CS_HANGUP (Cur 1 Tot 25) 2021-05-14 11:50:17.603414 [DEBUG] switch_ivr_async.c:1445 Stop recording file /var/lib/freeswitch/recordings/b9b475f5-019e-4da6-a83d-4061c273f724.wav 2021-05-14 11:50:17.603414 [DEBUG] switch_ivr_async.c:1520 Channel is hung up 2021-05-14 11:50:17.625118 [DEBUG] mod_curl.c:192 method: get, url: http://127.0.0.1:1500?recording=b9b475f5-019e-4da6-a83d-4061c273f724.wav&freeswitch_host=165.22.124.94, content-type: (null) 2021-05-14 11:50:17.625118 [DEBUG] mod_conference.c:835 Write Lock ON 2021-05-14 11:50:17.625118 [DEBUG] mod_conference.c:838 Write Lock OFF 2021-05-14 11:50:17.903977 [DEBUG] switch_core_media_bug.c:1295 Removing BUG from sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io 2021-05-14 11:50:17.903977 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Callstate Change ACTIVE -> HANGUP 2021-05-14 11:50:17.903977 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State HANGUP 2021-05-14 11:50:17.903977 [DEBUG] mod_sofia.c:453 Channel sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io hanging up, cause: NORMAL_CLEARING 2021-05-14 11:50:17.903977 [DEBUG] switch_core_state_machine.c:60 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Standard HANGUP, cause: NORMAL_CLEARING 2021-05-14 11:50:17.903977 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State HANGUP going to sleep 2021-05-14 11:50:17.903977 [DEBUG] mod_curl.c:192 method: post, url: http://localhost:3001/mobitalk/freeswitch/call/terminate/b9b475f5-019e-4da6-a83d-4061c273f724, content-type: (null) 2021-05-14 11:50:17.903977 [DEBUG] mod_curl.c:230 Post data: content-type 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:781 Hangup Command with Session curl(http://localhost:3001/mobitalk/freeswitch/call/terminate/b9b475f5-019e-4da6-a83d-4061c273f724 post content-type 'application/json'): {"name":"ServiceUnavailableError","message":"Service unavailable","code":503} 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State Change CS_HANGUP -> CS_REPORTING 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Running State Change CS_REPORTING (Cur 1 Tot 25) 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State REPORTING 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:174 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Standard REPORTING, cause: NORMAL_CLEARING 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State REPORTING going to sleep 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State Change CS_REPORTING -> CS_DESTROY 2021-05-14 11:50:17.923238 [DEBUG] switch_core_session.c:1726 Session 25 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Locked, Waiting on external entities 2021-05-14 11:50:17.923238 [NOTICE] switch_core_session.c:1744 Session 25 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Ended 2021-05-14 11:50:17.923238 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io [CS_DESTROY] 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) Running State Change CS_DESTROY (Cur 0 Tot 25) 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State DESTROY 2021-05-14 11:50:17.923238 [DEBUG] mod_sofia.c:364 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io SOFIA DESTROY 2021-05-14 11:50:17.923238 [DEBUG] mod_opus.c:719 Opus decoder stats: Frames[225] PLC[0] FEC[0] 2021-05-14 11:50:17.923238 [DEBUG] mod_opus.c:734 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2021-05-14 11:50:17.923238 [DEBUG] mod_opus.c:719 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2021-05-14 11:50:17.923238 [DEBUG] mod_opus.c:734 Opus encoder stats: Frames[233] Bytes encoded[7504] Encoded length ms[4660] Average encoded bitrate bps[15008] 2021-05-14 11:50:17.923238 [DEBUG] mod_opus.c:719 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2021-05-14 11:50:17.923238 [DEBUG] mod_opus.c:734 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:181 sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io Standard DESTROY 2021-05-14 11:50:17.923238 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io) State DESTROY going to sleep error: tport_logging: capture socket error: No error information 2021-05-14 11:50:24.543215 [DEBUG] sofia.c:6414 Ping to sip user 'USR-7800c431-e6bf-4af0-9bf7-e03b4bd9ca4f@dev-voice.app.mobitalk.io' succeeded with code 200 - count 2, state Reachable error: tport_logging: capture socket error: No error information 2021-05-14 11:51:00.603186 [DEBUG] sofia.c:6414 Ping to sip user 'USR-4618ebb4-278e-4848-a577-26ae1d5424e3@dev-voice.app.mobitalk.io' succeeded with code 200 - count 2, state Reachable error: tport_logging: capture socket error: No error information freeswitch@dev-voice> ...