Skip to content

Instantly share code, notes, and snippets.

@andrius

andrius/logs.txt

Created May 14, 2021 11:57
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save andrius/a680f27fd8c36ca48439eda058bd8d5a to your computer and use it in GitHub Desktop.
Save andrius/a680f27fd8c36ca48439eda058bd8d5a to your computer and use it in GitHub Desktop.
Download ZIP
Raw
logs.txt
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&section=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&section=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> ...
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment