From Walloping Finch, 7 Years ago, written in Plain Text.
- go back
Embed
Viewing differences between and remote box - outgoing call
  1. 2017-10-10 16:42:36.993324 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/1008.csv
  2. 2017-10-10 16:42:36.993324 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/1002.csv
  3. 2017-10-10 16:42:36.993324 [NOTICE] mod_cdr_csv.c:127 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/Master.csv
  4. 2017-10-10 16:42:36.993324 [NOTICE] mod_logfile.c:192 New log started.
  5. nta.c:9107 outgoing_timer_dk() nta: timer K fired, terminate CANCEL (113490798)
  6. nta.c:8805 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7ff359c90d40)
  7. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
  8. nta.c:1296 agent_timer() nta: timer set next to 27000 ms
  9. nta.c:9107 outgoing_timer_dk() nta: timer D fired, terminate INVITE (113490798)
  10. nta.c:8805 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7ff359c90d40)
  11. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
  12. nta.c:1289 agent_timer() nta: timer not set
  13. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT
  14. 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
  15. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:486 Looking for zrtp-hash
  16. 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
  17. 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
  18. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ opus 111
  19. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ ISAC 103
  20. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ ISAC 104
  21. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ G722 9
  22. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ PCMU 0
  23. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ PCMA 8
  24. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ CN 106
  25. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ CN 105
  26. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ CN 13
  27. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ telephone-event 110
  28. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ telephone-event 112
  29. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ telephone-event 113
  30. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ telephone-event 126
  31. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ VP8 96
  32. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ VP9 98
  33. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ H264 100
  34. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ red 102
  35. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ ulpfec 127
  36. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ rtx 97
  37. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ rtx 99
  38. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ rtx 101
  39. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ rtx 125
  40. 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]
  41. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] mod_verto.c:3761 Remote SDP verto.rtc/91001:
  42. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 v=0
  43. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 o=- 566374174128884157 2 IN IP4 127.0.0.1
  44. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 s=-
  45. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 t=0 0
  46. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=group:BUNDLE audio video
  47. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=msid-semantic: WMS j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si
  48. 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
  49. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 c=IN IP4 166.177.186.163
  50. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp:9 IN IP4 0.0.0.0
  51. 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
  52. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:236887664 1 udp 2122194687 192.168.150.50 52888 typ host generation 0 network-id 1
  53. 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
  54. 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
  55. 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
  56. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-ufrag:9Rwb
  57. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-pwd:M7iM7cpAMhzhtx7xWICsLbka
  58. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-options:trickle
  59. 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
  60. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=setup:actpass
  61. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=mid:audio
  62. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  63. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=sendrecv
  64. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-mux
  65. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:111 opus/48000/2
  66. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:111 transport-cc
  67. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:111 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1
  68. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:103 ISAC/16000
  69. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:104 ISAC/32000
  70. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:9 G722/8000
  71. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:0 PCMU/8000
  72. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:8 PCMA/8000
  73. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:106 CN/32000
  74. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:105 CN/16000
  75. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:13 CN/8000
  76. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:110 telephone-event/48000
  77. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:112 telephone-event/32000
  78. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:113 telephone-event/16000
  79. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:126 telephone-event/8000
  80. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2937566273 cname:phkoy8BeHJQ2Pseb
  81. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2937566273 msid:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si 2d502557-1fc8-45bf-8463-a5304a753da2
  82. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2937566273 mslabel:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si
  83. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2937566273 label:2d502557-1fc8-45bf-8463-a5304a753da2
  84. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 m=video 26673 UDP/TLS/RTP/SAVPF 96 98 100 102 127 97 99 101 125
  85. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 c=IN IP4 166.177.186.163
  86. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp:9 IN IP4 0.0.0.0
  87. 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
  88. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=candidate:236887664 1 udp 2122194687 192.168.150.50 52890 typ host generation 0 network-id 1
  89. 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
  90. 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
  91. 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
  92. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-ufrag:9Rwb
  93. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-pwd:M7iM7cpAMhzhtx7xWICsLbka
  94. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ice-options:trickle
  95. 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
  96. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=setup:actpass
  97. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=mid:video
  98. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
  99. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
  100. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:4 urn:3gpp:video-orientation
  101. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
  102. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
  103. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=sendrecv
  104. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-mux
  105. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-rsize
  106. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:96 VP8/90000
  107. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 ccm fir
  108. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 nack
  109. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 nack pli
  110. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 goog-remb
  111. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:96 transport-cc
  112. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:98 VP9/90000
  113. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 ccm fir
  114. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 nack
  115. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 nack pli
  116. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 goog-remb
  117. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:98 transport-cc
  118. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:100 H264/90000
  119. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 ccm fir
  120. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 nack
  121. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 nack pli
  122. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 goog-remb
  123. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtcp-fb:100 transport-cc
  124. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
  125. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:102 red/90000
  126. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:127 ulpfec/90000
  127. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:97 rtx/90000
  128. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:97 apt=96
  129. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:99 rtx/90000
  130. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:99 apt=98
  131. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:101 rtx/90000
  132. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:101 apt=100
  133. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=rtpmap:125 rtx/90000
  134. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=fmtp:125 apt=102
  135. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc-group:FID 780967715 2800542550
  136. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:780967715 cname:phkoy8BeHJQ2Pseb
  137. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:780967715 msid:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si ac731432-dc84-4959-924f-f771a6fe824b
  138. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:780967715 mslabel:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si
  139. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:780967715 label:ac731432-dc84-4959-924f-f771a6fe824b
  140. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 a=ssrc:2800542550 cname:phkoy8BeHJQ2Pseb
  141. a=ssrc:2800542550 msid:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si ac731432-dc84-4959-924f-f771a6fe824b
  142. a=ssrc:2800542550 mslabel:j99C9EWB2ilqELV3FlXiQr23RoBOLuqYu6si
  143. a=ssrc:2800542550 label:ac731432-dc84-4959-924f-f771a6fe824b
  144.  
  145.  
  146. 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)
  147. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/91001) State INIT
  148. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_state_machine.c:627 verto.rtc/91001 Send KeyFrame
  149. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:40 verto.rtc/91001 Standard INIT
  150. 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
  151. 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
  152. 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)
  153. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_channel.c:2249 (verto.rtc/91001) Callstate Change DOWN -> RINGING
  154. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/91001) State ROUTING
  155. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_state_machine.c:643 verto.rtc/91001 Send KeyFrame
  156. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] mod_rtc.c:89 verto.rtc/91001 RTC ROUTING
  157. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:236 verto.rtc/91001 Standard ROUTING
  158. 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
  159. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 Dialplan: verto.rtc/91001 parsing [default->baremetal.joshebosh.com] continue=false
  160. 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
  161. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 Dialplan: verto.rtc/91001 Action bridge(sofia/external/1001@baremetal.joshebosh.com)
  162. 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
  163. 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
  164. 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)
  165. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/91001) State EXECUTE
  166. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_state_machine.c:650 verto.rtc/91001 Send KeyFrame
  167. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] mod_rtc.c:120 verto.rtc/91001 RTC EXECUTE
  168. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_core_state_machine.c:328 verto.rtc/91001 Standard EXECUTE
  169. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [ALERT] switch_core_session.c:2648 verto.rtc/91001 Send KeyFrame
  170. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 EXECUTE verto.rtc/91001 bridge(sofia/external/1001@baremetal.joshebosh.com)
  171. 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]
  172. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:27.613325 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
  173. 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]
  174. 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
  175. 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)
  176. 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
  177. 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
  178. 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
  179. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ opus 102
  180. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ G722 9
  181. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ PCMU 0
  182. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ PCMA 8
  183. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ opus 103
  184. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ VP9 98
  185. 2017-10-10 16:44:27.613325 [ALERT] switch_core_media.c:934 ADD PMAP REQ VP8 96
  186. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
  187. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  188. 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
  189. 04caab82-2ea9-422b-878d-557134564128 Local SDP:
  190. 04caab82-2ea9-422b-878d-557134564128 v=0
  191. 04caab82-2ea9-422b-878d-557134564128 o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
  192. 04caab82-2ea9-422b-878d-557134564128 s=FreeSWITCH
  193. 04caab82-2ea9-422b-878d-557134564128 c=IN IP4 74.208.165.83
  194. 04caab82-2ea9-422b-878d-557134564128 t=0 0
  195. 04caab82-2ea9-422b-878d-557134564128 m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
  196. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:102 opus/48000/2
  197. 04caab82-2ea9-422b-878d-557134564128 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  198. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:9 G722/8000
  199. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:0 PCMU/8000
  200. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:8 PCMA/8000
  201. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:103 opus/48000/2
  202. 04caab82-2ea9-422b-878d-557134564128 a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  203. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:104 telephone-event/48000
  204. 04caab82-2ea9-422b-878d-557134564128 a=fmtp:104 0-16
  205. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:101 telephone-event/8000
  206. 04caab82-2ea9-422b-878d-557134564128 a=fmtp:101 0-16
  207. 04caab82-2ea9-422b-878d-557134564128 a=ptime:20
  208. 04caab82-2ea9-422b-878d-557134564128 a=sendrecv
  209. 04caab82-2ea9-422b-878d-557134564128 m=video 16426 RTP/AVP 98 96
  210. 04caab82-2ea9-422b-878d-557134564128 b=AS:1024
  211. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:98 VP9/90000
  212. 04caab82-2ea9-422b-878d-557134564128 a=rtpmap:96 VP8/90000
  213. 04caab82-2ea9-422b-878d-557134564128 a=sendrecv
  214. 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:98 ccm fir
  215. 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:98 ccm tmmbr
  216. 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:98 nack
  217. 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:98 nack pli
  218. 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:96 ccm fir
  219. 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:96 ccm tmmbr
  220. 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:96 nack
  221. 04caab82-2ea9-422b-878d-557134564128 a=rtcp-fb:96 nack pli
  222. 04caab82-2ea9-422b-878d-557134564128
  223. nua.c:633 nua_invite() nua: nua_invite: entering
  224. nua_stack.c:569 nua_stack_signal() nua(0x1632cb0): recv signal r_invite
  225. nua_params.c:482 nua_stack_set_params() nua: nua_stack_set_params: entering
  226. soa.c:280 soa_clone() soa_clone(static::0x7ff340028d80, 0x7ff3400260e0, 0x1632cb0) called
  227. soa.c:403 soa_set_params() soa_set_params(static::0x7ff34004fa50, ...) called
  228. soa.c:403 soa_set_params() soa_set_params(static::0x7ff34004fa50, ...) called
  229. soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7ff34004fa50, (nil), 0x16d6386, -1) called
  230. soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x7ff34004fa50, (nil), 0x16d6386, -1) called
  231. nua_dialog.c:338 nua_dialog_usage_add() nua(0x1632cb0): adding session usage
  232. nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7ff34004fc30)
  233. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7ff34004fa50) called
  234. soa.c:1426 soa_generate_offer() soa_generate_offer(static::0x7ff34004fa50, 0) called
  235. soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7ff34004fa50, soa_generate_offer): called
  236. soa_static.c:1189 offer_answer_step() soa_static(0x7ff34004fa50, soa_generate_offer): generating local description
  237. soa_static.c:1217 offer_answer_step() soa_static(0x7ff34004fa50, soa_generate_offer): upgrade with local description
  238. soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7ff359c8eac0, (nil), ""): called
  239. soa_static.c:1446 offer_answer_step() soa_static(0x7ff34004fa50, soa_generate_offer): storing local description
  240. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7ff34004fa50, [(nil)], [0x7ff359c90bf8], [0x7ff359c90bf4]) called
  241. nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
  242. sres_cache.c:272 sres_cache_get() sres_cache_get(0x7ff340026380, NAPTR, "baremetal.joshebosh.com.") called
  243. nta.c:10404 outgoing_query_naptr() nta: for "baremetal.joshebosh.com" query "baremetal.joshebosh.com" NAPTR
  244. sres.c:968 sres_query() sres_query(0x7ff340016b20, 0x7ff340060b40, NAPTR, "baremetal.joshebosh.com") called
  245. sres.c:2730 sres_send_dns_query() sres_send_dns_query(0x7ff340016b20, 0x7ff340081a00) called
  246. 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)
  247. nua_session.c:4139 signal_call_state_change() nua(0x1632cb0): call state changed: init -> calling, sent offer
  248. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7ff34004fa50, [0x7ff359c90bd8], [0x7ff359c90be0], [(nil)]) called
  249. nua_stack.c:269 nua_stack_event() nua(0x1632cb0): event i_state INVITE sent
  250. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  251. nua_stack.c:529 nua_signal() nua(0x1632cb0): sent signal r_invite
  252. 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
  253. 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
  254. 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
  255. 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)
  256. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  257. 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]
  258. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  259. 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
  260. 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
  261. 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
  262. 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
  263. 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
  264. 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)
  265. 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
  266. 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
  267. 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
  268. 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!
  269. sres.c:3467 sres_resolver_receive() sres_resolver_receive(0x7ff340016b20, 91) called
  270. sres.c:3781 sres_create_record() AUTHORITY RR received joshebosh.com. SOA IN 3298 rdlen=56
  271. sres.c:3572 sres_log_response() sres_resolver_receive(0x7ff340016b20, 0x7ff340081a00) id=51765 (from [74.208.165.12]:53)
  272. sres.c:2987 sres_query_report_error() sres(q=0x7ff340081a00): reporting error RECORD_ERR for NAPTR baremetal.joshebosh.com
  273. sres_cache.c:272 sres_cache_get() sres_cache_get(0x7ff340026380, SRV, "_sip._udp.baremetal.joshebosh.com.") called
  274. nta.c:10604 outgoing_query_srv() nta: for "baremetal.joshebosh.com" query "_sip._udp.baremetal.joshebosh.com" SRV
  275. sres.c:968 sres_query() sres_query(0x7ff340016b20, 0x7ff340060b40, SRV, "_sip._udp.baremetal.joshebosh.com") called
  276. sres.c:2730 sres_send_dns_query() sres_send_dns_query(0x7ff340016b20, 0x7ff340083410) called
  277. 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)
  278. sres.c:3467 sres_resolver_receive() sres_resolver_receive(0x7ff340016b20, 91) called
  279. sres.c:3781 sres_create_record() AUTHORITY RR received joshebosh.com. SOA IN 3298 rdlen=56
  280. sres.c:3572 sres_log_response() sres_resolver_receive(0x7ff340016b20, 0x7ff340083410) id=51766 (from [74.208.165.12]:53)
  281. sres.c:2987 sres_query_report_error() sres(q=0x7ff340083410): reporting error NAME_ERR for SRV _sip._udp.baremetal.joshebosh.com
  282. sres_cache.c:272 sres_cache_get() sres_cache_get(0x7ff340026380, A, "baremetal.joshebosh.com.") called
  283. sres_cache.c:318 sres_cache_get() sres_cache_get(0x7ff340026380, A, "baremetal.joshebosh.com.") returned 1 entries
  284. nta.c:10809 outgoing_query_a() nta: for "baremetal.joshebosh.com" query "baremetal.joshebosh.com" A (cached)
  285. nta.c:10862 outgoing_answer_a() nta: baremetal.joshebosh.com. IN A 65.15.69.32
  286. tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
  287. tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
  288. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
  289. tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
  290. tport.c:3492 tport_send_msg() tport_vsend returned 1712
  291. send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:27.624447:
  292.    ------------------------------------------------------------------------
  293.    INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
  294.    Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
  295.    Max-Forwards: 70
  296.    From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
  297.    To: <sip:1001@baremetal.joshebosh.com>
  298.    Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
  299.    CSeq: 113490869 INVITE
  300.    Contact: <sip:mod_sofia@74.208.165.83:5080>
  301.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
  302.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  303.    Supported: timer, path, replaces
  304.    Allow-Events: talk, hold, conference, refer
  305.    Content-Type: application/sdp
  306.    Content-Disposition: session
  307.    Content-Length: 883
  308.    X-FS-Support: update_display,send_info
  309.    Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
  310.    
  311.    v=0
  312.    o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
  313.    s=FreeSWITCH
  314.    c=IN IP4 74.208.165.83
  315.    t=0 0
  316.    m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
  317.    a=rtpmap:102 opus/48000/2
  318.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  319.    a=rtpmap:9 G722/8000
  320.    a=rtpmap:0 PCMU/8000
  321.    a=rtpmap:8 PCMA/8000
  322.    a=rtpmap:103 opus/48000/2
  323.    a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  324.    a=rtpmap:104 telephone-event/48000
  325.    a=fmtp:104 0-16
  326.    a=rtpmap:101 telephone-event/8000
  327.    a=fmtp:101 0-16
  328.    a=ptime:20
  329.    m=video 16426 RTP/AVP 98 96
  330.    b=AS:1024
  331.    a=rtpmap:98 VP9/90000
  332.    a=rtpmap:96 VP8/90000
  333.    a=rtcp-fb:98 ccm fir
  334.    a=rtcp-fb:98 ccm tmmbr
  335.    a=rtcp-fb:98 nack
  336.    a=rtcp-fb:98 nack pli
  337.    a=rtcp-fb:96 ccm fir
  338.    a=rtcp-fb:96 ccm tmmbr
  339.    a=rtcp-fb:96 nack
  340.    a=rtcp-fb:96 nack pli
  341.    ------------------------------------------------------------------------
  342. nta.c:8310 outgoing_send() nta: sent INVITE (113490869) to */65.15.69.32:5060
  343. tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
  344. nta.c:1350 set_timeout() nta: timer set to 32000 ms
  345. nta.c:1348 set_timeout() nta: timer shortened to 1000 ms
  346. nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
  347. tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
  348. tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
  349. tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
  350. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
  351. tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
  352. tport.c:3492 tport_send_msg() tport_vsend returned 1712
  353. send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:28.624785:
  354.    ------------------------------------------------------------------------
  355.    INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
  356.    Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
  357.    Max-Forwards: 70
  358.    From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
  359.    To: <sip:1001@baremetal.joshebosh.com>
  360.    Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
  361.    CSeq: 113490869 INVITE
  362.    Contact: <sip:mod_sofia@74.208.165.83:5080>
  363.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
  364.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  365.    Supported: timer, path, replaces
  366.    Allow-Events: talk, hold, conference, refer
  367.    Content-Type: application/sdp
  368.    Content-Disposition: session
  369.    Content-Length: 883
  370.    X-FS-Support: update_display,send_info
  371.    Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
  372.    
  373.    v=0
  374.    o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
  375.    s=FreeSWITCH
  376.    c=IN IP4 74.208.165.83
  377.    t=0 0
  378.    m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
  379.    a=rtpmap:102 opus/48000/2
  380.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  381.    a=rtpmap:9 G722/8000
  382.    a=rtpmap:0 PCMU/8000
  383.    a=rtpmap:8 PCMA/8000
  384.    a=rtpmap:103 opus/48000/2
  385.    a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  386.    a=rtpmap:104 telephone-event/48000
  387.    a=fmtp:104 0-16
  388.    a=rtpmap:101 telephone-event/8000
  389.    a=fmtp:101 0-16
  390.    a=ptime:20
  391.    m=video 16426 RTP/AVP 98 96
  392.    b=AS:1024
  393.    a=rtpmap:98 VP9/90000
  394.    a=rtpmap:96 VP8/90000
  395.    a=rtcp-fb:98 ccm fir
  396.    a=rtcp-fb:98 ccm tmmbr
  397.    a=rtcp-fb:98 nack
  398.    a=rtcp-fb:98 nack pli
  399.    a=rtcp-fb:96 ccm fir
  400.    a=rtcp-fb:96 ccm tmmbr
  401.    a=rtcp-fb:96 nack
  402.    a=rtcp-fb:96 nack pli
  403.    ------------------------------------------------------------------------
  404. nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
  405. tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
  406. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free
  407. nta.c:1296 agent_timer() nta: timer set next to 2000 ms
  408. nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
  409. tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
  410. tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
  411. tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
  412. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
  413. tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
  414. tport.c:3492 tport_send_msg() tport_vsend returned 1712
  415. send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:30.624814:
  416.    ------------------------------------------------------------------------
  417.    INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
  418.    Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
  419.    Max-Forwards: 70
  420.    From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
  421.    To: <sip:1001@baremetal.joshebosh.com>
  422.    Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
  423.    CSeq: 113490869 INVITE
  424.    Contact: <sip:mod_sofia@74.208.165.83:5080>
  425.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
  426.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  427.    Supported: timer, path, replaces
  428.    Allow-Events: talk, hold, conference, refer
  429.    Content-Type: application/sdp
  430.    Content-Disposition: session
  431.    Content-Length: 883
  432.    X-FS-Support: update_display,send_info
  433.    Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
  434.    
  435.    v=0
  436.    o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
  437.    s=FreeSWITCH
  438.    c=IN IP4 74.208.165.83
  439.    t=0 0
  440.    m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
  441.    a=rtpmap:102 opus/48000/2
  442.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  443.    a=rtpmap:9 G722/8000
  444.    a=rtpmap:0 PCMU/8000
  445.    a=rtpmap:8 PCMA/8000
  446.    a=rtpmap:103 opus/48000/2
  447.    a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  448.    a=rtpmap:104 telephone-event/48000
  449.    a=fmtp:104 0-16
  450.    a=rtpmap:101 telephone-event/8000
  451.    a=fmtp:101 0-16
  452.    a=ptime:20
  453.    m=video 16426 RTP/AVP 98 96
  454.    b=AS:1024
  455.    a=rtpmap:98 VP9/90000
  456.    a=rtpmap:96 VP8/90000
  457.    a=rtcp-fb:98 ccm fir
  458.    a=rtcp-fb:98 ccm tmmbr
  459.    a=rtcp-fb:98 nack
  460.    a=rtcp-fb:98 nack pli
  461.    a=rtcp-fb:96 ccm fir
  462.    a=rtcp-fb:96 ccm tmmbr
  463.    a=rtcp-fb:96 nack
  464.    a=rtcp-fb:96 nack pli
  465.    ------------------------------------------------------------------------
  466. nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
  467. tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
  468. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free
  469. nta.c:1296 agent_timer() nta: timer set next to 4000 ms
  470. nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
  471. tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
  472. tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
  473. tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
  474. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
  475. tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
  476. tport.c:3492 tport_send_msg() tport_vsend returned 1712
  477. send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:34.624859:
  478.    ------------------------------------------------------------------------
  479.    INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
  480.    Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
  481.    Max-Forwards: 70
  482.    From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
  483.    To: <sip:1001@baremetal.joshebosh.com>
  484.    Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
  485.    CSeq: 113490869 INVITE
  486.    Contact: <sip:mod_sofia@74.208.165.83:5080>
  487.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
  488.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  489.    Supported: timer, path, replaces
  490.    Allow-Events: talk, hold, conference, refer
  491.    Content-Type: application/sdp
  492.    Content-Disposition: session
  493.    Content-Length: 883
  494.    X-FS-Support: update_display,send_info
  495.    Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
  496.    
  497.    v=0
  498.    o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
  499.    s=FreeSWITCH
  500.    c=IN IP4 74.208.165.83
  501.    t=0 0
  502.    m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
  503.    a=rtpmap:102 opus/48000/2
  504.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  505.    a=rtpmap:9 G722/8000
  506.    a=rtpmap:0 PCMU/8000
  507.    a=rtpmap:8 PCMA/8000
  508.    a=rtpmap:103 opus/48000/2
  509.    a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  510.    a=rtpmap:104 telephone-event/48000
  511.    a=fmtp:104 0-16
  512.    a=rtpmap:101 telephone-event/8000
  513.    a=fmtp:101 0-16
  514.    a=ptime:20
  515.    m=video 16426 RTP/AVP 98 96
  516.    b=AS:1024
  517.    a=rtpmap:98 VP9/90000
  518.    a=rtpmap:96 VP8/90000
  519.    a=rtcp-fb:98 ccm fir
  520.    a=rtcp-fb:98 ccm tmmbr
  521.    a=rtcp-fb:98 nack
  522.    a=rtcp-fb:98 nack pli
  523.    a=rtcp-fb:96 ccm fir
  524.    a=rtcp-fb:96 ccm tmmbr
  525.    a=rtcp-fb:96 nack
  526.    a=rtcp-fb:96 nack pli
  527.    ------------------------------------------------------------------------
  528. nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
  529. tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
  530. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free
  531. nta.c:1296 agent_timer() nta: timer set next to 8000 ms
  532. nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
  533. tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
  534. tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
  535. tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
  536. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
  537. tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
  538. tport.c:3492 tport_send_msg() tport_vsend returned 1712
  539. send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:42.625013:
  540.    ------------------------------------------------------------------------
  541.    INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
  542.    Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
  543.    Max-Forwards: 70
  544.    From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
  545.    To: <sip:1001@baremetal.joshebosh.com>
  546.    Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
  547.    CSeq: 113490869 INVITE
  548.    Contact: <sip:mod_sofia@74.208.165.83:5080>
  549.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
  550.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  551.    Supported: timer, path, replaces
  552.    Allow-Events: talk, hold, conference, refer
  553.    Content-Type: application/sdp
  554.    Content-Disposition: session
  555.    Content-Length: 883
  556.    X-FS-Support: update_display,send_info
  557.    Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
  558.    
  559.    v=0
  560.    o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
  561.    s=FreeSWITCH
  562.    c=IN IP4 74.208.165.83
  563.    t=0 0
  564.    m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
  565.    a=rtpmap:102 opus/48000/2
  566.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  567.    a=rtpmap:9 G722/8000
  568.    a=rtpmap:0 PCMU/8000
  569.    a=rtpmap:8 PCMA/8000
  570.    a=rtpmap:103 opus/48000/2
  571.    a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  572.    a=rtpmap:104 telephone-event/48000
  573.    a=fmtp:104 0-16
  574.    a=rtpmap:101 telephone-event/8000
  575.    a=fmtp:101 0-16
  576.    a=ptime:20
  577.    m=video 16426 RTP/AVP 98 96
  578.    b=AS:1024
  579.    a=rtpmap:98 VP9/90000
  580.    a=rtpmap:96 VP8/90000
  581.    a=rtcp-fb:98 ccm fir
  582.    a=rtcp-fb:98 ccm tmmbr
  583.    a=rtcp-fb:98 nack
  584.    a=rtcp-fb:98 nack pli
  585.    a=rtcp-fb:96 ccm fir
  586.    a=rtcp-fb:96 ccm tmmbr
  587.    a=rtcp-fb:96 nack
  588.    a=rtcp-fb:96 nack pli
  589.    ------------------------------------------------------------------------
  590. nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
  591. tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
  592. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free
  593. nta.c:1296 agent_timer() nta: timer set next to 16000 ms
  594. 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]
  595. 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]
  596. 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]
  597. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [INFO] mod_dptools.c:3508 Originate Failed.  Cause: ORIGINATOR_CANCEL
  598. 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]
  599. 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)
  600. 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
  601. 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)
  602. 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
  603. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/91001) State HANGUP
  604. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:852 verto.rtc/91001 Send KeyFrame
  605. 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
  606. 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
  607. 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
  608. 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)
  609. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/91001) State REPORTING
  610. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:938 verto.rtc/91001 Send KeyFrame
  611. 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
  612. 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
  613. 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
  614. 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
  615. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [NOTICE] switch_core_session.c:1731 Session 71 (verto.rtc/91001) Ended
  616. 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]
  617. 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)
  618. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/91001) State DESTROY
  619. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [ALERT] switch_core_state_machine.c:751 verto.rtc/91001 Send KeyFrame
  620. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] mod_rtc.c:132 verto.rtc/91001 RTC DESTROY
  621. 3e1aed8d-cbda-ad24-5fdc-6154a47b93d2 2017-10-10 16:44:57.693324 [DEBUG] switch_core_state_machine.c:181 verto.rtc/91001 Standard DESTROY
  622. 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
  623. 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!
  624. 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)
  625. 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
  626. 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
  627. 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
  628. 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
  629. 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
  630. nua.c:651 nua_cancel() nua: nua_cancel: entering
  631. nua_stack.c:569 nua_stack_signal() nua(0x1632cb0): recv signal r_cancel
  632. nua_params.c:482 nua_stack_set_params() nua: nua_stack_set_params: entering
  633. soa.c:403 soa_set_params() soa_set_params(static::0x7ff34004fa50, ...) called
  634. nta.c:8189 outgoing_send_via() nta: delayed sending CANCEL (113490869)
  635. nua_stack.c:529 nua_signal() nua(0x1632cb0): sent signal r_cancel
  636. 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
  637. 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
  638. 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
  639. 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)
  640. 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
  641. 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
  642. 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
  643. 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
  644. 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
  645. 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
  646. 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
  647. 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]
  648. 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)
  649. 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
  650. 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
  651. 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
  652. 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
  653. 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
  654. nta.c:8899 _nta_outgoing_timer() nta: timer A fired, retransmit INVITE (113490869)
  655. tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
  656. tport.c:3257 tport_tsend() tport_tsend(0x7ff340033240) tpn = */65.15.69.32:5060
  657. tport.c:4046 tport_resolve() tport_resolve addrinfo = 65.15.69.32:5060
  658. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7ff340033240): not found by name */65.15.69.32:5060
  659. tport.c:3594 tport_vsend() tport_vsend(0x7ff340033240): 1712 bytes of 1712 to udp/65.15.69.32:5060
  660. tport.c:3492 tport_send_msg() tport_vsend returned 1712
  661. send 1712 bytes to udp/[65.15.69.32]:5060 at 16:44:58.625358:
  662.    ------------------------------------------------------------------------
  663.    INVITE sip:1001@baremetal.joshebosh.com SIP/2.0
  664.    Via: SIP/2.0/UDP 74.208.165.83:5080;rport;branch=z9hG4bKQ53vZSv6Q8e1N
  665.    Max-Forwards: 70
  666.    From: "Extension 1002" <sip:1002@74.208.165.83>;tag=QjNvrB385yQcQ
  667.    To: <sip:1001@baremetal.joshebosh.com>
  668.    Call-ID: 1f1cc943-287d-1236-ce99-0050560f07d6
  669.    CSeq: 113490869 INVITE
  670.    Contact: <sip:mod_sofia@74.208.165.83:5080>
  671.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170924T203829Z~542825ef81~64bit
  672.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  673.    Supported: timer, path, replaces
  674.    Allow-Events: talk, hold, conference, refer
  675.    Content-Type: application/sdp
  676.    Content-Disposition: session
  677.    Content-Length: 883
  678.    X-FS-Support: update_display,send_info
  679.    Remote-Party-ID: "Extension 1002" <sip:1002@74.208.165.83>;party=calling;screen=yes;privacy=off
  680.    
  681.    v=0
  682.    o=FreeSWITCH 1507637419 1507637420 IN IP4 74.208.165.83
  683.    s=FreeSWITCH
  684.    c=IN IP4 74.208.165.83
  685.    t=0 0
  686.    m=audio 16448 RTP/AVP 102 9 0 8 103 104 101
  687.    a=rtpmap:102 opus/48000/2
  688.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  689.    a=rtpmap:9 G722/8000
  690.    a=rtpmap:0 PCMU/8000
  691.    a=rtpmap:8 PCMA/8000
  692.    a=rtpmap:103 opus/48000/2
  693.    a=fmtp:103 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  694.    a=rtpmap:104 telephone-event/48000
  695.    a=fmtp:104 0-16
  696.    a=rtpmap:101 telephone-event/8000
  697.    a=fmtp:101 0-16
  698.    a=ptime:20
  699.    m=video 16426 RTP/AVP 98 96
  700.    b=AS:1024
  701.    a=rtpmap:98 VP9/90000
  702.    a=rtpmap:96 VP8/90000
  703.    a=rtcp-fb:98 ccm fir
  704.    a=rtcp-fb:98 ccm tmmbr
  705.    a=rtcp-fb:98 nack
  706.    a=rtcp-fb:98 nack pli
  707.    a=rtcp-fb:96 ccm fir
  708.    a=rtcp-fb:96 ccm tmmbr
  709.    a=rtcp-fb:96 nack
  710.    a=rtcp-fb:96 nack pli
  711.    ------------------------------------------------------------------------
  712. nta.c:8310 outgoing_send() nta: resent INVITE (113490869) to */65.15.69.32:5060
  713. tport.c:4160 tport_pend() tport_pend(0x7ff340033240): pending 0x7ff340050e80 for udp/74.208.165.83:5080 (already 0)
  714. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/2 free
  715. nta.c:1296 agent_timer() nta: timer set next to 1000 ms
  716. nta.c:8988 outgoing_timer_bf() nta: timer B fired, timeout INVITE (113490869)
  717. tport.c:4222 tport_release() tport_release(0x7ff340033240): 0x7ff340050e80 by 0x7ff340060b40 with (nil)
  718. nua_stack.c:271 nua_stack_event() nua(0x1632cb0): event r_invite 408 Request Timeout
  719. nua_session.c:4139 signal_call_state_change() nua(0x1632cb0): call state changed: calling -> terminated
  720. nua_stack.c:271 nua_stack_event() nua(0x1632cb0): event i_state 408 Request Timeout
  721. nua_stack.c:271 nua_stack_event() nua(0x1632cb0): event i_terminated 408 Request Timeout
  722. nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x1632cb0): removing session usage
  723. soa.c:356 soa_destroy() soa_destroy(static::0x7ff34004fa50) called
  724. nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7ff34004fc30)
  725. nua_stack.c:271 nua_stack_event() nua(0x1632cb0): event r_cancel 408 Request Timeout
  726. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 0/1 resent, 1/1 tout, 0/0 term, 0/2 free
  727. nta.c:1296 agent_timer() nta: timer set next to 5000 ms
  728. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  729. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  730. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  731. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  732. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  733. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  734. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  735. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  736. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  737. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  738. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  739. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  740. nua_stack.c:569 nua_stack_signal() nua(0x1632cb0): recv signal r_destroy
  741. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
  742. nua_stack.c:529 nua_signal() nua(0x1632cb0): sent signal r_destroy
  743. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  744. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  745. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  746. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  747. nta.c:9107 outgoing_timer_dk() nta: timer K fired, terminate CANCEL (113490869)
  748. nta.c:8805 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7ff359c90d40)
  749. nta.c:8935 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
  750. nta.c:1296 agent_timer() nta: timer set next to 27000 ms
  751.