From Agustí Ubalde, 7 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: goldenrod; font-weight: bold;"> 2017-05-18 18:27:05.704303 [DEBUG] mod_verto.c:4064 89.1.250.120:60761 Client Connect from 89.1.250.120:60761 accepted
  2. 2017-05-18 18:27:05.704303 [DEBUG] mod_verto.c:1978 89.1.250.120:60761 Starting client thread.
  3. 2017-05-18 18:27:05.744296 [DEBUG] mod_verto.c:1267 89.1.250.120:60761 re-connecting session 29958187-3a76-c51b-2e6c-cdf1e5d92f49
  4. 2017-05-18 18:27:28.404299 [DEBUG] mod_verto.c:4064 89.1.250.120:60816 Client Connect from 89.1.250.120:60816 accepted
  5. 2017-05-18 18:27:28.404299 [DEBUG] mod_verto.c:1978 89.1.250.120:60816 Starting client thread.
  6. 2017-05-18 18:27:28.444300 [DEBUG] mod_verto.c:1267 89.1.250.120:60816 re-connecting session 85f46bc0-257b-df98-4db7-a32d1e941279
  7. 2017-05-18 18:27:36.504289 [DEBUG] mod_rtc.c:389 () State Change CS_NEW -> CS_INIT
  8. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_session.c:613 N/A set UUID=85f4dcc6-3b5e-183e-af2f-925d5db97b66
  9. 2017-05-18 18:27:36.504289 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/1001 [85f4dcc6-3b5e-183e-af2f-925d5db97b66]
  10. 2017-05-18 18:27:36.504289 [DEBUG] mod_verto.c:3603 Remote SDP verto.rtc/1001:
  11. v=0
  12. o=- 1081317877255920020 2 IN IP4 127.0.0.1
  13. s=-
  14. t=0 0
  15. a=group:BUNDLE audio
  16. a=msid-semantic: WMS 7Idx1eQKTwlSZEC1lCROARBqn9vrN8T1yspw
  17. m=audio 50533 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
  18. c=IN IP4 89.1.250.120
  19. a=rtcp:9 IN IP4 0.0.0.0
  20. a=candidate:1543227530 1 udp 2122265343 fd66:1762:b6b8:1000:59fe:c8b2:6a05:3c50 50532 typ host generation 0 network-id 3 network-cost 50
  21. a=candidate:1566881451 1 udp 2122194687 89.1.250.120 50533 typ host generation 0 network-id 2
  22. a=candidate:2999745851 1 udp 2122129151 192.168.56.1 50534 typ host generation 0 network-id 1
  23. a=candidate:360199290 1 tcp 1518285567 fd66:1762:b6b8:1000:59fe:c8b2:6a05:3c50 9 typ host tcptype active generation 0 network-id 3 network-cost 50
  24. a=candidate:333924955 1 tcp 1518214911 89.1.250.120 9 typ host tcptype active generation 0 network-id 2
  25. a=candidate:4233069003 1 tcp 1518149375 192.168.56.1 9 typ host tcptype active generation 0 network-id 1
  26. a=ice-ufrag:afPg
  27. a=ice-pwd:rcj6SsVgZRjRkJWJYBnEOPtY
  28. a=fingerprint:sha-256 2A:E9:59:A0:D2:73:69:7F:B5:F7:9B:A7:C3:B3:03:3E:5F:E9:5C:B5:ED:E2:F1:3E:66:A1:32:77:F5:8D:00:04
  29. a=setup:actpass
  30. a=mid:audio
  31. a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  32. a=sendrecv
  33. a=rtcp-mux
  34. a=rtpmap:111 opus/48000/2
  35. a=rtcp-fb:111 transport-cc
  36. a=fmtp:111 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1
  37. a=rtpmap:103 ISAC/16000
  38. a=rtpmap:104 ISAC/32000
  39. a=rtpmap:9 G722/8000
  40. a=rtpmap:0 PCMU/8000
  41. a=rtpmap:8 PCMA/8000
  42. a=rtpmap:106 CN/32000
  43. a=rtpmap:105 CN/16000
  44. a=rtpmap:13 CN/8000
  45. a=rtpmap:110 telephone-event/48000
  46. a=rtpmap:112 telephone-event/32000
  47. a=rtpmap:113 telephone-event/16000
  48. a=rtpmap:126 telephone-event/8000
  49. a=ssrc:1992292474 cname:bQsBu1h5TT6DmiUv
  50. a=ssrc:1992292474 msid:7Idx1eQKTwlSZEC1lCROARBqn9vrN8T1yspw 5497791f-6fdd-49b8-9931-b6646a243cf0
  51. a=ssrc:1992292474 mslabel:7Idx1eQKTwlSZEC1lCROARBqn9vrN8T1yspw
  52. a=ssrc:1992292474 label:5497791f-6fdd-49b8-9931-b6646a243cf0
  53.  
  54. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_INIT (Cur 1 Tot 46)
  55. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/1001) State INIT
  56. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:40 verto.rtc/1001 Standard INIT
  57. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/1001) State Change CS_INIT -> CS_ROUTING
  58. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/1001) State INIT going to sleep
  59. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_ROUTING (Cur 1 Tot 46)
  60. 2017-05-18 18:27:36.504289 [DEBUG] switch_channel.c:2249 (verto.rtc/1001) Callstate Change DOWN -> RINGING
  61. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/1001) State ROUTING
  62. 2017-05-18 18:27:36.504289 [DEBUG] mod_rtc.c:89 verto.rtc/1001 RTC ROUTING
  63. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:236 verto.rtc/1001 Standard ROUTING
  64. 2017-05-18 18:27:36.504289 [INFO] mod_dialplan_xml.c:637 Processing 1000 <1000>->1001 in context public
  65. Dialplan: verto.rtc/1001 parsing [public->verto call] continue=true
  66. Dialplan: verto.rtc/1001 Regex (PASS) [verto call] destination_number(1001) =~ /^(\d+)$/ break=on-false
  67. Dialplan: verto.rtc/1001 Action log(INFO ***** Verto WebRTC Call ***** )
  68. Dialplan: verto.rtc/1001 Action bridge(${verto_contact 1001})
  69. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/1001) State Change CS_ROUTING -> CS_EXECUTE
  70. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/1001) State ROUTING going to sleep
  71. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_EXECUTE (Cur 1 Tot 46)
  72. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/1001) State EXECUTE
  73. 2017-05-18 18:27:36.504289 [DEBUG] mod_rtc.c:120 verto.rtc/1001 RTC EXECUTE
  74. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:328 verto.rtc/1001 Standard EXECUTE
  75. EXECUTE verto.rtc/1001 log(INFO ***** Verto WebRTC Call ***** )
  76. 2017-05-18 18:27:36.504289 [INFO] mod_dptools.c:1721 ***** Verto WebRTC Call *****
  77. EXECUTE verto.rtc/1001 bridge(verto.rtc/u:85f46bc0-257b-df98-4db7-a32d1e941279)
  78. 2017-05-18 18:27:36.504289 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables
  79. 2017-05-18 18:27:36.504289 [DEBUG] mod_rtc.c:389 () State Change CS_NEW -> CS_INIT
  80. 2017-05-18 18:27:36.504289 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 [4a53bb02-a26e-422f-8363-7945eab73e73]
  81. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Running State Change CS_INIT (Cur 2 Tot 47)
  82. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State INIT
  83. 2017-05-18 18:27:36.504289 [DEBUG] mod_verto.c:2212 Local verto.invite SDP verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279:
  84. v=0
  85. o=FreeSWITCH 1495103652 1495103653 IN IP4 10.100.2.83
  86. s=FreeSWITCH
  87. c=IN IP4 10.100.2.83
  88. t=0 0
  89. a=msid-semantic: WMS 0UdFfd2V86Bohk00iiRWfOWA4xeSZM5Q
  90. m=audio 21204 RTP/SAVPF 102 9 0 8
  91. a=rtpmap:102 opus/48000/2
  92. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  93. a=rtpmap:9 G722/8000
  94. a=rtpmap:0 PCMU/8000
  95. a=rtpmap:8 PCMA/8000
  96. a=fingerprint:sha-256 4F:04:51:8A:03:26:BA:4A:88:B4:E9:B6:74:C2:87:1B:20:D5:5A:8D:7D:BA:79:86:51:B0:43:22:7A:27:C4:BB
  97. a=setup:actpass
  98. a=rtcp-mux
  99. a=rtcp:21204 IN IP4 10.100.2.83
  100. a=ssrc:1898608344 cname:w8lCcenaaPoi0f88
  101. a=ssrc:1898608344 msid:0UdFfd2V86Bohk00iiRWfOWA4xeSZM5Q a0
  102. a=ssrc:1898608344 mslabel:0UdFfd2V86Bohk00iiRWfOWA4xeSZM5Q
  103. a=ssrc:1898608344 label:0UdFfd2V86Bohk00iiRWfOWA4xeSZM5Qa0
  104. a=ice-ufrag:nQUtuGTp4Y7TA2zX
  105. a=ice-pwd:0Lp2PD3QjiY9nW8BDSVXpEck
  106. a=candidate:6525246038 1 udp 659136 10.100.2.83 21204 typ host generation 0
  107. a=candidate:6525246038 2 udp 659136 10.100.2.83 21204 typ host generation 0
  108. a=silenceSupp:off - - - -
  109. a=ptime:20
  110. a=sendrecv
  111.  
  112. 2017-05-18 18:27:36.504289 [NOTICE] mod_verto.c:2312 Ring-Ready verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279!
  113. 2017-05-18 18:27:36.504289 [DEBUG] switch_channel.c:3345 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Callstate Change DOWN -> RINGING
  114. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:40 verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 Standard INIT
  115. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State Change CS_INIT -> CS_ROUTING
  116. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State INIT going to sleep
  117. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Running State Change CS_ROUTING (Cur 2 Tot 47)
  118. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State ROUTING
  119. 2017-05-18 18:27:36.504289 [DEBUG] mod_rtc.c:89 verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 RTC ROUTING
  120. 2017-05-18 18:27:36.504289 [DEBUG] switch_ivr_originate.c:67 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  121. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State ROUTING going to sleep
  122. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 47)
  123. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State CONSUME_MEDIA
  124. 2017-05-18 18:27:36.504289 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State CONSUME_MEDIA going to sleep
  125. 2017-05-18 18:27:36.504289 [NOTICE] switch_ivr_originate.c:525 Ring Ready verto.rtc/1001!
  126. 2017-05-18 18:27:36.504289 [NOTICE] switch_ivr_originate.c:525 Ring-Ready verto.rtc/1001!
  127. 2017-05-18 18:28:06.504295 [NOTICE] mod_verto.c:2755 Hangup verto.rtc/1001 [CS_EXECUTE] [NORMAL_CLEARING]
  128. 2017-05-18 18:28:06.524299 [NOTICE] switch_ivr_originate.c:3601 Hangup verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
  129. 2017-05-18 18:28:06.524299 [DEBUG] switch_ivr_originate.c:3818 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
  130. 2017-05-18 18:28:06.524299 [INFO] mod_dptools.c:3409 Originate Failed.  Cause: ORIGINATOR_CANCEL
  131. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_session.c:2797 verto.rtc/1001 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  132. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/1001) State EXECUTE going to sleep
  133. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_HANGUP (Cur 2 Tot 47)
  134. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/1001) Callstate Change RINGING -> HANGUP
  135. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/1001) State HANGUP
  136. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:60 verto.rtc/1001 Standard HANGUP, cause: NORMAL_CLEARING
  137. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/1001) State HANGUP going to sleep
  138. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/1001) State Change CS_HANGUP -> CS_REPORTING
  139. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1001) Running State Change CS_REPORTING (Cur 2 Tot 47)
  140. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/1001) State REPORTING
  141. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:174 verto.rtc/1001 Standard REPORTING, cause: NORMAL_CLEARING
  142. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/1001) State REPORTING going to sleep
  143. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/1001) State Change CS_REPORTING -> CS_DESTROY
  144. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_session.c:1647 Session 46 (verto.rtc/1001) Locked, Waiting on external entities
  145. 2017-05-18 18:28:06.524299 [NOTICE] switch_core_session.c:1665 Session 46 (verto.rtc/1001) Ended
  146. 2017-05-18 18:28:06.524299 [NOTICE] switch_core_session.c:1669 Close Channel verto.rtc/1001 [CS_DESTROY]
  147. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/1001) Running State Change CS_DESTROY (Cur 1 Tot 47)
  148. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/1001) State DESTROY
  149. 2017-05-18 18:28:06.524299 [DEBUG] mod_rtc.c:132 verto.rtc/1001 RTC DESTROY
  150. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:181 verto.rtc/1001 Standard DESTROY
  151. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/1001) State DESTROY going to sleep
  152. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Running State Change CS_HANGUP (Cur 1 Tot 47)
  153. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Callstate Change RINGING -> HANGUP
  154. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State HANGUP
  155. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:60 verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 Standard HANGUP, cause: ORIGINATOR_CANCEL
  156. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State HANGUP going to sleep
  157. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State Change CS_HANGUP -> CS_REPORTING
  158. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Running State Change CS_REPORTING (Cur 1 Tot 47)
  159. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State REPORTING
  160. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:174 verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 Standard REPORTING, cause: ORIGINATOR_CANCEL
  161. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State REPORTING going to sleep
  162. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State Change CS_REPORTING -> CS_DESTROY
  163. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_session.c:1647 Session 47 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Locked, Waiting on external entities
  164. 2017-05-18 18:28:06.524299 [NOTICE] switch_core_session.c:1665 Session 47 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Ended
  165. 2017-05-18 18:28:06.524299 [NOTICE] switch_core_session.c:1669 Close Channel verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 [CS_DESTROY]
  166. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) Running State Change CS_DESTROY (Cur 0 Tot 47)
  167. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State DESTROY
  168. 2017-05-18 18:28:06.524299 [DEBUG] mod_rtc.c:132 verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 RTC DESTROY
  169. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:181 verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279 Standard DESTROY
  170. 2017-05-18 18:28:06.524299 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/85f46bc0-257b-df98-4db7-a32d1e941279) State DESTROY going to sleep
  171.  
captcha