- 2017-10-10 16:42:36.993324 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/1008.csv
- 2017-10-10 16:42:36.993324 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/1002.csv
- 2017-10-10 16:42:36.993324 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/Master.csv
- 2017-10-10 16:42:36.993324 [NOTICE] mod_logfile.c:192 New log started.
- nta.c:9107 outgoing_timer_dk() nta: timer K fired, terminate CANCEL (113490798)
- nta.c:8805 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7ff359c90d40)
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
- nta.c:1296 agent_timer() nta: timer set next to 27000 ms
- nta.c:9107 outgoing_timer_dk() nta: timer D fired, terminate INVITE (113490798)
- nta.c:8805 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7ff359c90d40)
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
- nta.c:1289 agent_timer() nta: timer not set
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_session.c:641 N/A set UUID=3e1aed8d-cbda-ad24-5fdc-6154a47b93d2
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:486 Looking for zrtp-hash
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:439 Deciding whether to pass zrtp-hash between legs
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:441 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ opus 111
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ ISAC 103
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ ISAC 104
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ G722 9
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ PCMU 0
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ PCMA 8
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ CN 106
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ CN 105
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ CN 13
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ telephone-event 110
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ telephone-event 112
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ telephone-event 113
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ telephone-event 126
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ VP8 96
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ VP9 98
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ H264 100
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ red 102
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ ulpfec 127
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ rtx 97
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ rtx 99
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ rtx 101
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ rtx 125
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/91001 [3e1aed8d-cbda-ad24-5fdc-6154a47b93d2]
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] mod_verto.c:3761 Remote SDP verto.rtc/91001:
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 v=0
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 o=- 566374174128884157 2 IN IP4 127.0.0.1
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 s=-
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 t=0 0
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=group:BUNDLE audio video
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=msid-semantic: WMS j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 m=audio 52531 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 c=IN IP4 166.177.186.163
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp:9 IN IP4 0.0.0.0
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:1004169428 1 udp 2122255103 2001::9d38:6ab8:3ccc:b5e:3f57:69cd 52887 typ host generation 0 network-id 2 network-cost 50
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:236887664 1 udp 2122194687 192.168.150.50 52888 typ host generation 0 network-id 1
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:3264455139 1 udp 1685987071 166.177.186.163 52531 typ srflx raddr 192.168.150.50 rport 52888 generation 0 network-id 1
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:1968804900 1 tcp 1518275327 2001::9d38:6ab8:3ccc:b5e:3f57:69cd 9 typ host tcptype active generation 0 network-id 2 network-cost 50
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:1084056192 1 tcp 1518214911 192.168.150.50 9 typ host tcptype active generation 0 network-id 1
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-ufrag:9Rwb
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-pwd:M7iM7cpAMhzhtx7xWICsLbka
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-options:trickle
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fingerprint:sha-256 E6:25:E6:8E:23:B4:18:BE:28:11:F7:7B:DF:1E:88:D3:40:C0:F2:36:8B:4E:A9:E0:FE:65:5B:55:C9:04:35:A6
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=setup:actpass
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=mid:audio
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=sendrecv
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-mux
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:111 opus/48000/2
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:111 transport-cc
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:111 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:103 ISAC/16000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:104 ISAC/32000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:9 G722/8000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:0 PCMU/8000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:8 PCMA/8000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:106 CN/32000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:105 CN/16000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:13 CN/8000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:110 telephone-event/48000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:112 telephone-event/32000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:113 telephone-event/16000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:126 telephone-event/8000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2937566273 cname:phkoy8BeHJQ2Pseb
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2937566273 msid:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si 2d502557-1fc8-45bf-8463-a5304a753da2
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2937566273 mslabel:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2937566273 label:2d502557-1fc8-45bf-8463-a5304a753da2
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 m=video 26673 UDP/TLS/RTP/SAVPF 96 98 100 102 127 97 99 101 125
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 c=IN IP4 166.177.186.163
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp:9 IN IP4 0.0.0.0
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:1004169428 1 udp 2122255103 2001::9d38:6ab8:3ccc:b5e:3f57:69cd 52889 typ host generation 0 network-id 2 network-cost 50
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:236887664 1 udp 2122194687 192.168.150.50 52890 typ host generation 0 network-id 1
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:3264455139 1 udp 1685987071 166.177.186.163 26673 typ srflx raddr 192.168.150.50 rport 52890 generation 0 network-id 1
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:1968804900 1 tcp 1518275327 2001::9d38:6ab8:3ccc:b5e:3f57:69cd 9 typ host tcptype active generation 0 network-id 2 network-cost 50
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:1084056192 1 tcp 1518214911 192.168.150.50 9 typ host tcptype active generation 0 network-id 1
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-ufrag:9Rwb
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-pwd:M7iM7cpAMhzhtx7xWICsLbka
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-options:trickle
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fingerprint:sha-256 E6:25:E6:8E:23:B4:18:BE:28:11:F7:7B:DF:1E:88:D3:40:C0:F2:36:8B:4E:A9:E0:FE:65:5B:55:C9:04:35:A6
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=setup:actpass
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=mid:video
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:4 urn:3gpp:video-orientation
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=sendrecv
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-mux
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-rsize
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:96 VP8/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 ccm fir
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 nack
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 nack pli
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 goog-remb
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 transport-cc
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:98 VP9/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 ccm fir
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 nack
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 nack pli
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 goog-remb
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 transport-cc
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:100 H264/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 ccm fir
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 nack
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 nack pli
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 goog-remb
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 transport-cc
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:102 red/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:127 ulpfec/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:97 rtx/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:97 apt=96
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:99 rtx/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:99 apt=98
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:101 rtx/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:101 apt=100
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:125 rtx/90000
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:125 apt=102
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc-group:FID 780967715 2800542550
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:780967715 cname:phkoy8BeHJQ2Pseb
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:780967715 msid:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si ac731432-dc84-4959-924f-f771a6fe824b
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:780967715 mslabel:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:780967715 label:ac731432-dc84-4959-924f-f771a6fe824b
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2800542550 cname:phkoy8BeHJQ2Pseb
- a=ssrc:2800542550 msid:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si ac731432-dc84-4959-924f-f771a6fe824b
- a=ssrc:2800542550 mslabel:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si
- a=ssrc:2800542550 label:ac731432-dc84-4959-924f-f771a6fe824b
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/91001) Running State Change CS_INIT (Cur 1 Tot 71)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/91001) State INIT
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_state_machine.c:627 verto.rtc/91001 Send KeyFrame
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:40 verto.rtc/91001 Standard INIT
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/91001) State Change CS_INIT -> CS_ROUTING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/91001) State INIT going to sleep
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/91001) Running State Change CS_ROUTING (Cur 1 Tot 71)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_channel.c:2249 (verto.rtc/91001) Callstate Change DOWN -> RINGING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/91001) State ROUTING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_state_machine.c:643 verto.rtc/91001 Send KeyFrame
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] mod_rtc.c:89 verto.rtc/91001 RTC ROUTING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:236 verto.rtc/91001 Standard ROUTING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [INFO] mod_dialplan_xml.c:637 Processing 1002 <joshua@freeswitch.org>->91001 in context default
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 Dialplan: verto.rtc/91001 parsing [default->baremetal.joshebosh.com] continue=false
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 Dialplan: verto.rtc/91001 Regex (PASS) [baremetal.joshebosh.com] ${destination_number}(91001) =~ /^9(10[01][0-9])$/ break=on-false
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 Dialplan: verto.rtc/91001 Action bridge(sofia/external/1001@baremetal.joshebosh.com)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/91001) State Change CS_ROUTING -> CS_EXECUTE
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/91001) State ROUTING going to sleep
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/91001) Running State Change CS_EXECUTE (Cur 1 Tot 71)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/91001) State EXECUTE
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_state_machine.c:650 verto.rtc/91001 Send KeyFrame
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] mod_rtc.c:120 verto.rtc/91001 RTC EXECUTE
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:328 verto.rtc/91001 Standard EXECUTE
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_session.c:2648 verto.rtc/91001 Send KeyFrame
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 EXECUTE verto.rtc/91001 bridge(sofia/external/1001@baremetal.joshebosh.com)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_session.c:2870 verto.rtc/91001 receive message [APPLICATION_EXEC_COMPLETE]
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.613325 [NOTICE] switch_channel.c:1104 New Channel sofia/external/1001@baremetal.joshebosh.com [04caab82-2ea9-422b-878d-557134564128]
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.613325 [DEBUG] mod_sofia.c:5026 (sofia/external/1001@baremetal.joshebosh.com) State Change CS_NEW -> CS_INIT
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1001@baremetal.joshebosh.com) Running State Change CS_INIT (Cur 2 Tot 72)
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:627 (sofia/external/1001@baremetal.joshebosh.com) State INIT
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.613325 [ALERT] switch_core_state_machine.c:627 sofia/external/1001@baremetal.joshebosh.com Send KeyFrame
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.613325 [DEBUG] mod_sofia.c:93 sofia/external/1001@baremetal.joshebosh.com SOFIA INIT
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ opus 102
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ G722 9
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ PCMU 0
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ PCMA 8
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ opus 103
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ VP9 98
- 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ VP8 96
- nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
- nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.613325 [DEBUG] sofia_glue.c:1299 sofia/external/1001@baremetal.joshebosh.com sending invite version: 1.9.0 git 542825e 2017-09-24 20:38:29Z 64bit
- 04caab82-2ea9-422b-878d-557134564128 Local SDP:
- 04caab82-2ea9-422b-878d-557134564128 v=0
- 04caab82-2ea9-422b-878d-557134564128 o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
- 04caab82-2ea9-422b-878d-557134564128 s=FreeSWITCH
- 04caab82-2ea9-422b-878d-557134564128 c=IN IP4 74.208.165.83
- 04caab82-2ea9-422b-878d-557134564128 t=0 0
- 04caab82-2ea9-422b-878d-557134564128 m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:102 opus/48000/2
- 04caab82-2ea9-422b-878d-557134564128 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:9 G722/8000
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:0 PCMU/8000
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:8 PCMA/8000
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:103 opus/48000/2
- 04caab82-2ea9-422b-878d-557134564128 a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:104 telephone-event/48000
- 04caab82-2ea9-422b-878d-557134564128 a=fmtp:104 0-16
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:101 telephone-event/8000
- 04caab82-2ea9-422b-878d-557134564128 a=fmtp:101 0-16
- 04caab82-2ea9-422b-878d-557134564128 a=ptime:20
- 04caab82-2ea9-422b-878d-557134564128 a=sendrecv
- 04caab82-2ea9-422b-878d-557134564128 m=video 16426 RTP/AVP 98 96
- 04caab82-2ea9-422b-878d-557134564128 b=AS:1024
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:98 VP9/90000
- 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:96 VP8/90000
- 04caab82-2ea9-422b-878d-557134564128 a=sendrecv
- 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:98 ccm fir
- 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:98 ccm tmmbr
- 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:98 nack
- 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:98 nack pli
- 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:96 ccm fir
- 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:96 ccm tmmbr
- 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:96 nack
- 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:96 nack pli
- 04caab82-2ea9-422b-878d-557134564128
- nua.c:633 nua_invite() nua: nua_invite: entering
- nua_stack.c:569 nua_stack_signal() nua(0x1632cb0): recv signal r_invite
- nua_params.c:482 nua_stack_set_params() nua: nua_stack_set_params: entering
- soa.c:280 soa_clone() soa_clone(static::0x7ff340028d80, 0x7ff3400260e0, 0x1632cb0) called
- soa.c:403 soa_set_params() soa_set_params(static::0x7ff34004fa50, ...) called
- soa.c:403 soa_set_params() soa_set_params(static::0x7ff34004fa50, ...) called
- soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7ff34004fa50, (nil), 0x16d6386, -1) called
- soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x7ff34004fa50, (nil), 0x16d6386, -1) called
- nua_dialog.c:338 nua_dialog_usage_add() nua(0x1632cb0): adding session usage
- nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7ff34004fc30)
- soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7ff34004fa50) called
- soa.c:1426 soa_generate_offer() soa_generate_offer(static::0x7ff34004fa50, 0) called
- soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7ff34004fa50, soa_generate_offer): called
- soa_static.c:1189 offer_answer_step() soa_static(0x7ff34004fa50, soa_generate_offer): generating local description
- soa_static.c:1217 offer_answer_step() soa_static(0x7ff34004fa50, soa_generate_offer): upgrade with local description
- soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7ff359c8eac0, (nil), ""): called
- soa_static.c:1446 offer_answer_step() soa_static(0x7ff34004fa50, soa_generate_offer): storing local description
- soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7ff34004fa50, [(nil)], [0x7ff359c90bf8], [0x7ff359c90bf4]) called
- nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
- sres_cache.c:272 sres_cache_get() sres_cache_get(0x7ff340026380, NAPTR, "baremetal.joshebosh.com.") called
- nta.c:10404 outgoing_query_naptr() nta: for "baremetal.joshebosh.com" query "baremetal.joshebosh.com" NAPTR
- sres.c:968 sres_query() sres_query(0x7ff340016b20, 0x7ff340060b40, NAPTR, "baremetal.joshebosh.com") called
- sres.c:2730 sres_send_dns_query() sres_send_dns_query(0x7ff340016b20, 0x7ff340081a00) called
- sres.c:2819 sres_send_dns_query() sres_send_dns_query(0x7ff340016b20, 0x7ff340081a00) id=51765 NAPTR baremetal.joshebosh.com (to [74.208.165.12]:53)
- nua_session.c:4139 signal_call_state_change() nua(0x1632cb0): call state changed: init -> calling, sent offer
- soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7ff34004fa50, [0x7ff359c90bd8], [0x7ff359c90be0], [(nil)]) called
- nua_stack.c:269 nua_stack_event() nua(0x1632cb0): event i_state INVITE sent
- nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
- nua_stack.c:529 nua_signal() nua(0x1632cb0): sent signal r_invite
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:40 sofia/external/1001@baremetal.joshebosh.com Standard INIT
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:48 (sofia/external/1001@baremetal.joshebosh.com) State Change CS_INIT -> CS_ROUTING
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:627 (sofia/external/1001@baremetal.joshebosh.com) State INIT going to sleep
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1001@baremetal.joshebosh.com) Running State Change CS_ROUTING (Cur 2 Tot 72)
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] sofia.c:7283 Channel sofia/external/1001@baremetal.joshebosh.com entering state [calling][0]
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:643 (sofia/external/1001@baremetal.joshebosh.com) State ROUTING
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [ALERT] switch_core_state_machine.c:643 sofia/external/1001@baremetal.joshebosh.com Send KeyFrame
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] mod_sofia.c:154 sofia/external/1001@baremetal.joshebosh.com SOFIA ROUTING
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_ivr_originate.c:67 (sofia/external/1001@baremetal.joshebosh.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:643 (sofia/external/1001@baremetal.joshebosh.com) State ROUTING going to sleep
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1001@baremetal.joshebosh.com) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 72)
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:662 (sofia/external/1001@baremetal.joshebosh.com) State CONSUME_MEDIA
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [ALERT] switch_core_state_machine.c:662 sofia/external/1001@baremetal.joshebosh.com Send KeyFrame
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [DEBUG] switch_core_state_machine.c:662 (sofia/external/1001@baremetal.joshebosh.com) State CONSUME_MEDIA going to sleep
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:27.623650 [ALERT] switch_core_state_machine.c:701 sofia/external/1001@baremetal.joshebosh.com session thread sleep state: CS_CONSUME_MEDIA!
- sres.c:3467 sres_resolver_receive() sres_resolver_receive(0x7ff340016b20, 91) called
- sres.c:3781 sres_create_record() AUTHORITY RR received joshebosh.com. SOA IN 3298 rdlen=56
- sres.c:3572 sres_log_response() sres_resolver_receive(0x7ff340016b20, 0x7ff340081a00) id=51765 (from [74.208.165.12]:53)
- sres.c:2987 sres_query_report_error() sres(q=0x7ff340081a00): reporting error RECORD_ERR for NAPTR baremetal.joshebosh.com
- sres_cache.c:272 sres_cache_get() sres_cache_get(0x7ff340026380, SRV, "_sip._udp.baremetal.joshebosh.com.") called
- nta.c:10604 outgoing_query_srv() nta: for "baremetal.joshebosh.com" query "_sip._udp.baremetal.joshebosh.com" SRV
- sres.c:968 sres_query() sres_query(0x7ff340016b20, 0x7ff340060b40, SRV, "_sip._udp.baremetal.joshebosh.com") called
- sres.c:2730 sres_send_dns_query() sres_send_dns_query(0x7ff340016b20, 0x7ff340083410) called
- sres.c:2819 sres_send_dns_query() sres_send_dns_query(0x7ff340016b20, 0x7ff340083410) id=51766 SRV _sip._udp.baremetal.joshebosh.com (to [74.208.165.12]:53)
- sres.c:3467 sres_resolver_receive() sres_resolver_receive(0x7ff340016b20, 91) called
- sres.c:3781 sres_create_record() AUTHORITY RR received joshebosh.com. SOA IN 3298 rdlen=56
- sres.c:3572 sres_log_response() sres_resolver_receive(0x7ff340016b20, 0x7ff340083410) id=51766 (from [74.208.165.12]:53)
- sres.c:2987 sres_query_report_error() sres(q=0x7ff340083410): reporting error NAME_ERR for SRV _sip._udp.baremetal.joshebosh.com
- sres_cache.c:272 sres_cache_get() sres_cache_get(0x7ff340026380, A, "baremetal.joshebosh.com.") called
- sres_cache.c:318 sres_cache_get() sres_cache_get(0x7ff340026380, A, "baremetal.joshebosh.com.") returned 1 entries
- nta.c:10809 outgoing_query_a() nta: for "baremetal.joshebosh.com" query "baremetal.joshebosh.com" A (cached)
- nta.c:10862 outgoing_answer_a() nta: baremetal.joshebosh.com. IN A 65.15.69.32
- tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
- tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
- tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
- tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
- tport.c:3492 tport_send_msg() tport_vsend returned 1712
- send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:27.624447:
- ------------------------------------------------------------------------
- INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
- Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
- Max-Forwards: 70
- From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
- To: <sip:1001@baremetal.joshebosh.com>
- Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
- CSeq: 113490869 INVITE
- Contact: <sip:mod_sofia@74.208.165.83:5080>
- User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
- 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: 883
- X-FS-Support: update_display,send_info
- Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
- v=0
- o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
- s=FreeSWITCH
- c=IN IP4 74.208.165.83
- t=0 0
- m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
- a=rtpmap:102 opus/48000/2
- a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
- a=rtpmap:9 G722/8000
- a=rtpmap:0 PCMU/8000
- a=rtpmap:8 PCMA/8000
- a=rtpmap:103 opus/48000/2
- a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
- a=rtpmap:104 telephone-event/48000
- a=fmtp:104 0-16
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-16
- a=ptime:20
- m=video 16426 RTP/AVP 98 96
- b=AS:1024
- a=rtpmap:98 VP9/90000
- a=rtpmap:96 VP8/90000
- a=rtcp-fb:98 ccm fir
- a=rtcp-fb:98 ccm tmmbr
- a=rtcp-fb:98 nack
- a=rtcp-fb:98 nack pli
- a=rtcp-fb:96 ccm fir
- a=rtcp-fb:96 ccm tmmbr
- a=rtcp-fb:96 nack
- a=rtcp-fb:96 nack pli
- ------------------------------------------------------------------------
- nta.c:8310 outgoing_send() nta: sent INVITE (113490869) to */65.15.69.32:5060
- tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
- nta.c:1350 set_timeout() nta: timer set to 32000 ms
- nta.c:1348 set_timeout() nta: timer shortened to 1000 ms
- nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
- tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
- tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
- tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
- tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
- tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
- tport.c:3492 tport_send_msg() tport_vsend returned 1712
- send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:28.624785:
- ------------------------------------------------------------------------
- INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
- Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
- Max-Forwards: 70
- From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
- To: <sip:1001@baremetal.joshebosh.com>
- Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
- CSeq: 113490869 INVITE
- Contact: <sip:mod_sofia@74.208.165.83:5080>
- User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
- 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: 883
- X-FS-Support: update_display,send_info
- Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
- v=0
- o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
- s=FreeSWITCH
- c=IN IP4 74.208.165.83
- t=0 0
- m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
- a=rtpmap:102 opus/48000/2
- a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
- a=rtpmap:9 G722/8000
- a=rtpmap:0 PCMU/8000
- a=rtpmap:8 PCMA/8000
- a=rtpmap:103 opus/48000/2
- a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
- a=rtpmap:104 telephone-event/48000
- a=fmtp:104 0-16
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-16
- a=ptime:20
- m=video 16426 RTP/AVP 98 96
- b=AS:1024
- a=rtpmap:98 VP9/90000
- a=rtpmap:96 VP8/90000
- a=rtcp-fb:98 ccm fir
- a=rtcp-fb:98 ccm tmmbr
- a=rtcp-fb:98 nack
- a=rtcp-fb:98 nack pli
- a=rtcp-fb:96 ccm fir
- a=rtcp-fb:96 ccm tmmbr
- a=rtcp-fb:96 nack
- a=rtcp-fb:96 nack pli
- ------------------------------------------------------------------------
- nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
- tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free
- nta.c:1296 agent_timer() nta: timer set next to 2000 ms
- nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
- tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
- tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
- tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
- tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
- tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
- tport.c:3492 tport_send_msg() tport_vsend returned 1712
- send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:30.624814:
- ------------------------------------------------------------------------
- INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
- Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
- Max-Forwards: 70
- From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
- To: <sip:1001@baremetal.joshebosh.com>
- Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
- CSeq: 113490869 INVITE
- Contact: <sip:mod_sofia@74.208.165.83:5080>
- User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
- 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: 883
- X-FS-Support: update_display,send_info
- Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
- v=0
- o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
- s=FreeSWITCH
- c=IN IP4 74.208.165.83
- t=0 0
- m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
- a=rtpmap:102 opus/48000/2
- a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
- a=rtpmap:9 G722/8000
- a=rtpmap:0 PCMU/8000
- a=rtpmap:8 PCMA/8000
- a=rtpmap:103 opus/48000/2
- a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
- a=rtpmap:104 telephone-event/48000
- a=fmtp:104 0-16
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-16
- a=ptime:20
- m=video 16426 RTP/AVP 98 96
- b=AS:1024
- a=rtpmap:98 VP9/90000
- a=rtpmap:96 VP8/90000
- a=rtcp-fb:98 ccm fir
- a=rtcp-fb:98 ccm tmmbr
- a=rtcp-fb:98 nack
- a=rtcp-fb:98 nack pli
- a=rtcp-fb:96 ccm fir
- a=rtcp-fb:96 ccm tmmbr
- a=rtcp-fb:96 nack
- a=rtcp-fb:96 nack pli
- ------------------------------------------------------------------------
- nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
- tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free
- nta.c:1296 agent_timer() nta: timer set next to 4000 ms
- nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
- tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
- tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
- tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
- tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
- tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
- tport.c:3492 tport_send_msg() tport_vsend returned 1712
- send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:34.624859:
- ------------------------------------------------------------------------
- INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
- Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
- Max-Forwards: 70
- From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
- To: <sip:1001@baremetal.joshebosh.com>
- Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
- CSeq: 113490869 INVITE
- Contact: <sip:mod_sofia@74.208.165.83:5080>
- User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
- 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: 883
- X-FS-Support: update_display,send_info
- Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
- v=0
- o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
- s=FreeSWITCH
- c=IN IP4 74.208.165.83
- t=0 0
- m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
- a=rtpmap:102 opus/48000/2
- a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
- a=rtpmap:9 G722/8000
- a=rtpmap:0 PCMU/8000
- a=rtpmap:8 PCMA/8000
- a=rtpmap:103 opus/48000/2
- a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
- a=rtpmap:104 telephone-event/48000
- a=fmtp:104 0-16
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-16
- a=ptime:20
- m=video 16426 RTP/AVP 98 96
- b=AS:1024
- a=rtpmap:98 VP9/90000
- a=rtpmap:96 VP8/90000
- a=rtcp-fb:98 ccm fir
- a=rtcp-fb:98 ccm tmmbr
- a=rtcp-fb:98 nack
- a=rtcp-fb:98 nack pli
- a=rtcp-fb:96 ccm fir
- a=rtcp-fb:96 ccm tmmbr
- a=rtcp-fb:96 nack
- a=rtcp-fb:96 nack pli
- ------------------------------------------------------------------------
- nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
- tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free
- nta.c:1296 agent_timer() nta: timer set next to 8000 ms
- nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
- tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
- tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
- tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
- tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
- tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
- tport.c:3492 tport_send_msg() tport_vsend returned 1712
- send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:42.625013:
- ------------------------------------------------------------------------
- INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
- Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
- Max-Forwards: 70
- From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
- To: <sip:1001@baremetal.joshebosh.com>
- Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
- CSeq: 113490869 INVITE
- Contact: <sip:mod_sofia@74.208.165.83:5080>
- User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
- 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: 883
- X-FS-Support: update_display,send_info
- Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
- v=0
- o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
- s=FreeSWITCH
- c=IN IP4 74.208.165.83
- t=0 0
- m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
- a=rtpmap:102 opus/48000/2
- a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
- a=rtpmap:9 G722/8000
- a=rtpmap:0 PCMU/8000
- a=rtpmap:8 PCMA/8000
- a=rtpmap:103 opus/48000/2
- a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
- a=rtpmap:104 telephone-event/48000
- a=fmtp:104 0-16
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-16
- a=ptime:20
- m=video 16426 RTP/AVP 98 96
- b=AS:1024
- a=rtpmap:98 VP9/90000
- a=rtpmap:96 VP8/90000
- a=rtcp-fb:98 ccm fir
- a=rtcp-fb:98 ccm tmmbr
- a=rtcp-fb:98 nack
- a=rtcp-fb:98 nack pli
- a=rtcp-fb:96 ccm fir
- a=rtcp-fb:96 ccm tmmbr
- a=rtcp-fb:96 nack
- a=rtcp-fb:96 nack pli
- ------------------------------------------------------------------------
- nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
- tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free
- nta.c:1296 agent_timer() nta: timer set next to 16000 ms
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.683325 [NOTICE] mod_verto.c:2835 Hangup verto.rtc/91001 [CS_EXECUTE] [NORMAL_CLEARING]
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [NOTICE] switch_ivr_originate.c:3629 Hangup sofia/external/1001@baremetal.joshebosh.com [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_ivr_originate.c:3854 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [INFO] mod_dptools.c:3508 Originate Failed. Cause: ORIGINATOR_CANCEL
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [ALERT] switch_core_session.c:2885 verto.rtc/91001 receive message [PHONE_EVENT]
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_session.c:2885 verto.rtc/91001 skip receive message [PHONE_EVENT] (channel is hungup already)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/91001) State EXECUTE going to sleep
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/91001) Running State Change CS_HANGUP (Cur 2 Tot 72)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/91001) Callstate Change RINGING -> HANGUP
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/91001) State HANGUP
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:852 verto.rtc/91001 Send KeyFrame
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:60 verto.rtc/91001 Standard HANGUP, cause: NORMAL_CLEARING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/91001) State HANGUP going to sleep
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/91001) State Change CS_HANGUP -> CS_REPORTING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/91001) Running State Change CS_REPORTING (Cur 2 Tot 72)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/91001) State REPORTING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:938 verto.rtc/91001 Send KeyFrame
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:174 verto.rtc/91001 Standard REPORTING, cause: NORMAL_CLEARING
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/91001) State REPORTING going to sleep
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/91001) State Change CS_REPORTING -> CS_DESTROY
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_session.c:1713 Session 71 (verto.rtc/91001) Locked, Waiting on external entities
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [NOTICE] switch_core_session.c:1731 Session 71 (verto.rtc/91001) Ended
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [NOTICE] switch_core_session.c:1735 Close Channel verto.rtc/91001 [CS_DESTROY]
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/91001) Running State Change CS_DESTROY (Cur 1 Tot 72)
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/91001) State DESTROY
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:751 verto.rtc/91001 Send KeyFrame
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] mod_rtc.c:132 verto.rtc/91001 RTC DESTROY
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:181 verto.rtc/91001 Standard DESTROY
- 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/91001) State DESTROY going to sleep
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:705 sofia/external/1001@baremetal.joshebosh.com session thread wake state: CS_CONSUME_MEDIA!
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1001@baremetal.joshebosh.com) Running State Change CS_HANGUP (Cur 1 Tot 72)
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:850 (sofia/external/1001@baremetal.joshebosh.com) Callstate Change DOWN -> HANGUP
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:852 (sofia/external/1001@baremetal.joshebosh.com) State HANGUP
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:852 sofia/external/1001@baremetal.joshebosh.com Send KeyFrame
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] mod_sofia.c:449 Channel sofia/external/1001@baremetal.joshebosh.com hanging up, cause: ORIGINATOR_CANCEL
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] mod_sofia.c:513 Sending CANCEL to sofia/external/1001@baremetal.joshebosh.com
- nua.c:651 nua_cancel() nua: nua_cancel: entering
- nua_stack.c:569 nua_stack_signal() nua(0x1632cb0): recv signal r_cancel
- nua_params.c:482 nua_stack_set_params() nua: nua_stack_set_params: entering
- soa.c:403 soa_set_params() soa_set_params(static::0x7ff34004fa50, ...) called
- nta.c:8189 outgoing_send_via() nta: delayed sending CANCEL (113490869)
- nua_stack.c:529 nua_signal() nua(0x1632cb0): sent signal r_cancel
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:60 sofia/external/1001@baremetal.joshebosh.com Standard HANGUP, cause: ORIGINATOR_CANCEL
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:852 (sofia/external/1001@baremetal.joshebosh.com) State HANGUP going to sleep
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:619 (sofia/external/1001@baremetal.joshebosh.com) State Change CS_HANGUP -> CS_REPORTING
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1001@baremetal.joshebosh.com) Running State Change CS_REPORTING (Cur 1 Tot 72)
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:938 (sofia/external/1001@baremetal.joshebosh.com) State REPORTING
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:938 sofia/external/1001@baremetal.joshebosh.com Send KeyFrame
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:174 sofia/external/1001@baremetal.joshebosh.com Standard REPORTING, cause: ORIGINATOR_CANCEL
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:938 (sofia/external/1001@baremetal.joshebosh.com) State REPORTING going to sleep
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:610 (sofia/external/1001@baremetal.joshebosh.com) State Change CS_REPORTING -> CS_DESTROY
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_session.c:1713 Session 72 (sofia/external/1001@baremetal.joshebosh.com) Locked, Waiting on external entities
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [NOTICE] switch_core_session.c:1731 Session 72 (sofia/external/1001@baremetal.joshebosh.com) Ended
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [NOTICE] switch_core_session.c:1735 Close Channel sofia/external/1001@baremetal.joshebosh.com [CS_DESTROY]
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:741 (sofia/external/1001@baremetal.joshebosh.com) Running State Change CS_DESTROY (Cur 0 Tot 72)
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:751 (sofia/external/1001@baremetal.joshebosh.com) State DESTROY
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:751 sofia/external/1001@baremetal.joshebosh.com Send KeyFrame
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] mod_sofia.c:354 sofia/external/1001@baremetal.joshebosh.com SOFIA DESTROY
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:181 sofia/external/1001@baremetal.joshebosh.com Standard DESTROY
- 04caab82-2ea9-422b-878d-557134564128 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:751 (sofia/external/1001@baremetal.joshebosh.com) State DESTROY going to sleep
- nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
- tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
- tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
- tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
- tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
- tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
- tport.c:3492 tport_send_msg() tport_vsend returned 1712
- send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:58.625358:
- ------------------------------------------------------------------------
- INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
- Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
- Max-Forwards: 70
- From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
- To: <sip:1001@baremetal.joshebosh.com>
- Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
- CSeq: 113490869 INVITE
- Contact: <sip:mod_sofia@74.208.165.83:5080>
- User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
- 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: 883
- X-FS-Support: update_display,send_info
- Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
- v=0
- o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
- s=FreeSWITCH
- c=IN IP4 74.208.165.83
- t=0 0
- m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
- a=rtpmap:102 opus/48000/2
- a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
- a=rtpmap:9 G722/8000
- a=rtpmap:0 PCMU/8000
- a=rtpmap:8 PCMA/8000
- a=rtpmap:103 opus/48000/2
- a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
- a=rtpmap:104 telephone-event/48000
- a=fmtp:104 0-16
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-16
- a=ptime:20
- m=video 16426 RTP/AVP 98 96
- b=AS:1024
- a=rtpmap:98 VP9/90000
- a=rtpmap:96 VP8/90000
- a=rtcp-fb:98 ccm fir
- a=rtcp-fb:98 ccm tmmbr
- a=rtcp-fb:98 nack
- a=rtcp-fb:98 nack pli
- a=rtcp-fb:96 ccm fir
- a=rtcp-fb:96 ccm tmmbr
- a=rtcp-fb:96 nack
- a=rtcp-fb:96 nack pli
- ------------------------------------------------------------------------
- nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
- tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/2 free
- nta.c:1296 agent_timer() nta: timer set next to 1000 ms
- nta.c:8988 outgoing_timer_bf() nta: timer B fired, timeout INVITE (113490869)
- tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
- nua_stack.c:271 nua_stack_event() nua(0x1632cb0): event r_invite 408 Request Timeout
- nua_session.c:4139 signal_call_state_change() nua(0x1632cb0): call state changed: calling -> terminated
- nua_stack.c:271 nua_stack_event() nua(0x1632cb0): event i_state 408 Request Timeout
- nua_stack.c:271 nua_stack_event() nua(0x1632cb0): event i_terminated 408 Request Timeout
- nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x1632cb0): removing session usage
- soa.c:356 soa_destroy() soa_destroy(static::0x7ff34004fa50) called
- nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7ff34004fc30)
- nua_stack.c:271 nua_stack_event() nua(0x1632cb0): event r_cancel 408 Request Timeout
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 0/1 resent, 1/1 tout, 0/0 term, 0/2 free
- nta.c:1296 agent_timer() nta: timer set next to 5000 ms
- nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
- nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
- nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
- nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
- nua_stack.c:569 nua_stack_signal() nua(0x1632cb0): recv signal r_destroy
- nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
- nua_stack.c:529 nua_signal() nua(0x1632cb0): sent signal r_destroy
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
- nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
- nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
- nta.c:9107 outgoing_timer_dk() nta: timer K fired, terminate CANCEL (113490869)
- nta.c:8805 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7ff359c90d40)
- nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
- nta.c:1296 agent_timer() nta: timer set next to 27000 ms