2017-02-24 11:45:38.128732 [NOTICE] mod_logfile.c:192 New log started. recv 1221 bytes from udp/[172.21.0.12]:5060 at 11:45:45.464602: ------------------------------------------------------------------------ INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0 Record-Route: Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK04d1.dcaf147d671512068d390cdcdcd54a39.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-0 Max-Forwards: 43 To: "3613612202" From: "3613612209" ;tag=1 Call-ID: 1-1760@172.21.0.11 CSeq: 1 INVITE Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,MESSAGE,SUBSCRIBE,INFO Content-Type: application/sdp User-Agent: eyeBeam release 9330a stamp 37653 Content-Length: 434 Server: SIPp Server P-Asserted-Identity: "3613612209" Contact: v=0 o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79 s=CounterPath eyeBeam 1.5 c=IN IP4 10.112.48.79 t=0 0 m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101 a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578 a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578 a=fmtp:101 0-15 a=rtpmap:119 BV32-FEC/16000 a=rtpmap:98 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC ------------------------------------------------------------------------ send 439 bytes to udp/[172.21.0.12]:5060 at 11:45:45.465013: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK04d1.dcaf147d671512068d390cdcdcd54a39.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-0 Record-Route: From: "3613612209" ;tag=1 To: "3613612202" Call-ID: 1-1760@172.21.0.11 CSeq: 1 INVITE User-Agent: Voicis WebRTC Gateway Content-Length: 0 ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [NOTICE] switch_channel.c:1104 New Channel sofia/external/3613612209@webrtc.example.com [555a348b-6f43-4c9e-911a-45708a9e404c] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_NEW (Cur 1 Tot 7) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] sofia.c:9819 sofia/external/3613612209@webrtc.example.com receiving invite from 172.21.0.12:5060 version: 1.6.15 -32-bec4538 64bit 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [received][100] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] sofia.c:7052 Remote SDP: 555a348b-6f43-4c9e-911a-45708a9e404c v=0 555a348b-6f43-4c9e-911a-45708a9e404c o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79 555a348b-6f43-4c9e-911a-45708a9e404c s=CounterPath eyeBeam 1.5 555a348b-6f43-4c9e-911a-45708a9e404c c=IN IP4 10.112.48.79 555a348b-6f43-4c9e-911a-45708a9e404c t=0 0 555a348b-6f43-4c9e-911a-45708a9e404c m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:119 BV32-FEC/16000 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:98 iLBC/8000 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:101 telephone-event/8000 555a348b-6f43-4c9e-911a-45708a9e404c a=fmtp:101 0-15 555a348b-6f43-4c9e-911a-45708a9e404c a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578 555a348b-6f43-4c9e-911a-45708a9e404c a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578 555a348b-6f43-4c9e-911a-45708a9e404c a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC 555a348b-6f43-4c9e-911a-45708a9e404c 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [BV32-FEC:119:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [BV32-FEC:119:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [iLBC:98:8000:30:13330:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [iLBC:98:8000:30:13330:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4316 Set telephone-event payload to 101@8000 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:3047 Set Codec sofia/external/3613612209@webrtc.example.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_codec.c:111 sofia/external/3613612209@webrtc.example.com Original read codec set to PCMU:0 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4659 Set telephone-event payload to 101@8000 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_media.c:4718 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf send payload to 101 recv payload to 101 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] sofia.c:7465 (sofia/external/3613612209@webrtc.example.com) State Change CS_NEW -> CS_INIT 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:603 (sofia/external/3613612209@webrtc.example.com) State NEW 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_INIT (Cur 1 Tot 7) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:627 (sofia/external/3613612209@webrtc.example.com) State INIT 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] mod_sofia.c:90 sofia/external/3613612209@webrtc.example.com SOFIA INIT 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:40 sofia/external/3613612209@webrtc.example.com Standard INIT 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:48 (sofia/external/3613612209@webrtc.example.com) State Change CS_INIT -> CS_ROUTING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:627 (sofia/external/3613612209@webrtc.example.com) State INIT going to sleep 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_ROUTING (Cur 1 Tot 7) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_channel.c:2249 (sofia/external/3613612209@webrtc.example.com) Callstate Change DOWN -> RINGING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:643 (sofia/external/3613612209@webrtc.example.com) State ROUTING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] mod_sofia.c:143 sofia/external/3613612209@webrtc.example.com SOFIA ROUTING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:236 sofia/external/3613612209@webrtc.example.com Standard ROUTING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [INFO] mod_dialplan_xml.c:637 Processing 3613612209 <3613612209>->3613612202 in context public 555a348b-6f43-4c9e-911a-45708a9e404c Dialplan: sofia/external/3613612209@webrtc.example.com parsing [public->call_debug] continue=true 555a348b-6f43-4c9e-911a-45708a9e404c Dialplan: sofia/external/3613612209@webrtc.example.com Absolute Condition [call_debug] 555a348b-6f43-4c9e-911a-45708a9e404c Dialplan: sofia/external/3613612209@webrtc.example.com Action info() 555a348b-6f43-4c9e-911a-45708a9e404c Dialplan: sofia/external/3613612209@webrtc.example.com parsing [public->public_extensions] continue=false 555a348b-6f43-4c9e-911a-45708a9e404c Dialplan: sofia/external/3613612209@webrtc.example.com Absolute Condition [public_extensions] 555a348b-6f43-4c9e-911a-45708a9e404c Dialplan: sofia/external/3613612209@webrtc.example.com Action bridge(${verto_contact(${destination_number}@webrtc.example.com)}) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:286 (sofia/external/3613612209@webrtc.example.com) State Change CS_ROUTING -> CS_EXECUTE 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:643 (sofia/external/3613612209@webrtc.example.com) State ROUTING going to sleep 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_EXECUTE (Cur 1 Tot 7) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:650 (sofia/external/3613612209@webrtc.example.com) State EXECUTE 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] mod_sofia.c:198 sofia/external/3613612209@webrtc.example.com SOFIA EXECUTE 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [DEBUG] switch_core_state_machine.c:328 sofia/external/3613612209@webrtc.example.com Standard EXECUTE 555a348b-6f43-4c9e-911a-45708a9e404c EXECUTE sofia/external/3613612209@webrtc.example.com info() 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.448836 [INFO] mod_dptools.c:1740 CHANNEL_DATA: 555a348b-6f43-4c9e-911a-45708a9e404c Channel-State: [CS_EXECUTE] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Call-State: [RINGING] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-State-Number: [4] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Name: [sofia/external/3613612209@webrtc.example.com] 555a348b-6f43-4c9e-911a-45708a9e404c Unique-ID: [555a348b-6f43-4c9e-911a-45708a9e404c] 555a348b-6f43-4c9e-911a-45708a9e404c Call-Direction: [inbound] 555a348b-6f43-4c9e-911a-45708a9e404c Presence-Call-Direction: [inbound] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-HIT-Dialplan: [true] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Call-UUID: [555a348b-6f43-4c9e-911a-45708a9e404c] 555a348b-6f43-4c9e-911a-45708a9e404c Answer-State: [ringing] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Read-Codec-Name: [PCMU] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Read-Codec-Rate: [8000] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Read-Codec-Bit-Rate: [64000] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Write-Codec-Name: [PCMU] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Write-Codec-Rate: [8000] 555a348b-6f43-4c9e-911a-45708a9e404c Channel-Write-Codec-Bit-Rate: [64000] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Direction: [inbound] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Logical-Direction: [inbound] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Username: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Dialplan: [XML] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Caller-ID-Name: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Caller-ID-Number: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Orig-Caller-ID-Name: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Orig-Caller-ID-Number: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Network-Addr: [172.21.0.12] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-ANI: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Destination-Number: [3613612202] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Unique-ID: [555a348b-6f43-4c9e-911a-45708a9e404c] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Source: [mod_sofia] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Context: [public] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Name: [sofia/external/3613612209@webrtc.example.com] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Profile-Index: [1] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Profile-Created-Time: [1487936745448836] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Created-Time: [1487936745448836] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Answered-Time: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Progress-Time: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Progress-Media-Time: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Hangup-Time: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Transfer-Time: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Resurrect-Time: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Bridged-Time: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Last-Hold: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Channel-Hold-Accum: [0] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Screen-Bit: [true] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Privacy-Hide-Name: [false] 555a348b-6f43-4c9e-911a-45708a9e404c Caller-Privacy-Hide-Number: [false] 555a348b-6f43-4c9e-911a-45708a9e404c variable_direction: [inbound] 555a348b-6f43-4c9e-911a-45708a9e404c variable_uuid: [555a348b-6f43-4c9e-911a-45708a9e404c] 555a348b-6f43-4c9e-911a-45708a9e404c variable_session_id: [7] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_from_user: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_from_uri: [3613612209@webrtc.example.com] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_from_host: [webrtc.example.com] 555a348b-6f43-4c9e-911a-45708a9e404c variable_video_media_flow: [sendrecv] 555a348b-6f43-4c9e-911a-45708a9e404c variable_channel_name: [sofia/external/3613612209@webrtc.example.com] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_call_id: [1-1760@172.21.0.11] 555a348b-6f43-4c9e-911a-45708a9e404c variable_ep_codec_string: [CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h@20i@64000b] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_local_network_addr: [172.21.0.10] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_network_ip: [172.21.0.12] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_network_port: [5060] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_received_ip: [172.21.0.12] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_received_port: [5060] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_via_protocol: [udp] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_from_user_stripped: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_from_tag: [1] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sofia_profile_name: [external] 555a348b-6f43-4c9e-911a-45708a9e404c variable_recovery_profile_name: [external] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_P-Asserted-Identity: ["3613612209" ] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_cid_type: [pid] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_invite_route_uri: [] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_invite_record_route: [] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_full_via: [SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK04d1.dcaf147d671512068d390cdcdcd54a39.0,SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-0] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_from_display: [3613612209] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_full_from: ["3613612209" ;tag=1] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_to_display: [3613612202] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_full_to: ["3613612202" ] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_allow: [INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_req_user: [3613612202] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_req_port: [5080] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_req_uri: [3613612202@172.21.0.10:5080] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_req_host: [172.21.0.10] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_to_user: [3613612202] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_to_uri: [3613612202@webrtc.example.com] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_to_host: [webrtc.example.com] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_contact_params: [abcct=7369703a31302e3131322e32362e38353a35303630] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_contact_user: [siplb] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_contact_port: [5061] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_contact_uri: [siplb@172.21.0.11:5061] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_contact_host: [172.21.0.11] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_user_agent: [eyeBeam release 9330a stamp 37653] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_via_host: [172.21.0.12] 555a348b-6f43-4c9e-911a-45708a9e404c variable_max_forwards: [43] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_i_from: ["3613612209" ;tag=1] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_i_to: ["3613612202" ] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_i_call_id: [1-1760@172.21.0.11] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_i_cseq: [1 INVITE] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_i_max_forwards: [43] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_i_contact: [] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_i_user_agent: [eyeBeam release 9330a stamp 37653] 555a348b-6f43-4c9e-911a-45708a9e404c variable_sip_i_allow: [INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO] variable_sip_i_content_type: [application/sdp] variable_sip_i_content_length: [434] variable_sip_i_via: [ARRAY::SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK04d1.dcaf147d671512068d390cdcdcd54a39.0|:SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-0] variable_sip_i_record_route: [] variable_sip_i_p_asserted_identity: ["3613612209" ] variable_switch_r_sdp: [v=0 o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79 s=CounterPath eyeBeam 1.5 c=IN IP4 10.112.48.79 t=0 0 m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101 a=rtpmap:119 BV32-FEC/16000 a=rtpmap:98 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578 a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578 a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC ] variable_audio_media_flow: [sendrecv] variable_rtp_use_codec_string: [PCMA,PCMU] variable_remote_media_ip: [10.112.48.79] variable_remote_media_port: [60578] variable_rtp_audio_recv_pt: [0] variable_rtp_use_codec_name: [PCMU] variable_rtp_use_codec_rate: [8000] variable_rtp_use_codec_ptime: [20] variable_rtp_use_codec_channels: [1] variable_rtp_last_audio_codec_string: [PCMU@8000h@20i@1c] variable_read_codec: [PCMU] variable_original_read_codec: [PCMU] variable_read_rate: [8000] variable_original_read_rate: [8000] variable_write_codec: [PCMU] variable_write_rate: [8000] variable_dtmf_type: [rfc2833] variable_endpoint_disposition: [RECEIVED] variable_call_uuid: [555a348b-6f43-4c9e-911a-45708a9e404c] variable_current_application: [info] 555a348b-6f43-4c9e-911a-45708a9e404c EXECUTE sofia/external/3613612209@webrtc.example.com bridge(verto.rtc/u:d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.468721 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] mod_rtc.c:389 () State Change CS_NEW -> CS_INIT 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a [2b2096b0-99cd-44a2-92e2-9123163e2c23] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_INIT (Cur 2 Tot 8) 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State INIT 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] mod_verto.c:2218 Local verto.invite SDP verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a: 2b2096b0-99cd-44a2-92e2-9123163e2c23 v=0 2b2096b0-99cd-44a2-92e2-9123163e2c23 o=FreeSWITCH 1487911905 1487911906 IN IP4 172.21.0.10 2b2096b0-99cd-44a2-92e2-9123163e2c23 s=FreeSWITCH 2b2096b0-99cd-44a2-92e2-9123163e2c23 c=IN IP4 172.21.0.10 2b2096b0-99cd-44a2-92e2-9123163e2c23 t=0 0 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=msid-semantic: WMS Efvlg6fgTzT5wsT864WOI0bJFuE26wgA 2b2096b0-99cd-44a2-92e2-9123163e2c23 m=audio 24840 RTP/SAVPF 0 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=rtpmap:0 PCMU/8000 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=fingerprint:sha-256 41:97:2A:DD:7A:AD:58:E8:BC:50:42:80:46:1D:35:55:CE:58:97:C3:93:7A:2F:93:70:0C:8B:CE:77:B8:1A:42 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=setup:actpass 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=rtcp-mux 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=rtcp:24840 IN IP4 172.21.0.10 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ssrc:817022761 cname:BCLHyQOigxeCPXBM 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ssrc:817022761 msid:Efvlg6fgTzT5wsT864WOI0bJFuE26wgA a0 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ssrc:817022761 mslabel:Efvlg6fgTzT5wsT864WOI0bJFuE26wgA 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ssrc:817022761 label:Efvlg6fgTzT5wsT864WOI0bJFuE26wgAa0 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ice-ufrag:Snrqes0JNvCJRJaj 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ice-pwd:bLQAsvIzSM1yztaiGryLKpkn 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:6742118952 1 udp 659136 172.21.0.10 24840 typ host generation 0 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:6742118952 2 udp 659136 172.21.0.10 24840 typ host generation 0 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=silenceSupp:off - - - - 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ptime:20 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=sendrecv 2b2096b0-99cd-44a2-92e2-9123163e2c23 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [NOTICE] mod_verto.c:2318 Ring-Ready verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a! 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_channel.c:3345 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Callstate Change DOWN -> RINGING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:40 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Standard INIT 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_INIT -> CS_ROUTING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State INIT going to sleep 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_ROUTING (Cur 2 Tot 8) 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State ROUTING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] mod_rtc.c:89 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a RTC ROUTING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_ivr_originate.c:67 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State ROUTING going to sleep 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 8) 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State CONSUME_MEDIA 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:45.468721 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State CONSUME_MEDIA going to sleep 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.468721 [NOTICE] mod_sofia.c:2263 Ring-Ready sofia/external/3613612209@webrtc.example.com! send 810 bytes to udp/[172.21.0.12]:5060 at 11:45:45.472557: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK04d1.dcaf147d671512068d390cdcdcd54a39.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-0 Record-Route: From: "3613612209" ;tag=1 To: "3613612202" ;tag=04SeBK9031yZH Call-ID: 1-1760@172.21.0.11 CSeq: 1 INVITE Contact: User-Agent: Voicis WebRTC Gateway Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Length: 0 P-Asserted-Identity: "Outbound Call" ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.468721 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [early][180] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:45.468721 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/external/3613612209@webrtc.example.com! 2017-02-24 11:45:45.468721 [ALERT] mod_verto.c:604 WRITE 172.21.0.1:45670 [{ "jsonrpc": "2.0", "id": 7, "method": "verto.invite", "params": { "callID": "2b2096b0-99cd-44a2-92e2-9123163e2c23", "sdp": "v=0\r\no=FreeSWITCH 1487911905 1487911906 IN IP4 172.21.0.10\r\ns=FreeSWITCH\r\nc=IN IP4 172.21.0.10\r\nt=0 0\r\na=msid-semantic: WMS Efvlg6fgTzT5wsT864WOI0bJFuE26wgA\r\nm=audio 24840 RTP/SAVPF 0\r\na=rtpmap:0 PCMU/8000\r\na=fingerprint:sha-256 41:97:2A:DD:7A:AD:58:E8:BC:50:42:80:46:1D:35:55:CE:58:97:C3:93:7A:2F:93:70:0C:8B:CE:77:B8:1A:42\r\na=setup:actpass\r\na=rtcp-mux\r\na=rtcp:24840 IN IP4 172.21.0.10\r\na=ssrc:817022761 cname:BCLHyQOigxeCPXBM\r\na=ssrc:817022761 msid:Efvlg6fgTzT5wsT864WOI0bJFuE26wgA a0\r\na=ssrc:817022761 mslabel:Efvlg6fgTzT5wsT864WOI0bJFuE26wgA\r\na=ssrc:817022761 label:Efvlg6fgTzT5wsT864WOI0bJFuE26wgAa0\r\na=ice-ufrag:Snrqes0JNvCJRJaj\r\na=ice-pwd:bLQAsvIzSM1yztaiGryLKpkn\r\na=candidate:6742118952 1 udp 659136 172.21.0.10 24840 typ host generation 0\r\na=candidate:6742118952 2 udp 659136 172.21.0.10 24840 typ host generation 0\r\na=silenceSupp:off - - - -\r\na=ptime:20\r\na=sendrecv\r\n", "caller_id_name": "3613612209", "caller_id_number": "3613612209", "callee_id_name": "Outbound Call", "callee_id_number": "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a", "display_direction": "outbound" } }] 2017-02-24 11:45:45.488712 [ALERT] mod_verto.c:1373 READ 172.21.0.1:45670 [{ "jsonrpc": "2.0", "id": 7, "result": { "method": "verto.invite" } }] 2017-02-24 11:45:51.888717 [ALERT] mod_verto.c:1373 READ 172.21.0.1:45670 [{ "jsonrpc": "2.0", "method": "verto.answer", "params": { "sdp": "v=0\r\no=- 2920493074630100594 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=msid-semantic: WMS IhpAc4XXDgFsnSeQ8fIDWrDpeMeFS3LRi4cD\r\nm=audio 60033 RTP/SAVPF 0\r\nc=IN IP4 172.17.0.1\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:3885250869 1 udp 2122260223 172.17.0.1 60033 typ host generation 0 network-id 2 network-cost 50\r\na=candidate:410389623 1 udp 2122194687 172.21.0.1 59239 typ host generation 0 network-id 1 network-cost 50\r\na=candidate:88617392 1 udp 2122129151 10.178.17.174 52501 typ host generation 0 network-id 4\r\na=candidate:1019731727 1 udp 2122063615 192.168.1.104 52565 typ host generation 0 network-id 3\r\na=candidate:2836907461 1 tcp 1518280447 172.17.0.1 9 typ host tcptype active generation 0 network-id 2 network-cost 50\r\na=candidate:1458958471 1 tcp 1518214911 172.21.0.1 9 typ host tcptype active generation 0 network-id 1 network-cost 50\r\na=candidate:1271647552 1 tcp 1518149375 10.178.17.174 9 typ host tcptype active generation 0 network-id 4\r\na=candidate:1917068287 1 tcp 1518083839 192.168.1.104 9 typ host tcptype active generation 0 network-id 3\r\na=ice-ufrag:Ld2D\r\na=ice-pwd:Zbp5uVdNNGFtlqswFpEfA2tp\r\na=fingerprint:sha-256 8F:EE:D1:EC:92:57:42:C3:B7:15:5C:9A:30:DC:D5:B3:60:35:C0:E5:3B:9D:3C:B2:0E:FF:D3:F9:C2:6E:B5:38\r\na=setup:active\r\na=mid:audio\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:0 PCMU/8000\r\na=ssrc:1882092159 cname:R7f+qKM+2wuh5nfh\r\na=ssrc:1882092159 msid:IhpAc4XXDgFsnSeQ8fIDWrDpeMeFS3LRi4cD 63951c90-5ce1-4823-a060-8fa6f05ecf42\r\na=ssrc:1882092159 mslabel:IhpAc4XXDgFsnSeQ8fIDWrDpeMeFS3LRi4cD\r\na=ssrc:1882092159 label:63951c90-5ce1-4823-a060-8fa6f05ecf42\r\n", "dialogParams": { "screenShare": false, "useMic": "any", "useSpeak": "any", "tag": "webcam", "localTag": null, "login": "3613612202@webrtc.example.com", "videoParams": { "minWidth": "1280", "minHeight": "720", "minFrameRate": 30 }, "callID": "2b2096b0-99cd-44a2-92e2-9123163e2c23", "caller_id_name": "3613612209", "caller_id_number": "3613612209", "callee_id_name": "3613612202", "callee_id_number": "3613612202", "display_direction": "outbound", "remote_caller_id_name": "3613612209", "remote_caller_id_number": "3613612209" }, "sessid": "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a" }, "id": 23 }] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.888717 [DEBUG] mod_verto.c:2673 Remote SDP verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a: 2b2096b0-99cd-44a2-92e2-9123163e2c23 v=0 2b2096b0-99cd-44a2-92e2-9123163e2c23 o=- 2920493074630100594 2 IN IP4 127.0.0.1 2b2096b0-99cd-44a2-92e2-9123163e2c23 s=- 2b2096b0-99cd-44a2-92e2-9123163e2c23 t=0 0 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=msid-semantic: WMS IhpAc4XXDgFsnSeQ8fIDWrDpeMeFS3LRi4cD 2b2096b0-99cd-44a2-92e2-9123163e2c23 m=audio 60033 RTP/SAVPF 0 2b2096b0-99cd-44a2-92e2-9123163e2c23 c=IN IP4 172.17.0.1 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=rtcp:9 IN IP4 0.0.0.0 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:3885250869 1 udp 2122260223 172.17.0.1 60033 typ host generation 0 network-id 2 network-cost 50 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:410389623 1 udp 2122194687 172.21.0.1 59239 typ host generation 0 network-id 1 network-cost 50 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:88617392 1 udp 2122129151 10.178.17.174 52501 typ host generation 0 network-id 4 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:1019731727 1 udp 2122063615 192.168.1.104 52565 typ host generation 0 network-id 3 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:2836907461 1 tcp 1518280447 172.17.0.1 9 typ host tcptype active generation 0 network-id 2 network-cost 50 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:1458958471 1 tcp 1518214911 172.21.0.1 9 typ host tcptype active generation 0 network-id 1 network-cost 50 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:1271647552 1 tcp 1518149375 10.178.17.174 9 typ host tcptype active generation 0 network-id 4 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=candidate:1917068287 1 tcp 1518083839 192.168.1.104 9 typ host tcptype active generation 0 network-id 3 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ice-ufrag:Ld2D 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ice-pwd:Zbp5uVdNNGFtlqswFpEfA2tp 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=fingerprint:sha-256 8F:EE:D1:EC:92:57:42:C3:B7:15:5C:9A:30:DC:D5:B3:60:35:C0:E5:3B:9D:3C:B2:0E:FF:D3:F9:C2:6E:B5:38 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=setup:active 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=mid:audio 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=sendrecv 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=rtcp-mux 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=rtpmap:0 PCMU/8000 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ssrc:1882092159 cname:R7f+qKM+2wuh5nfh 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ssrc:1882092159 msid:IhpAc4XXDgFsnSeQ8fIDWrDpeMeFS3LRi4cD 63951c90-5ce1-4823-a060-8fa6f05ecf42 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ssrc:1882092159 mslabel:IhpAc4XXDgFsnSeQ8fIDWrDpeMeFS3LRi4cD 2b2096b0-99cd-44a2-92e2-9123163e2c23 a=ssrc:1882092159 label:63951c90-5ce1-4823-a060-8fa6f05ecf42 2b2096b0-99cd-44a2-92e2-9123163e2c23 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [WARNING] switch_ivr.c:3858 can't find user [3613612202@webrtc.example.com] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3047 Set Codec verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_codec.c:111 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Original read codec set to PCMU:0 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3455 Save audio Candidate cid: 1 proto: udp type: host addr: 172.17.0.1:60033 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3455 Save audio Candidate cid: 1 proto: udp type: host addr: 172.21.0.1:59239 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3455 Save audio Candidate cid: 1 proto: udp type: host addr: 10.178.17.174:52501 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3455 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.1.104:52565 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3497 Searching for rtp candidate. 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3502 Choose rtp candidate, index 1, 172.21.0.1:59239 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3276 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a choosing family v4 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3513 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 172.21.0.1:59239 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3558 setting remote audio ice addr to index 1 172.21.0.1:59239 based on candidate 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:3579 Setting remote rtcp audio addr to 172.21.0.1:59239 based on candidate 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:4725 No 2833 in SDP. Disable 2833 dtmf and switch to INFO 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:6732 AUDIO RTP [verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a] 172.21.0.10 port 24840 -> 172.21.0.1 port 59239 codec: 0 ms: 20 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_rtp.c:3878 Starting timer [soft] 160 bytes per 20ms 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [INFO] switch_core_media.c:6914 Activating Audio ICE 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [NOTICE] switch_rtp.c:4367 Activating RTP audio ICE: Ld2D:Snrqes0JNvCJRJaj 172.21.0.1:59239 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:6957 Activating RTCP PORT 59239 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_rtp.c:4264 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 59239 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [INFO] switch_core_media.c:6965 Skipping RTCP ICE (Same as RTP) 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [INFO] switch_rtp.c:3371 Activate RTP/RTCP audio DTLS server 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [INFO] switch_rtp.c:3505 Changing audio DTLS state from OFF to HANDSHAKE 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:2017 Setting Jitterbuffer to 20ms (1 frames) (50 max frames) 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:6715 Audio params are unchanged for verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a. 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [NOTICE] mod_verto.c:2707 Channel [verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a] has been answered 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:6732 AUDIO RTP [sofia/external/3613612209@webrtc.example.com] 172.21.0.10 port 18918 -> 10.112.48.79 port 60578 codec: 0 ms: 20 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] switch_rtp.c:3878 Starting timer [soft] 160 bytes per 20ms 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.908721 [DEBUG] switch_channel.c:3772 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Callstate Change RINGING -> ACTIVE 2017-02-24 11:45:51.908721 [ALERT] mod_verto.c:604 WRITE 172.21.0.1:45670 [{ "jsonrpc": "2.0", "id": 23, "result": { "sessid": "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a" } }] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:7038 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf send payload to 101 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:7045 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf receive payload to 101 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] switch_core_media.c:7068 sofia/external/3613612209@webrtc.example.com Set rtp dtmf delay to 40 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/3613612209@webrtc.example.com: 555a348b-6f43-4c9e-911a-45708a9e404c v=0 555a348b-6f43-4c9e-911a-45708a9e404c o=FreeSWITCH 1487917833 1487917834 IN IP4 172.21.0.10 555a348b-6f43-4c9e-911a-45708a9e404c s=FreeSWITCH 555a348b-6f43-4c9e-911a-45708a9e404c c=IN IP4 172.21.0.10 555a348b-6f43-4c9e-911a-45708a9e404c t=0 0 555a348b-6f43-4c9e-911a-45708a9e404c m=audio 18918 RTP/AVP 0 101 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:0 PCMU/8000 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:101 telephone-event/8000 555a348b-6f43-4c9e-911a-45708a9e404c a=fmtp:101 0-16 555a348b-6f43-4c9e-911a-45708a9e404c a=ptime:20 555a348b-6f43-4c9e-911a-45708a9e404c a=sendrecv 555a348b-6f43-4c9e-911a-45708a9e404c 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [NOTICE] switch_ivr_originate.c:3628 Channel [sofia/external/3613612209@webrtc.example.com] has been answered 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] switch_channel.c:3772 (sofia/external/3613612209@webrtc.example.com) Callstate Change RINGING -> ACTIVE 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] switch_ivr_originate.c:3686 Originate Resulted in Success: [verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a] send 1032 bytes to udp/[172.21.0.12]:5060 at 11:45:51.925850: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK04d1.dcaf147d671512068d390cdcdcd54a39.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-0 Record-Route: From: "3613612209" ;tag=1 To: "3613612202" ;tag=04SeBK9031yZH Call-ID: 1-1760@172.21.0.11 CSeq: 1 INVITE Contact: User-Agent: Voicis WebRTC Gateway Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 218 P-Asserted-Identity: "3613612202" v=0 o=FreeSWITCH 1487917833 1487917834 IN IP4 172.21.0.10 s=FreeSWITCH c=IN IP4 172.21.0.10 t=0 0 m=audio 18918 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.908721 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [completed][200] recv 559 bytes from udp/[172.21.0.12]:5060 at 11:45:51.929218: ------------------------------------------------------------------------ ACK sip:3613612202@172.21.0.10:5080 SIP/2.0 Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK04d1.ec7e5899ea4b6a853992528d8b4d0bf7.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-4 Max-Forwards: 43 To: "3613612202" ;tag=04SeBK9031yZH From: "3613612209" ;tag=1 Call-ID: 1-1760@172.21.0.11 CSeq: 1 ACK User-Agent: eyeBeam release 9330a stamp 37653 Content-Length: 0 Server: SIPp Server Contact: ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:51.928706 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [ready][200] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.928706 [DEBUG] switch_core_media.c:9969 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a PAUSE Jitterbuffer 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.928706 [DEBUG] switch_ivr_bridge.c:1566 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.928706 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 8) 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.928706 [DEBUG] switch_core_state_machine.c:653 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State EXCHANGE_MEDIA 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.928706 [DEBUG] mod_rtc.c:146 RTC EXCHANGE_MEDIA 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.948705 [INFO] switch_rtp.c:3162 Changing audio DTLS state from HANDSHAKE to SETUP 2017-02-24 11:45:51.968704 [ALERT] mod_verto.c:604 WRITE 172.21.0.1:45670 [{ "jsonrpc": "2.0", "id": 8, "method": "verto.display", "params": { "callID": "2b2096b0-99cd-44a2-92e2-9123163e2c23", "display_name": "3613612209", "display_number": "3613612209", "caller_id_name": "3613612209", "caller_id_number": "3613612209", "callee_id_name": "3613612202", "callee_id_number": "3613612202", "display_direction": "outbound" } }] 2017-02-24 11:45:51.968704 [ALERT] mod_verto.c:1373 READ 172.21.0.1:45670 [{ "jsonrpc": "2.0", "id": 8, "result": { "method": "verto.display" } }] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.968704 [INFO] switch_rtp.c:3071 audio Fingerprint Verified. 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.968704 [INFO] switch_rtp.c:3690 Activating Audio Secure RTP SEND 2017-02-24 11:45:51.968704 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.968704 [INFO] switch_rtp.c:3668 Activating Audio Secure RTP RECV 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.968704 [INFO] switch_rtp.c:3111 Changing audio DTLS state from SETUP to READY 2017-02-24 11:45:51.968704 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:45:51.988706 [DEBUG] switch_rtp.c:1878 rtcp_stats_init: audio ssrc[1882092159] base_seq[3868] recv 1006 bytes from udp/[172.21.0.12]:5060 at 11:45:53.931488: ------------------------------------------------------------------------ INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0 Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKd3d1.99f4017bf7a35d519e82f851a7273079.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-6 Max-Forwards: 43 To: "3613612202" ;tag=04SeBK9031yZH From: "3613612209" ;tag=1 Call-ID: 1-1760@172.21.0.11 CSeq: 2 INVITE Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,MESSAGE,SUBSCRIBE,INFO Content-Type: application/sdp User-Agent: eyeBeam release 9330a stamp 37653 Content-Length: 260 Server: SIPp Server P-Asserted-Identity: "3613612209" Contact: v=0 o=ipjib 1487263187420 1487263187421 IN IP4 10.112.48.79 s=CounterPath eyeBeam 1.5 c=IN IP4 0.0.0.0 t=0 0 m=audio 60578 RTP/AVP 0 101 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendonly a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC ------------------------------------------------------------------------ send 398 bytes to udp/[172.21.0.12]:5060 at 11:45:53.931831: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKd3d1.99f4017bf7a35d519e82f851a7273079.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-6 From: "3613612209" ;tag=1 To: "3613612202" ;tag=04SeBK9031yZH Call-ID: 1-1760@172.21.0.11 CSeq: 2 INVITE User-Agent: Voicis WebRTC Gateway Content-Length: 0 ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [received][100] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] sofia.c:7052 Remote SDP: 555a348b-6f43-4c9e-911a-45708a9e404c v=0 555a348b-6f43-4c9e-911a-45708a9e404c o=ipjib 1487263187420 1487263187421 IN IP4 10.112.48.79 555a348b-6f43-4c9e-911a-45708a9e404c s=CounterPath eyeBeam 1.5 555a348b-6f43-4c9e-911a-45708a9e404c c=IN IP4 0.0.0.0 555a348b-6f43-4c9e-911a-45708a9e404c t=0 0 555a348b-6f43-4c9e-911a-45708a9e404c m=audio 60578 RTP/AVP 0 101 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:101 telephone-event/8000 555a348b-6f43-4c9e-911a-45708a9e404c a=fmtp:101 0-15 555a348b-6f43-4c9e-911a-45708a9e404c a=sendonly 555a348b-6f43-4c9e-911a-45708a9e404c a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC 555a348b-6f43-4c9e-911a-45708a9e404c 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [WARNING] switch_core_media.c:3895 RFC2543 from March 1999 called; They want their 0.0.0.0 hold method back..... 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_channel.c:1844 (sofia/external/3613612209@webrtc.example.com) Callstate Change ACTIVE -> HELD 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:4316 Set telephone-event payload to 101@8000 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:4659 Set telephone-event payload to 101@8000 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:4718 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf send payload to 101 recv payload to 101 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:6721 Audio params changed for sofia/external/3613612209@webrtc.example.com from 10.112.48.79:60578 to 0.0.0.0:60578 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:6732 AUDIO RTP [sofia/external/3613612209@webrtc.example.com] 172.21.0.10 port 18918 -> 0.0.0.0 port 60578 codec: 0 ms: 20 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] switch_core_media.c:6761 AUDIO RTP CHANGING DEST TO: [0.0.0.0:60578] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.928709 [DEBUG] sofia.c:7942 Processing updated SDP send 894 bytes to udp/[172.21.0.12]:5060 at 11:45:53.945513: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKd3d1.99f4017bf7a35d519e82f851a7273079.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-6 From: "3613612209" ;tag=1 To: "3613612202" ;tag=04SeBK9031yZH Call-ID: 1-1760@172.21.0.11 CSeq: 2 INVITE Contact: User-Agent: Voicis WebRTC Gateway Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 230 v=0 o=FreeSWITCH 1487917833 1487917835 IN IP4 172.21.0.10 s=FreeSWITCH c=IN IP4 172.21.0.10 t=0 0 m=audio 18918 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=recvonly a=ptime:20 ------------------------------------------------------------------------ recv 560 bytes from udp/[172.21.0.12]:5060 at 11:45:53.946564: ------------------------------------------------------------------------ ACK sip:3613612202@172.21.0.10:5080 SIP/2.0 Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKd3d1.05268c3cdeef6c0447b1f41dc8422a5e.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-10 Max-Forwards: 43 To: "3613612202" ;tag=04SeBK9031yZH From: "3613612209" ;tag=1 Call-ID: 1-1760@172.21.0.11 CSeq: 2 ACK User-Agent: eyeBeam release 9330a stamp 37653 Content-Length: 0 Server: SIPp Server Contact: ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.968713 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [completed][200] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:53.968713 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [ready][200] recv 1180 bytes from udp/[172.21.0.12]:5060 at 11:45:55.949065: ------------------------------------------------------------------------ INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0 Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKe3d1.056c93ff816cb5eae23ce769cff7a919.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-12 Max-Forwards: 43 To: "3613612202" ;tag=04SeBK9031yZH From: "3613612201" ;tag=1 Call-ID: 1-1760@172.21.0.11 CSeq: 3 INVITE Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,MESSAGE,SUBSCRIBE,INFO Content-Type: application/sdp User-Agent: eyeBeam release 9330a stamp 37653 Content-Length: 434 Server: SIPp Server P-Asserted-Identity: "3613612201" Contact: v=0 o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79 s=CounterPath eyeBeam 1.5 c=IN IP4 10.112.48.79 t=0 0 m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101 a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578 a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578 a=fmtp:101 0-15 a=rtpmap:119 BV32-FEC/16000 a=rtpmap:98 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC ------------------------------------------------------------------------ send 399 bytes to udp/[172.21.0.12]:5060 at 11:45:55.949468: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKe3d1.056c93ff816cb5eae23ce769cff7a919.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-12 From: "3613612201" ;tag=1 To: "3613612202" ;tag=04SeBK9031yZH Call-ID: 1-1760@172.21.0.11 CSeq: 3 INVITE User-Agent: Voicis WebRTC Gateway Content-Length: 0 ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:55.948715 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [received][100] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:55.948715 [DEBUG] sofia.c:7052 Remote SDP: 555a348b-6f43-4c9e-911a-45708a9e404c v=0 555a348b-6f43-4c9e-911a-45708a9e404c o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79 555a348b-6f43-4c9e-911a-45708a9e404c s=CounterPath eyeBeam 1.5 555a348b-6f43-4c9e-911a-45708a9e404c c=IN IP4 10.112.48.79 555a348b-6f43-4c9e-911a-45708a9e404c t=0 0 555a348b-6f43-4c9e-911a-45708a9e404c m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:119 BV32-FEC/16000 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:98 iLBC/8000 555a348b-6f43-4c9e-911a-45708a9e404c a=rtpmap:101 telephone-event/8000 555a348b-6f43-4c9e-911a-45708a9e404c a=fmtp:101 0-15 555a348b-6f43-4c9e-911a-45708a9e404c a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578 555a348b-6f43-4c9e-911a-45708a9e404c a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578 555a348b-6f43-4c9e-911a-45708a9e404c a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC 555a348b-6f43-4c9e-911a-45708a9e404c 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_channel.c:2029 (sofia/external/3613612209@webrtc.example.com) Callstate Change HELD -> UNHELD 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [BV32-FEC:119:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [BV32-FEC:119:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [iLBC:98:8000:30:13330:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [iLBC:98:8000:30:13330:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4316 Set telephone-event payload to 101@8000 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4659 Set telephone-event payload to 101@8000 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:4718 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf send payload to 101 recv payload to 101 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:6721 Audio params changed for sofia/external/3613612209@webrtc.example.com from 0.0.0.0:60578 to 10.112.48.79:60578 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:6732 AUDIO RTP [sofia/external/3613612209@webrtc.example.com] 172.21.0.10 port 18918 -> 10.112.48.79 port 60578 codec: 0 ms: 20 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_media.c:6761 AUDIO RTP CHANGING DEST TO: [10.112.48.79:60578] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] sofia.c:7942 Processing updated SDP 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.188711 [DEBUG] switch_core_io.c:941 (sofia/external/3613612209@webrtc.example.com) Callstate Change UNHELD -> ACTIVE send 883 bytes to udp/[172.21.0.12]:5060 at 11:45:56.206417: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKe3d1.056c93ff816cb5eae23ce769cff7a919.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-12 From: "3613612201" ;tag=1 To: "3613612202" ;tag=04SeBK9031yZH Call-ID: 1-1760@172.21.0.11 CSeq: 3 INVITE Contact: User-Agent: Voicis WebRTC Gateway Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 218 v=0 o=FreeSWITCH 1487917833 1487917836 IN IP4 172.21.0.10 s=FreeSWITCH c=IN IP4 172.21.0.10 t=0 0 m=audio 18918 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ recv 560 bytes from udp/[172.21.0.12]:5060 at 11:45:56.207529: ------------------------------------------------------------------------ ACK sip:3613612202@172.21.0.10:5080 SIP/2.0 Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKe3d1.52fab6f59bab92a72d76ff368c0cc885.0 Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1760-1-16 Max-Forwards: 43 To: "3613612202" ;tag=04SeBK9031yZH From: "3613612209" ;tag=1 Call-ID: 1-1760@172.21.0.11 CSeq: 3 ACK User-Agent: eyeBeam release 9330a stamp 37653 Content-Length: 0 Server: SIPp Server Contact: ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.208708 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [completed][200] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:45:56.208708 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [ready][200] 2017-02-24 11:46:30.988712 [ALERT] mod_verto.c:1373 READ 172.21.0.1:45670 [{ "jsonrpc": "2.0", "method": "verto.bye", "params": { "dialogParams": { "screenShare": false, "useMic": "any", "useSpeak": "any", "tag": "webcam", "localTag": null, "login": "3613612202@webrtc.example.com", "videoParams": { "minWidth": "1280", "minHeight": "720", "minFrameRate": 30 }, "callID": "2b2096b0-99cd-44a2-92e2-9123163e2c23", "caller_id_name": "3613612209", "caller_id_number": "3613612209", "callee_id_name": "3613612202", "callee_id_number": "3613612202", "display_direction": "outbound", "remote_caller_id_name": "3613612209", "remote_caller_id_number": "3613612209" }, "sessid": "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a" }, "id": 24 }] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [NOTICE] mod_verto.c:2764 Hangup verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2017-02-24 11:46:30.988712 [ALERT] mod_verto.c:604 WRITE 172.21.0.1:45670 [{ "jsonrpc": "2.0", "id": 24, "result": { "callID": "2b2096b0-99cd-44a2-92e2-9123163e2c23", "message": "CALL ENDED", "causeCode": 16, "cause": "NORMAL_CLEARING", "sessid": "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a" } }] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_ivr_bridge.c:679 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a ending bridge by request from read function 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_ivr_bridge.c:752 BRIDGE THREAD DONE [verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:653 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State EXCHANGE_MEDIA going to sleep 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_HANGUP (Cur 2 Tot 8) 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Callstate Change ACTIVE -> HANGUP 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State HANGUP 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:60 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Standard HANGUP, cause: NORMAL_CLEARING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State HANGUP going to sleep 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_HANGUP -> CS_REPORTING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_REPORTING (Cur 2 Tot 8) 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State REPORTING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:174 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Standard REPORTING, cause: NORMAL_CLEARING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State REPORTING going to sleep 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_REPORTING -> CS_DESTROY 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_ivr_bridge.c:752 BRIDGE THREAD DONE [sofia/external/3613612209@webrtc.example.com] 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_session.c:1664 Session 8 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Locked, Waiting on external entities 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [NOTICE] switch_core_session.c:1682 Session 8 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Ended 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [NOTICE] switch_core_session.c:1686 Close Channel verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a [CS_DESTROY] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [NOTICE] switch_core_state_machine.c:385 sofia/external/3613612209@webrtc.example.com has executed the last dialplan instruction, hanging up. 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/3613612209@webrtc.example.com [CS_EXECUTE] [NORMAL_CLEARING] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:650 (sofia/external/3613612209@webrtc.example.com) State EXECUTE going to sleep 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_HANGUP (Cur 1 Tot 8) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:850 (sofia/external/3613612209@webrtc.example.com) Callstate Change ACTIVE -> HANGUP 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:852 (sofia/external/3613612209@webrtc.example.com) State HANGUP 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] mod_sofia.c:438 Channel sofia/external/3613612209@webrtc.example.com hanging up, cause: NORMAL_CLEARING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/3613612209@webrtc.example.com 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:60 sofia/external/3613612209@webrtc.example.com Standard HANGUP, cause: NORMAL_CLEARING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:852 (sofia/external/3613612209@webrtc.example.com) State HANGUP going to sleep 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:619 (sofia/external/3613612209@webrtc.example.com) State Change CS_HANGUP -> CS_REPORTING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_REPORTING (Cur 1 Tot 8) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:938 (sofia/external/3613612209@webrtc.example.com) State REPORTING 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_DESTROY (Cur 1 Tot 8) send 639 bytes to udp/[172.21.0.12]:5060 at 11:46:31.006702: ------------------------------------------------------------------------ BYE sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630 SIP/2.0 Via: SIP/2.0/UDP 172.21.0.10:5080;rport;branch=z9hG4bKX2yFeXX0SZeSm Route: Max-Forwards: 70 From: "3613612202" ;tag=04SeBK9031yZH To: "3613612209" ;tag=1 Call-ID: 1-1760@172.21.0.11 CSeq: 103632331 BYE User-Agent: Voicis WebRTC Gateway Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State DESTROY 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] mod_rtc.c:132 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a RTC DESTROY 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:181 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Standard DESTROY 2b2096b0-99cd-44a2-92e2-9123163e2c23 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State DESTROY going to sleep recv 316 bytes from udp/[172.21.0.12]:5060 at 11:46:31.007813: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.21.0.10:5080;received=172.21.0.10;rport=5080;branch=z9hG4bKX2yFeXX0SZeSm From: "3613612202" ;tag=04SeBK9031yZH To: "3613612209" ;tag=1 Call-ID: 1-1760@172.21.0.11 CSeq: 103632331 BYE Content-Length: 0 ------------------------------------------------------------------------ 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:174 sofia/external/3613612209@webrtc.example.com Standard REPORTING, cause: NORMAL_CLEARING 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:938 (sofia/external/3613612209@webrtc.example.com) State REPORTING going to sleep 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_state_machine.c:610 (sofia/external/3613612209@webrtc.example.com) State Change CS_REPORTING -> CS_DESTROY 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [DEBUG] switch_core_session.c:1664 Session 7 (sofia/external/3613612209@webrtc.example.com) Locked, Waiting on external entities 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [NOTICE] switch_core_session.c:1682 Session 7 (sofia/external/3613612209@webrtc.example.com) Ended 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:30.988712 [NOTICE] switch_core_session.c:1686 Close Channel sofia/external/3613612209@webrtc.example.com [CS_DESTROY] 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:31.008709 [DEBUG] switch_core_state_machine.c:741 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_DESTROY (Cur 0 Tot 8) 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:31.008709 [DEBUG] switch_core_state_machine.c:751 (sofia/external/3613612209@webrtc.example.com) State DESTROY 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:31.008709 [DEBUG] mod_sofia.c:343 sofia/external/3613612209@webrtc.example.com SOFIA DESTROY 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:31.008709 [DEBUG] switch_core_state_machine.c:181 sofia/external/3613612209@webrtc.example.com Standard DESTROY 555a348b-6f43-4c9e-911a-45708a9e404c 2017-02-24 11:46:31.008709 [DEBUG] switch_core_state_machine.c:751 (sofia/external/3613612209@webrtc.example.com) State DESTROY going to sleep 2017-02-24 11:47:36.628766 [NOTICE] mod_xml_cdr.c:102 Rotating log file paths 2017-02-24 11:47:36.628766 [NOTICE] mod_xml_cdr.c:107 Rotating log file path to /var/log/freeswitch/xml_cdr/2017-02-24-11-47-36 2017-02-24 11:47:36.668715 [NOTICE] mod_xml_cdr.c:146 Rotating err log file path to /var/log/freeswitch/xml_cdr/2017-02-24-11-47-36