From Lousy Mockingbird, 2 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [NOTICE] switch_channel.c:1104 New Channel sofia/192.168.10.200/262086050@187.239.216.12:5060 [315a736f-b5a2-4190-8f87-2541d13a5ea4]
  2. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:584 (sofia/192.168.10.200/262086050@187.239.216.12:5060) Running State Change CS_NEW
  3. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] sofia.c:9639 sofia/192.168.10.200/262086050@187.239.216.12:5060 receiving invite from 187.239.216.12:5060 version: 1.7.0 git d34b501 2016-09-20 22:18:10Z 64bit
  4. nua.c:610 nua_set_hparams() nua: nua_r_set_params with invalid handle (nil)
  5. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] sofia.c:6965 Channel sofia/192.168.10.200/262086050@187.239.216.12:5060 entering state [received][100]
  6. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] sofia.c:6975 Remote SDP:
  7. v=0
  8. o=- 907486760 907486761 IN IP4 187.239.216.12
  9. s=VoipSIP
  10. c=IN IP4 187.239.216.12
  11. t=0 0
  12. m=audio 7446 RTP/AVP 8 0 97
  13. a=rtpmap:8 PCMA/8000
  14. a=rtpmap:0 PCMU/8000
  15. a=rtpmap:97 telephone-event/8000
  16. a=fmtp:97 0-15
  17. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] sofia.c:7344 (sofia/192.168.10.200/262086050@187.239.216.12:5060) State Change CS_NEW -> CS_INIT
  18. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:603 (sofia/192.168.10.200/262086050@187.239.216.12:5060) State NEW
  19. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:584 (sofia/192.168.10.200/262086050@187.239.216.12:5060) Running State Change CS_INIT
  20. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:627 (sofia/192.168.10.200/262086050@187.239.216.12:5060) State INIT
  21. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] mod_sofia.c:90 sofia/192.168.10.200/262086050@187.239.216.12:5060 SOFIA INIT
  22. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:40 sofia/192.168.10.200/262086050@187.239.216.12:5060 Standard INIT
  23. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:48 (sofia/192.168.10.200/262086050@187.239.216.12:5060) State Change CS_INIT -> CS_ROUTING
  24. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:627 (sofia/192.168.10.200/262086050@187.239.216.12:5060) State INIT going to sleep
  25. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:584 (sofia/192.168.10.200/262086050@187.239.216.12:5060) Running State Change CS_ROUTING
  26. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_channel.c:2249 (sofia/192.168.10.200/262086050@187.239.216.12:5060) Callstate Change DOWN -> RINGING
  27. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:643 (sofia/192.168.10.200/262086050@187.239.216.12:5060) State ROUTING
  28. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] mod_sofia.c:143 sofia/192.168.10.200/262086050@187.239.216.12:5060 SOFIA ROUTING
  29. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [DEBUG] switch_core_state_machine.c:236 sofia/192.168.10.200/262086050@187.239.216.12:5060 Standard ROUTING
  30. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.018819 [INFO] mod_dialplan_xml.c:637 Processing 262086050 <262086050>->962810476 in context default
  31. [...]
  32.  
  33. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [INFO] switch_ivr_originate.c:1215 Sending early media
  34. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:4299 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  35. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:4354 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  36. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:4299 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  37. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:4215 Set telephone-event payload to 97@8000
  38. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:3019 Set Codec sofia/192.168.10.200/262086050@187.239.216.12:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  39. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_codec.c:111 sofia/192.168.10.200/262086050@187.239.216.12:5060 Original read codec set to PCMA:8
  40. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:4560 Set telephone-event payload to 97@8000
  41. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:4619 sofia/192.168.10.200/262086050@187.239.216.12:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  42. [...]
  43. 2016-09-29 16:33:02.238820 [DEBUG] switch_core_port_allocator.c:183 UDP port robustness check for port 10442 pass
  44. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:6479 AUDIO RTP [sofia/192.168.10.200/262086050@187.239.216.12:5060] 192.168.10.200 port 10442 -> 187.239.216.12 port 7446 codec: 8 ms: 20
  45. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_rtp.c:3838 Starting timer [soft] 160 bytes per 20ms
  46. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:6778 sofia/192.168.10.200/262086050@187.239.216.12:5060 Set 2833 dtmf send payload to 101
  47. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:6785 sofia/192.168.10.200/262086050@187.239.216.12:5060 Set 2833 dtmf receive payload to 101
  48. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] switch_core_media.c:6808 sofia/192.168.10.200/262086050@187.239.216.12:5060 Set rtp dtmf delay to 40
  49. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [NOTICE] sofia_media.c:92 Pre-Answer sofia/192.168.10.200/262086050@187.239.216.12:5060!
  50. 315a736f-b5a2-4190-8f87-2541d13a5ea4 EXECUTE sofia/192.168.10.200/262086050@187.239.216.12:5060 lua(execute_on_ring.lua)
  51. 2016-09-29 16:33:02.238820 [DEBUG] switch_cpp.cpp:1360 /opt/commsmundi/scripts/fs/execute_on_ring.lua:12 starting
  52. 315a736f-b5a2-4190-8f87-2541d13a5ea4 EXECUTE sofia/192.168.10.200/262086050@187.239.216.12:5060 set(_execute_on_ring_done=true)
  53. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.238820 [DEBUG] mod_dptools.c:1519 SET sofia/192.168.10.200/262086050@187.239.216.12:5060 [_execute_on_ring_done]=[true]
  54. 315a736f-b5a2-4190-8f87-2541d13a5ea4 EXECUTE sofia/192.168.10.200/262086050@187.239.216.12:5060 lua(execute_on_ring_line.lua)
  55. 2016-09-29 16:33:02.258821 [DEBUG] switch_cpp.cpp:1360 /opt/commsmundi/scripts/fs/execute_on_ring_line.lua:13 starting (SUPER_RABINO_ENTRADA, => inbound)
  56. 315a736f-b5a2-4190-8f87-2541d13a5ea4 EXECUTE sofia/192.168.10.200/262086050@187.239.216.12:5060 hash(insert/last_inbound_uuid/line:SUPER_RABINO_ENTRADA/315a736f-b5a2-4190-8f87-2541d13a5ea4)
  57. 315a736f-b5a2-4190-8f87-2541d13a5ea4 EXECUTE sofia/192.168.10.200/262086050@187.239.216.12:5060 hash(insert/last_uuid/line:SUPER_RABINO_ENTRADA/315a736f-b5a2-4190-8f87-2541d13a5ea4)
  58. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.258821 [DEBUG] switch_cpp.cpp:1107 sofia/192.168.10.200/262086050@187.239.216.12:5060 destroy/unlink session from object
  59. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.258821 [DEBUG] switch_cpp.cpp:1107 sofia/192.168.10.200/262086050@187.239.216.12:5060 destroy/unlink session from object
  60. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.258821 [DEBUG] switch_channel.c:3480 (sofia/192.168.10.200/262086050@187.239.216.12:5060) Callstate Change RINGING -> EARLY
  61. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.258821 [DEBUG] mod_sofia.c:2307 Ring SDP:
  62. v=0
  63. o=FreeSWITCH 1475149140 1475149141 IN IP4 84.124.119.225
  64. s=FreeSWITCH
  65. c=IN IP4 84.124.119.225
  66. t=0 0
  67. m=audio 10442 RTP/AVP 8 101
  68. a=rtpmap:8 PCMA/8000
  69. a=rtpmap:101 telephone-event/8000
  70. a=fmtp:101 0-16
  71. a=silenceSupp:off - - - -
  72. a=ptime:20
  73. a=sendrecv
  74.  
  75. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.258821 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  76. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.258821 [DEBUG] switch_core_codec.c:221 sofia/192.168.10.200/262086050@187.239.216.12:5060 Push codec L16:100
  77. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.258821 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(1500,3000,425.0)]
  78. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.278821 [DEBUG] sofia.c:6965 Channel sofia/192.168.10.200/262086050@187.239.216.12:5060 entering state [early][183]
  79. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.478822 [DEBUG] switch_rtp.c:6738 Correct audio ip/port confirmed.
  80. [...]
  81. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.738819 [DEBUG] switch_core_codec.c:246 sofia/192.168.10.200/262086050@187.239.216.12:5060 Restore previous codec PCMA:8.
  82. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.738819 [DEBUG] mod_sofia.c:822 Local SDP sofia/192.168.10.200/262086050@187.239.216.12:5060:
  83. v=0
  84. o=FreeSWITCH 1475149140 1475149142 IN IP4 84.124.119.225
  85. s=FreeSWITCH
  86. c=IN IP4 84.124.119.225
  87. t=0 0
  88. m=audio 10442 RTP/AVP 8 101
  89. a=rtpmap:8 PCMA/8000
  90. a=rtpmap:101 telephone-event/8000
  91. a=fmtp:101 0-16
  92. a=silenceSupp:off - - - -
  93. a=ptime:20
  94. a=sendrecv
  95.  
  96. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.738819 [NOTICE] switch_ivr_originate.c:3628 Channel [sofia/192.168.10.200/262086050@187.239.216.12:5060] has been answered
  97. 315a736f-b5a2-4190-8f87-2541d13a5ea4 EXECUTE sofia/192.168.10.200/262086050@187.239.216.12:5060 lua(execute_on_answer.lua)
  98. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:02.738819 [DEBUG] sofia.c:6965 Channel sofia/192.168.10.200/262086050@187.239.216.12:5060 entering state [completed][200]
  99. [...]
  100. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.758819 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  101. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.758819 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  102. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.778832 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  103. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.778832 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  104. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.798820 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  105. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.798820 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  106. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.818820 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  107. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.818820 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  108. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.838820 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  109. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.838820 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  110. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.858819 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  111. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.858819 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  112. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.878820 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  113. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.878820 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  114. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.898829 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  115. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.898829 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  116. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.918820 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  117. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.918820 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  118. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.938820 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  119. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.938820 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  120. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.958831 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  121. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:07.958831 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  122. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:08.298818 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  123. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:08.298818 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  124. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:08.318819 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  125. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:08.318819 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  126. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:08.338819 [DEBUG] switch_core_media.c:2542 alternate payload received (received 97, expecting 8)
  127. 315a736f-b5a2-4190-8f87-2541d13a5ea4 2016-09-29 16:33:08.338819 [WARNING] switch_core_media.c:2565 Could not change to payload type 97, ignoring...
  128.