FreeSWITCH Pastebin

FS Cli Log

From Sam, 8 Years ago, written in FreeSWITCH.
- go back
URL https://pastebin.freeswitch.org/view/f9be26b9
Embed
Viewing differences between and FS Cli Log
  1. span style="color: cornflowerblue; font-weight: bold;"> 2016-11-04 08:56:37.704543 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/8003@172.xx.xx.3 [41267c3a-f0ed-4ab5-a836-0fea886d3863]
  2. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_NEW
  3. 2016-11-04 08:56:37.704543 [DEBUG] sofia.c:9374 sofia/internal/8003@172.xx.xx.3 receiving invite from 172.xx.xx.3:65516 version: 1.6.7 git f0c3870 2016-04-01 13:40:07Z 64bit
  4. 2016-11-04 08:56:37.704543 [DEBUG] sofia.c:9486 IP 172.xx.xx.3 Approved by acl "domains[]". Access Granted.
  5. 2016-11-04 08:56:37.704543 [DEBUG] sofia.c:10676 Setting NAT mode based on via port
  6. 2016-11-04 08:56:37.704543 [DEBUG] sofia.c:6858 Channel sofia/internal/8003@172.xx.xx.3 entering state [received][100]
  7. 2016-11-04 08:56:37.704543 [DEBUG] sofia.c:6868 Remote SDP:
  8. v=0
  9. o=CiscoSystemsSIP-GW-UserAgent 2699 1175 IN IP4 172.xx.xx.3
  10. s=SIP Call
  11. c=IN IP4 172.xx.xx.3
  12. t=0 0
  13. m=audio 18568 RTP/AVP 18 101 121
  14. c=IN IP4 172.xx.xx.3
  15. a=rtpmap:18 G729/8000
  16. a=fmtp:18 annexb=no
  17. a=rtpmap:101 telephone-event/8000
  18. a=fmtp:101 0-16
  19. a=rtpmap:121 frf-dialed-digit/8000
  20. a=fmtp:121 0-15
  21. a=ptime:20
  22.  
  23. 2016-11-04 08:56:37.704543 [DEBUG] sofia.c:7227 (sofia/internal/8003@172.xx.xx.3) State Change CS_NEW -> CS_INIT
  24. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:562 (sofia/internal/8003@172.xx.xx.3) State NEW
  25. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_INIT
  26. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/8003@172.xx.xx.3) State INIT
  27. 2016-11-04 08:56:37.704543 [DEBUG] mod_sofia.c:89 sofia/internal/8003@172.xx.xx.3 SOFIA INIT
  28. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:40 sofia/internal/8003@172.xx.xx.3 Standard INIT
  29. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/8003@172.xx.xx.3) State Change CS_INIT -> CS_ROUTING
  30. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/8003@172.xx.xx.3) State INIT going to sleep
  31. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_ROUTING
  32. 2016-11-04 08:56:37.704543 [DEBUG] switch_channel.c:2249 (sofia/internal/8003@172.xx.xx.3) Callstate Change DOWN -> RINGING
  33. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/8003@172.xx.xx.3) State ROUTING
  34. 2016-11-04 08:56:37.704543 [DEBUG] mod_sofia.c:142 sofia/internal/8003@172.xx.xx.3 SOFIA ROUTING
  35. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:236 sofia/internal/8003@172.xx.xx.3 Standard ROUTING
  36. 2016-11-04 08:56:37.704543 [INFO] mod_dialplan_xml.c:637 Processing 8003 <8003>->4099 in context public
  37. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->unloop] continue=false
  38. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  39. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  40. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->group_dial_huntgroup] continue=false
  41. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [group_dial_huntgroup] destination_number(4099) =~ /^0870980891$/ break=on-false
  42. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->group_dial_did_to_local] continue=false
  43. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [group_dial_did_to_local] destination_number(4099) =~ /^0870980892$/ break=on-false
  44. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->outside_call] continue=true
  45. Dialplan: sofia/internal/8003@172.xx.xx.3 Absolute Condition [outside_call]
  46. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(outside_call=true)
  47. Dialplan: sofia/internal/8003@172.xx.xx.3 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  48. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->call_debug] continue=true
  49. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  50. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_extensions] continue=false
  51. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_extensions] destination_number(4099) =~ /^(10[01][0-9])$/ break=on-false
  52. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_did] continue=false
  53. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_did] destination_number(4099) =~ /^(5551212)$/ break=on-false
  54. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_did] continue=false
  55. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_did] destination_number(4099) =~ /^(0870980892)$/ break=on-false
  56. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->nb_conferences] continue=false
  57. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [nb_conferences] destination_number(4099) =~ /^0870980890$/ break=on-false
  58. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->nb_conferences] continue=false
  59. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [nb_conferences] destination_number(4099) =~ /^0870980893$/ break=on-false
  60. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->conf11001] continue=false
  61. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [conf11001] destination_number(4099) =~ /^11001$/ break=on-false
  62. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->UNKNOWN] continue=false
  63. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (PASS) [UNKNOWN] destination_number(4099) =~ /^(4099)$/ break=on-false
  64. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(transfer_after_bridge=4099)
  65. Dialplan: sofia/internal/8003@172.xx.xx.3 Action sleep(300)
  66. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set uuid ${caller_id_number} '${uuid}')})
  67. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set type ${caller_id_number} 'uuid-standby')})
  68. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set status ${caller_id_number} 'Available')})
  69. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set state ${caller_id_number} 'idle')})
  70. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(cc_warning_tone=tone_stream://%(200,0,500,600,700);loops=3)
  71. Dialplan: sofia/internal/8003@172.xx.xx.3 Action answer()
  72. Dialplan: sofia/internal/8003@172.xx.xx.3 Action playback(local_stream://moh)
  73. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/8003@172.xx.xx.3) State Change CS_ROUTING -> CS_EXECUTE
  74. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/8003@172.xx.xx.3) State ROUTING going to sleep
  75. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_EXECUTE
  76. 2016-11-04 08:56:37.704543 [CONSOLE] sofia_presence.c:1618 Event Thread Started
  77. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/8003@172.xx.xx.3) State EXECUTE
  78. 2016-11-04 08:56:37.704543 [DEBUG] mod_sofia.c:197 sofia/internal/8003@172.xx.xx.3 SOFIA EXECUTE
  79. 2016-11-04 08:56:37.704543 [DEBUG] switch_core_state_machine.c:328 sofia/internal/8003@172.xx.xx.3 Standard EXECUTE
  80. EXECUTE sofia/internal/8003@172.xx.xx.3 set(outside_call=true)
  81. 2016-11-04 08:56:37.704543 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [outside_call]=[true]
  82. EXECUTE sofia/internal/8003@172.xx.xx.3 export(RFC2822_DATE=Fri, 04 Nov 2016 08:56:37 +0200)
  83. 2016-11-04 08:56:37.704543 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 04 Nov 2016 08:56:37 +0200]
  84. EXECUTE sofia/internal/8003@172.xx.xx.3 set(transfer_after_bridge=4099)
  85. 2016-11-04 08:56:37.704543 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [transfer_after_bridge]=[4099]
  86. EXECUTE sofia/internal/8003@172.xx.xx.3 sleep(300)
  87. 2016-11-04 08:56:37.997528 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set uuid = 41267c3a-f0ed-4ab5-a836-0fea886d3863
  88. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  89. )
  90. 2016-11-04 08:56:37.997528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  91. ]
  92. 2016-11-04 08:56:37.997528 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set type = uuid-standby
  93. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  94. )
  95. 2016-11-04 08:56:37.997528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  96. ]
  97. 2016-11-04 08:56:37.997528 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set status = Available
  98. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  99. )
  100. 2016-11-04 08:56:37.997528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  101. ]
  102. 2016-11-04 08:56:37.997528 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = idle
  103. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  104. )
  105. 2016-11-04 08:56:37.997528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  106. ]
  107. EXECUTE sofia/internal/8003@172.xx.xx.3 set(cc_warning_tone=tone_stream://%(200,0,500,600,700);loops=3)
  108. 2016-11-04 08:56:37.997528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [cc_warning_tone]=[tone_stream://%(200,0,500,600,700);loops=3]
  109. EXECUTE sofia/internal/8003@172.xx.xx.3 answer()
  110. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:3720 Activate Buggy RFC2833 Mode!
  111. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:4211 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  112. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:4266 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  113. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:4127 Set telephone-event payload to 101@8000
  114. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:4211 Audio Codec Compare [frf-dialed-digit:121:8000:20:0:1]/[G729:18:8000:20:8000:1]
  115. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:2946 Set Codec sofia/internal/8003@172.xx.xx.3 G729/8000 20 ms 160 samples 8000 bits 1 channels
  116. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_codec.c:111 sofia/internal/8003@172.xx.xx.3 Original read codec set to G729:18
  117. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:4479 Set telephone-event payload to 101@8000
  118. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:4537 sofia/internal/8003@172.xx.xx.3 Set 2833 dtmf send payload to 101 recv payload to 101
  119. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:6320 AUDIO RTP [sofia/internal/8003@172.xx.xx.3] 172.31.35.60 port 29602 -> 172.xx.xx.3 port 18568 codec: 18 ms: 20
  120. 2016-11-04 08:56:37.997528 [DEBUG] switch_rtp.c:3812 Starting timer [soft] 160 bytes per 20ms
  121. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:6619 sofia/internal/8003@172.xx.xx.3 Set 2833 dtmf send payload to 101
  122. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:6626 sofia/internal/8003@172.xx.xx.3 Set 2833 dtmf receive payload to 101
  123. 2016-11-04 08:56:37.997528 [DEBUG] switch_core_media.c:6649 sofia/internal/8003@172.xx.xx.3 Set rtp dtmf delay to 40
  124. 2016-11-04 08:56:37.997528 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/8003@172.xx.xx.3!
  125. 2016-11-04 08:56:37.997528 [DEBUG] switch_channel.c:3471 (sofia/internal/8003@172.xx.xx.3) Callstate Change RINGING -> EARLY
  126. 2016-11-04 08:56:37.997528 [DEBUG] mod_sofia.c:814 Local SDP sofia/internal/8003@172.xx.xx.3:
  127. v=0
  128. o=FreeSWITCH 1478212995 1478212996 IN IP4 172.31.35.60
  129. s=FreeSWITCH
  130. c=IN IP4 172.31.35.60
  131. t=0 0
  132. m=audio 29602 RTP/AVP 18 101
  133. a=rtpmap:18 G729/8000
  134. a=fmtp:18 annexb=no
  135. a=rtpmap:101 telephone-event/8000
  136. a=fmtp:101 0-16
  137. a=ptime:20
  138. a=sendrecv
  139.  
  140. 2016-11-04 08:56:37.997528 [NOTICE] mod_dptools.c:1309 Channel [sofia/internal/8003@172.xx.xx.3] has been answered
  141. 2016-11-04 08:56:37.997528 [DEBUG] switch_channel.c:3770 (sofia/internal/8003@172.xx.xx.3) Callstate Change EARLY -> ACTIVE
  142. 2016-11-04 08:56:37.997528 [DEBUG] sofia.c:6858 Channel sofia/internal/8003@172.xx.xx.3 entering state [completed][200]
  143. EXECUTE sofia/internal/8003@172.xx.xx.3 playback(local_stream://moh)
  144. 2016-11-04 08:56:37.997528 [DEBUG] mod_local_stream.c:843 Opening Stream [moh/8000] 8000hz
  145. 2016-11-04 08:56:37.997528 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
  146. 2016-11-04 08:56:38.017473 [DEBUG] sofia.c:6858 Channel sofia/internal/8003@172.xx.xx.3 entering state [ready][200]
  147. 2016-11-04 08:56:38.017473 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc204b8988 0x7efc204b8988
  148. 2016-11-04 08:56:38.017473 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc204b8988 0x7efc204b8988
  149. 2016-11-04 08:56:38.237569 [DEBUG] switch_rtp.c:6670 Correct audio ip/port confirmed.
  150. 2016-11-04 08:56:45.597565 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = waiting
  151. 2016-11-04 08:56:47.117517 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
  152. 2016-11-04 08:56:47.117517 [NOTICE] switch_channel.c:1104 New Channel sofia/external/0833573222 [f53f74c1-62f0-4608-90e3-ecf01e2e4020]
  153. 2016-11-04 08:56:47.117517 [DEBUG] mod_sofia.c:4799 (sofia/external/0833573222) State Change CS_NEW -> CS_INIT
  154. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_INIT
  155. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:586 (sofia/external/0833573222) State INIT
  156. 2016-11-04 08:56:47.117517 [DEBUG] mod_sofia.c:89 sofia/external/0833573222 SOFIA INIT
  157. 2016-11-04 08:56:47.117517 [DEBUG] sofia_glue.c:1257 sofia/external/0833573222 sending invite version: 1.6.7 git f0c3870 2016-04-01 13:40:07Z 64bit
  158. Local SDP:
  159. v=0
  160. o=FreeSWITCH 1478217901 1478217902 IN IP4 172.xx.xx.85
  161. s=FreeSWITCH
  162. c=IN IP4 172.xx.xx.85
  163. t=0 0
  164. m=audio 24706 RTP/AVP 18
  165. a=rtpmap:18 G729/8000
  166. a=fmtp:18 annexb=no
  167. a=ptime:20
  168. a=sendrecv
  169.  
  170. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:40 sofia/external/0833573222 Standard INIT
  171. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:48 (sofia/external/0833573222) State Change CS_INIT -> CS_ROUTING
  172. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:586 (sofia/external/0833573222) State INIT going to sleep
  173. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_ROUTING
  174. 2016-11-04 08:56:47.117517 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [calling][0]
  175. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING
  176. 2016-11-04 08:56:47.117517 [DEBUG] mod_sofia.c:142 sofia/external/0833573222 SOFIA ROUTING
  177. 2016-11-04 08:56:47.117517 [DEBUG] switch_ivr_originate.c:67 (sofia/external/0833573222) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  178. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING going to sleep
  179. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_CONSUME_MEDIA
  180. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:621 (sofia/external/0833573222) State CONSUME_MEDIA
  181. 2016-11-04 08:56:47.117517 [DEBUG] switch_core_state_machine.c:621 (sofia/external/0833573222) State CONSUME_MEDIA going to sleep
  182. 2016-11-04 08:56:47.117517 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [calling][0]
  183. 2016-11-04 08:56:48.377480 [INFO] sofia.c:1255 sofia/external/0833573222 Update Callee ID to "Outbound Call" <sip:D0820833573222@172.xx.xx.61>
  184. 2016-11-04 08:56:48.377480 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [proceeding][183]
  185. 2016-11-04 08:56:48.377480 [DEBUG] sofia.c:6868 Remote SDP:
  186. v=0
  187. o=FreeSWITCH 1478219828 1478219829 IN IP4 172.xx.xx.61
  188. s=FreeSWITCH
  189. c=IN IP4 172.xx.xx.61
  190. t=0 0
  191. m=audio 22794 RTP/AVP 18
  192. a=rtpmap:18 G729/8000
  193. a=fmtp:18 annexb=no
  194. a=ptime:20
  195.  
  196. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:4211 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  197. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:4266 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  198. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:2946 Set Codec sofia/external/0833573222 G729/8000 20 ms 160 samples 8000 bits 1 channels
  199. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_codec.c:111 sofia/external/0833573222 Original read codec set to G729:18
  200. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:4509 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  201. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:4537 sofia/external/0833573222 Set 2833 dtmf send payload to 101 recv payload to 101
  202. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:6320 AUDIO RTP [sofia/external/0833573222] 172.xx.xx.85 port 24706 -> 172.xx.xx.61 port 22794 codec: 18 ms: 20
  203. 2016-11-04 08:56:48.377480 [DEBUG] switch_rtp.c:3812 Starting timer [soft] 160 bytes per 20ms
  204. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:6619 sofia/external/0833573222 Set 2833 dtmf send payload to 101
  205. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:6626 sofia/external/0833573222 Set 2833 dtmf receive payload to 101
  206. 2016-11-04 08:56:48.377480 [DEBUG] switch_core_media.c:6649 sofia/external/0833573222 Set rtp dtmf delay to 40
  207. 2016-11-04 08:56:48.377480 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/0833573222!
  208. 2016-11-04 08:56:48.377480 [DEBUG] switch_channel.c:3471 (sofia/external/0833573222) Callstate Change DOWN -> EARLY
  209. 2016-11-04 08:56:48.397474 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/external/0833573222]
  210. 2016-11-04 08:56:48.397474 [DEBUG] switch_ivr.c:2160 (sofia/external/0833573222) State Change CS_CONSUME_MEDIA -> CS_ROUTING
  211. 2016-11-04 08:56:48.397474 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/0833573222 to XML[0870980890@default]
  212. 2016-11-04 08:56:48.397474 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_ROUTING
  213. 2016-11-04 08:56:48.397474 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING
  214. 2016-11-04 08:56:48.397474 [DEBUG] mod_sofia.c:142 sofia/external/0833573222 SOFIA ROUTING
  215. 2016-11-04 08:56:48.397474 [DEBUG] switch_core_state_machine.c:236 sofia/external/0833573222 Standard ROUTING
  216. 2016-11-04 08:56:48.397474 [INFO] mod_dialplan_xml.c:637 Processing  <0870980000>->0870980890 in context default
  217. Dialplan: sofia/external/0833573222 parsing [default->unloop] continue=false
  218. Dialplan: sofia/external/0833573222 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  219. Dialplan: sofia/external/0833573222 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  220. Dialplan: sofia/external/0833573222 parsing [default->tod_example] continue=true
  221. Dialplan: sofia/external/0833573222 Date/TimeMatch (FAIL) [tod_example] break=on-false
  222. Dialplan: sofia/external/0833573222 parsing [default->holiday_example] continue=true
  223. Dialplan: sofia/external/0833573222 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  224. Dialplan: sofia/external/0833573222 parsing [default->global-intercept] continue=false
  225. Dialplan: sofia/external/0833573222 Regex (FAIL) [global-intercept] destination_number(0870980890) =~ /^886$/ break=on-false
  226. Dialplan: sofia/external/0833573222 parsing [default->group-intercept] continue=false
  227. Dialplan: sofia/external/0833573222 Regex (FAIL) [group-intercept] destination_number(0870980890) =~ /^\*8$/ break=on-false
  228. Dialplan: sofia/external/0833573222 parsing [default->intercept-ext] continue=false
  229. Dialplan: sofia/external/0833573222 Regex (FAIL) [intercept-ext] destination_number(0870980890) =~ /^\*\*(\d+)$/ break=on-false
  230. Dialplan: sofia/external/0833573222 parsing [default->redial] continue=false
  231. Dialplan: sofia/external/0833573222 Regex (FAIL) [redial] destination_number(0870980890) =~ /^(redial|870)$/ break=on-false
  232. Dialplan: sofia/external/0833573222 parsing [default->global] continue=true
  233. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  234. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${default_password}(4321) =~ /^1234$/ break=never
  235. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  236. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${endpoint_disposition}(EARLY MEDIA) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  237. Dialplan: sofia/external/0833573222 parsing [default->snom-demo-2] continue=false
  238. Dialplan: sofia/external/0833573222 Regex (FAIL) [snom-demo-2] destination_number(0870980890) =~ /^9001$/ break=on-false
  239. Dialplan: sofia/external/0833573222 parsing [default->snom-demo-1] continue=false
  240. Dialplan: sofia/external/0833573222 Regex (FAIL) [snom-demo-1] destination_number(0870980890) =~ /^9000$/ break=on-false
  241. Dialplan: sofia/external/0833573222 parsing [default->eavesdrop] continue=false
  242. Dialplan: sofia/external/0833573222 Regex (FAIL) [eavesdrop] destination_number(0870980890) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  243. Dialplan: sofia/external/0833573222 parsing [default->eavesdrop] continue=false
  244. Dialplan: sofia/external/0833573222 Regex (FAIL) [eavesdrop] destination_number(0870980890) =~ /^779$/ break=on-false
  245. Dialplan: sofia/external/0833573222 parsing [default->call_return] continue=false
  246. Dialplan: sofia/external/0833573222 Regex (FAIL) [call_return] destination_number(0870980890) =~ /^\*69$|^869$|^lcr$/ break=on-false
  247. Dialplan: sofia/external/0833573222 parsing [default->del-group] continue=false
  248. Dialplan: sofia/external/0833573222 Regex (FAIL) [del-group] destination_number(0870980890) =~ /^80(\d{2})$/ break=on-false
  249. Dialplan: sofia/external/0833573222 parsing [default->add-group] continue=false
  250. Dialplan: sofia/external/0833573222 Regex (FAIL) [add-group] destination_number(0870980890) =~ /^81(\d{2})$/ break=on-false
  251. Dialplan: sofia/external/0833573222 parsing [default->call-group-simo] continue=false
  252. Dialplan: sofia/external/0833573222 Regex (FAIL) [call-group-simo] destination_number(0870980890) =~ /^82(\d{2})$/ break=on-false
  253. Dialplan: sofia/external/0833573222 parsing [default->call-group-order] continue=false
  254. Dialplan: sofia/external/0833573222 Regex (FAIL) [call-group-order] destination_number(0870980890) =~ /^83(\d{2})$/ break=on-false
  255. Dialplan: sofia/external/0833573222 parsing [default->extension-intercom] continue=false
  256. Dialplan: sofia/external/0833573222 Regex (FAIL) [extension-intercom] destination_number(0870980890) =~ /^8(10[01][0-9])$/ break=on-false
  257. Dialplan: sofia/external/0833573222 parsing [default->Local_Ext] continue=false
  258. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Ext] destination_number(0870980890) =~ /^(30[01][0-9])$/ break=on-false
  259. Dialplan: sofia/external/0833573222 parsing [default->Local_Extension] continue=false
  260. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Extension] destination_number(0870980890) =~ /^(10[01][0-9])$/ break=on-false
  261. Dialplan: sofia/external/0833573222 parsing [default->Local_Extension_Skinny] continue=false
  262. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Extension_Skinny] destination_number(0870980890) =~ /^(11[01][0-9])$/ break=on-false
  263. Dialplan: sofia/external/0833573222 parsing [default->group_dial_sales] continue=false
  264. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_sales] destination_number(0870980890) =~ /^2000$/ break=on-false
  265. Dialplan: sofia/external/0833573222 parsing [default->group_dial_support] continue=false
  266. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_support] destination_number(0870980890) =~ /^2001$/ break=on-false
  267. Dialplan: sofia/external/0833573222 parsing [default->group_dial_billing] continue=false
  268. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_billing] destination_number(0870980890) =~ /^2002$/ break=on-false
  269. Dialplan: sofia/external/0833573222 parsing [default->group_dial_huntgroup] continue=false
  270. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_huntgroup] destination_number(0870980890) =~ /^0870980891$/ break=on-false
  271. Dialplan: sofia/external/0833573222 parsing [default->group_dial_did_to_local] continue=false
  272. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_did_to_local] destination_number(0870980890) =~ /^0870980892$/ break=on-false
  273. Dialplan: sofia/external/0833573222 parsing [default->operator] continue=false
  274. Dialplan: sofia/external/0833573222 Regex (FAIL) [operator] destination_number(0870980890) =~ /^(operator|0)$/ break=on-false
  275. Dialplan: sofia/external/0833573222 parsing [default->vmain] continue=false
  276. Dialplan: sofia/external/0833573222 Regex (FAIL) [vmain] destination_number(0870980890) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  277. Dialplan: sofia/external/0833573222 parsing [default->sip_uri] continue=false
  278. Dialplan: sofia/external/0833573222 Regex (FAIL) [sip_uri] destination_number(0870980890) =~ /^sip:(.*)$/ break=on-false
  279. Dialplan: sofia/external/0833573222 parsing [default->nb_conferences] continue=false
  280. Dialplan: sofia/external/0833573222 Regex (FAIL) [nb_conferences] destination_number(0870980890) =~ /^(30\d{2})$/ break=on-false
  281. Dialplan: sofia/external/0833573222 parsing [default->wb_conferences] continue=false
  282. Dialplan: sofia/external/0833573222 Regex (FAIL) [wb_conferences] destination_number(0870980890) =~ /^(31\d{2})$/ break=on-false
  283. Dialplan: sofia/external/0833573222 parsing [default->uwb_conferences] continue=false
  284. Dialplan: sofia/external/0833573222 Regex (FAIL) [uwb_conferences] destination_number(0870980890) =~ /^(32\d{2})$/ break=on-false
  285. Dialplan: sofia/external/0833573222 parsing [default->cdquality_conferences] continue=false
  286. Dialplan: sofia/external/0833573222 Regex (FAIL) [cdquality_conferences] destination_number(0870980890) =~ /^(33\d{2})$/ break=on-false
  287. Dialplan: sofia/external/0833573222 parsing [default->cdquality_conferences] continue=false
  288. Dialplan: sofia/external/0833573222 Regex (FAIL) [cdquality_conferences] destination_number(0870980890) =~ /^(35\d{2})$/ break=on-false
  289. Dialplan: sofia/external/0833573222 parsing [default->freeswitch_public_conf_via_sip] continue=false
  290. Dialplan: sofia/external/0833573222 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(0870980890) =~ /^9(888|8888|1616|3232)$/ break=on-false
  291. Dialplan: sofia/external/0833573222 parsing [default->mad_boss_intercom] continue=false
  292. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss_intercom] destination_number(0870980890) =~ /^0911$/ break=on-false
  293. Dialplan: sofia/external/0833573222 parsing [default->mad_boss_intercom] continue=false
  294. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss_intercom] destination_number(0870980890) =~ /^0912$/ break=on-false
  295. Dialplan: sofia/external/0833573222 parsing [default->mad_boss] continue=false
  296. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss] destination_number(0870980890) =~ /^0913$/ break=on-false
  297. Dialplan: sofia/external/0833573222 parsing [default->ivr_demo] continue=false
  298. Dialplan: sofia/external/0833573222 Regex (FAIL) [ivr_demo] destination_number(0870980890) =~ /^5000$/ break=on-false
  299. Dialplan: sofia/external/0833573222 parsing [default->dynamic_conference] continue=false
  300. Dialplan: sofia/external/0833573222 Regex (FAIL) [dynamic_conference] destination_number(0870980890) =~ /^5001$/ break=on-false
  301. Dialplan: sofia/external/0833573222 parsing [default->rtp_multicast_page] continue=false
  302. Dialplan: sofia/external/0833573222 Regex (FAIL) [rtp_multicast_page] destination_number(0870980890) =~ /^pagegroup$|^7243$/ break=on-false
  303. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  304. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] destination_number(0870980890) =~ /^5900$/ break=on-false
  305. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  306. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] destination_number(0870980890) =~ /^5901$/ break=on-false
  307. Dialplan: sofia/external/0833573222 parsing [default->valet_park] continue=false
  308. Dialplan: sofia/external/0833573222 Regex (FAIL) [valet_park] destination_number(0870980890) =~ /^(6000)$/ break=on-false
  309. Dialplan: sofia/external/0833573222 parsing [default->valet_park] continue=false
  310. Dialplan: sofia/external/0833573222 Regex (FAIL) [valet_park] destination_number(0870980890) =~ /^((?!6000)60\d{2})$/ break=on-false
  311. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  312. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  313. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  314. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  315. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  316. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  317. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  318. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  319. Dialplan: sofia/external/0833573222 parsing [default->wait] continue=false
  320. Dialplan: sofia/external/0833573222 Regex (FAIL) [wait] destination_number(0870980890) =~ /^wait$/ break=on-false
  321. Dialplan: sofia/external/0833573222 parsing [default->fax_receive] continue=false
  322. Dialplan: sofia/external/0833573222 Regex (FAIL) [fax_receive] destination_number(0870980890) =~ /^9178$/ break=on-false
  323. Dialplan: sofia/external/0833573222 parsing [default->fax_transmit] continue=false
  324. Dialplan: sofia/external/0833573222 Regex (FAIL) [fax_transmit] destination_number(0870980890) =~ /^9179$/ break=on-false
  325. Dialplan: sofia/external/0833573222 parsing [default->ringback_180] continue=false
  326. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_180] destination_number(0870980890) =~ /^9180$/ break=on-false
  327. Dialplan: sofia/external/0833573222 parsing [default->ringback_183_uk_ring] continue=false
  328. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_183_uk_ring] destination_number(0870980890) =~ /^9181$/ break=on-false
  329. Dialplan: sofia/external/0833573222 parsing [default->ringback_183_music_ring] continue=false
  330. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_183_music_ring] destination_number(0870980890) =~ /^9182$/ break=on-false
  331. Dialplan: sofia/external/0833573222 parsing [default->ringback_post_answer_uk_ring] continue=false
  332. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(0870980890) =~ /^9183$/ break=on-false
  333. Dialplan: sofia/external/0833573222 parsing [default->ringback_post_answer_music] continue=false
  334. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_post_answer_music] destination_number(0870980890) =~ /^9184$/ break=on-false
  335. Dialplan: sofia/external/0833573222 parsing [default->ClueCon] continue=false
  336. Dialplan: sofia/external/0833573222 Regex (FAIL) [ClueCon] destination_number(0870980890) =~ /^9191$/ break=on-false
  337. Dialplan: sofia/external/0833573222 parsing [default->show_info] continue=false
  338. Dialplan: sofia/external/0833573222 Regex (FAIL) [show_info] destination_number(0870980890) =~ /^9192$/ break=on-false
  339. Dialplan: sofia/external/0833573222 parsing [default->video_record] continue=false
  340. Dialplan: sofia/external/0833573222 Regex (FAIL) [video_record] destination_number(0870980890) =~ /^9193$/ break=on-false
  341. Dialplan: sofia/external/0833573222 parsing [default->video_playback] continue=false
  342. Dialplan: sofia/external/0833573222 Regex (FAIL) [video_playback] destination_number(0870980890) =~ /^9194$/ break=on-false
  343. Dialplan: sofia/external/0833573222 parsing [default->delay_echo] continue=false
  344. Dialplan: sofia/external/0833573222 Regex (FAIL) [delay_echo] destination_number(0870980890) =~ /^9195$/ break=on-false
  345. Dialplan: sofia/external/0833573222 parsing [default->echo] continue=false
  346. Dialplan: sofia/external/0833573222 Regex (FAIL) [echo] destination_number(0870980890) =~ /^9196$/ break=on-false
  347. Dialplan: sofia/external/0833573222 parsing [default->milliwatt] continue=false
  348. Dialplan: sofia/external/0833573222 Regex (FAIL) [milliwatt] destination_number(0870980890) =~ /^9197$/ break=on-false
  349. Dialplan: sofia/external/0833573222 parsing [default->tone_stream] continue=false
  350. Dialplan: sofia/external/0833573222 Regex (FAIL) [tone_stream] destination_number(0870980890) =~ /^9198$/ break=on-false
  351. Dialplan: sofia/external/0833573222 parsing [default->zrtp_enrollement] continue=false
  352. Dialplan: sofia/external/0833573222 Regex (FAIL) [zrtp_enrollement] destination_number(0870980890) =~ /^9787$/ break=on-false
  353. Dialplan: sofia/external/0833573222 parsing [default->hold_music] continue=false
  354. Dialplan: sofia/external/0833573222 Regex (FAIL) [hold_music] destination_number(0870980890) =~ /^9664$/ break=on-false
  355. Dialplan: sofia/external/0833573222 parsing [default->laugh break] continue=false
  356. Dialplan: sofia/external/0833573222 Regex (FAIL) [laugh break] destination_number(0870980890) =~ /^9386$/ break=on-false
  357. Dialplan: sofia/external/0833573222 parsing [default->101] continue=false
  358. Dialplan: sofia/external/0833573222 Regex (FAIL) [101] destination_number(0870980890) =~ /^101$/ break=on-false
  359. Dialplan: sofia/external/0833573222 parsing [default->pizza_demo] continue=false
  360. Dialplan: sofia/external/0833573222 Regex (FAIL) [pizza_demo] destination_number(0870980890) =~ /^(pizza|74992)$/ break=on-false
  361. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Time] continue=false
  362. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Time] destination_number(0870980890) =~ /^9170$/ break=on-false
  363. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Date] continue=false
  364. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Date] destination_number(0870980890) =~ /^9171$/ break=on-false
  365. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Date and Time] continue=false
  366. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Date and Time] destination_number(0870980890) =~ /^9172$/ break=on-false
  367. Dialplan: sofia/external/0833573222 parsing [default->exe_on_ans] continue=false
  368. Dialplan: sofia/external/0833573222 Regex (PASS) [exe_on_ans] destination_number(0870980890) =~ /^0870980890$/ break=on-false
  369. Dialplan: sofia/external/0833573222 Action set(execute_on_answer=transfer ANSWEREDCALL XML default)
  370. Dialplan: sofia/external/0833573222 Action log(INFO Waiting 30 seconds for $1 to answer...)
  371. Dialplan: sofia/external/0833573222 Action sleep(30000)
  372. Dialplan: sofia/external/0833573222 Action log(INFO Call to $1 was not answered, taking alternative action...)
  373. Dialplan: sofia/external/0833573222 Action transfer(UNANSWEREDCALL XML default)
  374. 2016-11-04 08:56:48.397474 [INFO] switch_channel.c:3127 sofia/external/0833573222 Flipping CID from "" <0870980000> to "Outbound Call" <D0820833573222>
  375. 2016-11-04 08:56:48.397474 [DEBUG] switch_core_state_machine.c:286 (sofia/external/0833573222) State Change CS_ROUTING -> CS_EXECUTE
  376. 2016-11-04 08:56:48.397474 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING going to sleep
  377. 2016-11-04 08:56:48.397474 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_EXECUTE
  378. 2016-11-04 08:56:48.397474 [DEBUG] switch_core_state_machine.c:609 (sofia/external/0833573222) State EXECUTE
  379. 2016-11-04 08:56:48.397474 [DEBUG] mod_sofia.c:197 sofia/external/0833573222 SOFIA EXECUTE
  380. 2016-11-04 08:56:48.397474 [DEBUG] switch_core_state_machine.c:328 sofia/external/0833573222 Standard EXECUTE
  381. EXECUTE sofia/external/0833573222 set(execute_on_answer=transfer ANSWEREDCALL XML default)
  382. 2016-11-04 08:56:48.397474 [DEBUG] mod_dptools.c:1519 SET sofia/external/0833573222 [execute_on_answer]=[transfer ANSWEREDCALL XML default]
  383. EXECUTE sofia/external/0833573222 log(INFO Waiting 30 seconds for $1 to answer...)
  384. 2016-11-04 08:56:48.397474 [INFO] mod_dptools.c:1713 Waiting 30 seconds for $1 to answer...
  385. EXECUTE sofia/external/0833573222 sleep(30000)
  386. 2016-11-04 08:56:48.417537 [DEBUG] switch_rtp.c:6670 Correct audio ip/port confirmed.
  387. 2016-11-04 08:56:49.077564 [DEBUG] switch_core_media.c:2386 Correcting calculated ptime value from 300 to 150 to compensate for 1 lost packet(s)
  388. 2016-11-04 08:56:49.197564 [WARNING] switch_core_media.c:2418 Asynchronous PTIME not supported, changing our end from 20 to 60
  389. 2016-11-04 08:56:49.217472 [DEBUG] switch_core_media.c:2844 Changing Codec from G729@20ms@8000hz to G729@60ms@8000hz
  390. 2016-11-04 08:56:49.257567 [DEBUG] switch_rtp.c:3690 RE-Starting timer [soft] 480 bytes per 60ms
  391. 2016-11-04 08:56:49.257567 [DEBUG] switch_core_media.c:2946 Set Codec sofia/external/0833573222 G729/8000 60 ms 480 samples 8000 bits 1 channels
  392. 2016-11-04 08:56:49.257567 [DEBUG] switch_core_codec.c:123 sofia/external/0833573222 Original read codec replaced with G729:18
  393. 2016-11-04 08:56:55.317529 [INFO] sofia.c:1244 sofia/external/0833573222 Update Caller ID to "Outbound Call" <sip:D0820833573222@172.xx.xx.61>
  394. 2016-11-04 08:56:55.317529 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [completing][200]
  395. 2016-11-04 08:56:55.317529 [DEBUG] sofia.c:6865 Duplicate SDP
  396. v=0
  397. o=FreeSWITCH 1478219828 1478219829 IN IP4 172.xx.xx.61
  398. s=FreeSWITCH
  399. c=IN IP4 172.xx.xx.61
  400. t=0 0
  401. m=audio 22794 RTP/AVP 18
  402. a=rtpmap:18 G729/8000
  403. a=fmtp:18 annexb=no
  404. a=ptime:20
  405.  
  406. 2016-11-04 08:56:55.377488 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [ready][200]
  407. 2016-11-04 08:56:55.377488 [NOTICE] sofia.c:7765 Channel [sofia/external/0833573222] has been answered
  408. EXECUTE sofia/external/0833573222 transfer(ANSWEREDCALL XML default)
  409. 2016-11-04 08:56:55.377488 [DEBUG] switch_ivr.c:2160 (sofia/external/0833573222) State Change CS_EXECUTE -> CS_ROUTING
  410. 2016-11-04 08:56:55.377488 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/0833573222 to XML[ANSWEREDCALL@default]
  411. 2016-11-04 08:56:55.377488 [DEBUG] switch_channel.c:3770 (sofia/external/0833573222) Callstate Change EARLY -> ACTIVE
  412. 2016-11-04 08:56:55.377488 [DEBUG] switch_core_state_machine.c:609 (sofia/external/0833573222) State EXECUTE going to sleep
  413. 2016-11-04 08:56:55.377488 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_ROUTING
  414. 2016-11-04 08:56:55.377488 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING
  415. 2016-11-04 08:56:55.377488 [DEBUG] mod_sofia.c:142 sofia/external/0833573222 SOFIA ROUTING
  416. 2016-11-04 08:56:55.377488 [DEBUG] switch_core_state_machine.c:236 sofia/external/0833573222 Standard ROUTING
  417. 2016-11-04 08:56:55.377488 [INFO] mod_dialplan_xml.c:637 Processing Outbound Call <D0820833573222>->ANSWEREDCALL in context default
  418. Dialplan: sofia/external/0833573222 parsing [default->unloop] continue=false
  419. Dialplan: sofia/external/0833573222 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  420. Dialplan: sofia/external/0833573222 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  421. Dialplan: sofia/external/0833573222 parsing [default->tod_example] continue=true
  422. Dialplan: sofia/external/0833573222 Date/TimeMatch (FAIL) [tod_example] break=on-false
  423. Dialplan: sofia/external/0833573222 parsing [default->holiday_example] continue=true
  424. Dialplan: sofia/external/0833573222 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  425. Dialplan: sofia/external/0833573222 parsing [default->global-intercept] continue=false
  426. Dialplan: sofia/external/0833573222 Regex (FAIL) [global-intercept] destination_number(ANSWEREDCALL) =~ /^886$/ break=on-false
  427. Dialplan: sofia/external/0833573222 parsing [default->group-intercept] continue=false
  428. Dialplan: sofia/external/0833573222 Regex (FAIL) [group-intercept] destination_number(ANSWEREDCALL) =~ /^\*8$/ break=on-false
  429. Dialplan: sofia/external/0833573222 parsing [default->intercept-ext] continue=false
  430. Dialplan: sofia/external/0833573222 Regex (FAIL) [intercept-ext] destination_number(ANSWEREDCALL) =~ /^\*\*(\d+)$/ break=on-false
  431. Dialplan: sofia/external/0833573222 parsing [default->redial] continue=false
  432. Dialplan: sofia/external/0833573222 Regex (FAIL) [redial] destination_number(ANSWEREDCALL) =~ /^(redial|870)$/ break=on-false
  433. Dialplan: sofia/external/0833573222 parsing [default->global] continue=true
  434. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  435. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${default_password}(4321) =~ /^1234$/ break=never
  436. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  437. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${endpoint_disposition}(ANSWER) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  438. Dialplan: sofia/external/0833573222 parsing [default->snom-demo-2] continue=false
  439. Dialplan: sofia/external/0833573222 Regex (FAIL) [snom-demo-2] destination_number(ANSWEREDCALL) =~ /^9001$/ break=on-false
  440. Dialplan: sofia/external/0833573222 parsing [default->snom-demo-1] continue=false
  441. Dialplan: sofia/external/0833573222 Regex (FAIL) [snom-demo-1] destination_number(ANSWEREDCALL) =~ /^9000$/ break=on-false
  442. Dialplan: sofia/external/0833573222 parsing [default->eavesdrop] continue=false
  443. Dialplan: sofia/external/0833573222 Regex (FAIL) [eavesdrop] destination_number(ANSWEREDCALL) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  444. Dialplan: sofia/external/0833573222 parsing [default->eavesdrop] continue=false
  445. Dialplan: sofia/external/0833573222 Regex (FAIL) [eavesdrop] destination_number(ANSWEREDCALL) =~ /^779$/ break=on-false
  446. Dialplan: sofia/external/0833573222 parsing [default->call_return] continue=false
  447. Dialplan: sofia/external/0833573222 Regex (FAIL) [call_return] destination_number(ANSWEREDCALL) =~ /^\*69$|^869$|^lcr$/ break=on-false
  448. Dialplan: sofia/external/0833573222 parsing [default->del-group] continue=false
  449. Dialplan: sofia/external/0833573222 Regex (FAIL) [del-group] destination_number(ANSWEREDCALL) =~ /^80(\d{2})$/ break=on-false
  450. Dialplan: sofia/external/0833573222 parsing [default->add-group] continue=false
  451. Dialplan: sofia/external/0833573222 Regex (FAIL) [add-group] destination_number(ANSWEREDCALL) =~ /^81(\d{2})$/ break=on-false
  452. Dialplan: sofia/external/0833573222 parsing [default->call-group-simo] continue=false
  453. Dialplan: sofia/external/0833573222 Regex (FAIL) [call-group-simo] destination_number(ANSWEREDCALL) =~ /^82(\d{2})$/ break=on-false
  454. Dialplan: sofia/external/0833573222 parsing [default->call-group-order] continue=false
  455. Dialplan: sofia/external/0833573222 Regex (FAIL) [call-group-order] destination_number(ANSWEREDCALL) =~ /^83(\d{2})$/ break=on-false
  456. Dialplan: sofia/external/0833573222 parsing [default->extension-intercom] continue=false
  457. Dialplan: sofia/external/0833573222 Regex (FAIL) [extension-intercom] destination_number(ANSWEREDCALL) =~ /^8(10[01][0-9])$/ break=on-false
  458. Dialplan: sofia/external/0833573222 parsing [default->Local_Ext] continue=false
  459. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Ext] destination_number(ANSWEREDCALL) =~ /^(30[01][0-9])$/ break=on-false
  460. Dialplan: sofia/external/0833573222 parsing [default->Local_Extension] continue=false
  461. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Extension] destination_number(ANSWEREDCALL) =~ /^(10[01][0-9])$/ break=on-false
  462. Dialplan: sofia/external/0833573222 parsing [default->Local_Extension_Skinny] continue=false
  463. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Extension_Skinny] destination_number(ANSWEREDCALL) =~ /^(11[01][0-9])$/ break=on-false
  464. Dialplan: sofia/external/0833573222 parsing [default->group_dial_sales] continue=false
  465. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_sales] destination_number(ANSWEREDCALL) =~ /^2000$/ break=on-false
  466. Dialplan: sofia/external/0833573222 parsing [default->group_dial_support] continue=false
  467. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_support] destination_number(ANSWEREDCALL) =~ /^2001$/ break=on-false
  468. Dialplan: sofia/external/0833573222 parsing [default->group_dial_billing] continue=false
  469. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_billing] destination_number(ANSWEREDCALL) =~ /^2002$/ break=on-false
  470. Dialplan: sofia/external/0833573222 parsing [default->group_dial_huntgroup] continue=false
  471. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_huntgroup] destination_number(ANSWEREDCALL) =~ /^0870980891$/ break=on-false
  472. Dialplan: sofia/external/0833573222 parsing [default->group_dial_did_to_local] continue=false
  473. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_did_to_local] destination_number(ANSWEREDCALL) =~ /^0870980892$/ break=on-false
  474. Dialplan: sofia/external/0833573222 parsing [default->operator] continue=false
  475. Dialplan: sofia/external/0833573222 Regex (FAIL) [operator] destination_number(ANSWEREDCALL) =~ /^(operator|0)$/ break=on-false
  476. Dialplan: sofia/external/0833573222 parsing [default->vmain] continue=false
  477. Dialplan: sofia/external/0833573222 Regex (FAIL) [vmain] destination_number(ANSWEREDCALL) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  478. Dialplan: sofia/external/0833573222 parsing [default->sip_uri] continue=false
  479. Dialplan: sofia/external/0833573222 Regex (FAIL) [sip_uri] destination_number(ANSWEREDCALL) =~ /^sip:(.*)$/ break=on-false
  480. Dialplan: sofia/external/0833573222 parsing [default->nb_conferences] continue=false
  481. Dialplan: sofia/external/0833573222 Regex (FAIL) [nb_conferences] destination_number(ANSWEREDCALL) =~ /^(30\d{2})$/ break=on-false
  482. Dialplan: sofia/external/0833573222 parsing [default->wb_conferences] continue=false
  483. Dialplan: sofia/external/0833573222 Regex (FAIL) [wb_conferences] destination_number(ANSWEREDCALL) =~ /^(31\d{2})$/ break=on-false
  484. Dialplan: sofia/external/0833573222 parsing [default->uwb_conferences] continue=false
  485. Dialplan: sofia/external/0833573222 Regex (FAIL) [uwb_conferences] destination_number(ANSWEREDCALL) =~ /^(32\d{2})$/ break=on-false
  486. Dialplan: sofia/external/0833573222 parsing [default->cdquality_conferences] continue=false
  487. Dialplan: sofia/external/0833573222 Regex (FAIL) [cdquality_conferences] destination_number(ANSWEREDCALL) =~ /^(33\d{2})$/ break=on-false
  488. Dialplan: sofia/external/0833573222 parsing [default->cdquality_conferences] continue=false
  489. Dialplan: sofia/external/0833573222 Regex (FAIL) [cdquality_conferences] destination_number(ANSWEREDCALL) =~ /^(35\d{2})$/ break=on-false
  490. Dialplan: sofia/external/0833573222 parsing [default->freeswitch_public_conf_via_sip] continue=false
  491. Dialplan: sofia/external/0833573222 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(ANSWEREDCALL) =~ /^9(888|8888|1616|3232)$/ break=on-false
  492. Dialplan: sofia/external/0833573222 parsing [default->mad_boss_intercom] continue=false
  493. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss_intercom] destination_number(ANSWEREDCALL) =~ /^0911$/ break=on-false
  494. Dialplan: sofia/external/0833573222 parsing [default->mad_boss_intercom] continue=false
  495. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss_intercom] destination_number(ANSWEREDCALL) =~ /^0912$/ break=on-false
  496. Dialplan: sofia/external/0833573222 parsing [default->mad_boss] continue=false
  497. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss] destination_number(ANSWEREDCALL) =~ /^0913$/ break=on-false
  498. Dialplan: sofia/external/0833573222 parsing [default->ivr_demo] continue=false
  499. Dialplan: sofia/external/0833573222 Regex (FAIL) [ivr_demo] destination_number(ANSWEREDCALL) =~ /^5000$/ break=on-false
  500. Dialplan: sofia/external/0833573222 parsing [default->dynamic_conference] continue=false
  501. Dialplan: sofia/external/0833573222 Regex (FAIL) [dynamic_conference] destination_number(ANSWEREDCALL) =~ /^5001$/ break=on-false
  502. Dialplan: sofia/external/0833573222 parsing [default->rtp_multicast_page] continue=false
  503. Dialplan: sofia/external/0833573222 Regex (FAIL) [rtp_multicast_page] destination_number(ANSWEREDCALL) =~ /^pagegroup$|^7243$/ break=on-false
  504. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  505. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] destination_number(ANSWEREDCALL) =~ /^5900$/ break=on-false
  506. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  507. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] destination_number(ANSWEREDCALL) =~ /^5901$/ break=on-false
  508. Dialplan: sofia/external/0833573222 parsing [default->valet_park] continue=false
  509. Dialplan: sofia/external/0833573222 Regex (FAIL) [valet_park] destination_number(ANSWEREDCALL) =~ /^(6000)$/ break=on-false
  510. Dialplan: sofia/external/0833573222 parsing [default->valet_park] continue=false
  511. Dialplan: sofia/external/0833573222 Regex (FAIL) [valet_park] destination_number(ANSWEREDCALL) =~ /^((?!6000)60\d{2})$/ break=on-false
  512. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  513. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  514. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  515. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  516. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  517. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  518. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  519. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  520. Dialplan: sofia/external/0833573222 parsing [default->wait] continue=false
  521. Dialplan: sofia/external/0833573222 Regex (FAIL) [wait] destination_number(ANSWEREDCALL) =~ /^wait$/ break=on-false
  522. Dialplan: sofia/external/0833573222 parsing [default->fax_receive] continue=false
  523. Dialplan: sofia/external/0833573222 Regex (FAIL) [fax_receive] destination_number(ANSWEREDCALL) =~ /^9178$/ break=on-false
  524. Dialplan: sofia/external/0833573222 parsing [default->fax_transmit] continue=false
  525. Dialplan: sofia/external/0833573222 Regex (FAIL) [fax_transmit] destination_number(ANSWEREDCALL) =~ /^9179$/ break=on-false
  526. Dialplan: sofia/external/0833573222 parsing [default->ringback_180] continue=false
  527. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_180] destination_number(ANSWEREDCALL) =~ /^9180$/ break=on-false
  528. Dialplan: sofia/external/0833573222 parsing [default->ringback_183_uk_ring] continue=false
  529. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_183_uk_ring] destination_number(ANSWEREDCALL) =~ /^9181$/ break=on-false
  530. Dialplan: sofia/external/0833573222 parsing [default->ringback_183_music_ring] continue=false
  531. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_183_music_ring] destination_number(ANSWEREDCALL) =~ /^9182$/ break=on-false
  532. Dialplan: sofia/external/0833573222 parsing [default->ringback_post_answer_uk_ring] continue=false
  533. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(ANSWEREDCALL) =~ /^9183$/ break=on-false
  534. Dialplan: sofia/external/0833573222 parsing [default->ringback_post_answer_music] continue=false
  535. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_post_answer_music] destination_number(ANSWEREDCALL) =~ /^9184$/ break=on-false
  536. Dialplan: sofia/external/0833573222 parsing [default->ClueCon] continue=false
  537. Dialplan: sofia/external/0833573222 Regex (FAIL) [ClueCon] destination_number(ANSWEREDCALL) =~ /^9191$/ break=on-false
  538. Dialplan: sofia/external/0833573222 parsing [default->show_info] continue=false
  539. Dialplan: sofia/external/0833573222 Regex (FAIL) [show_info] destination_number(ANSWEREDCALL) =~ /^9192$/ break=on-false
  540. Dialplan: sofia/external/0833573222 parsing [default->video_record] continue=false
  541. Dialplan: sofia/external/0833573222 Regex (FAIL) [video_record] destination_number(ANSWEREDCALL) =~ /^9193$/ break=on-false
  542. Dialplan: sofia/external/0833573222 parsing [default->video_playback] continue=false
  543. Dialplan: sofia/external/0833573222 Regex (FAIL) [video_playback] destination_number(ANSWEREDCALL) =~ /^9194$/ break=on-false
  544. Dialplan: sofia/external/0833573222 parsing [default->delay_echo] continue=false
  545. Dialplan: sofia/external/0833573222 Regex (FAIL) [delay_echo] destination_number(ANSWEREDCALL) =~ /^9195$/ break=on-false
  546. Dialplan: sofia/external/0833573222 parsing [default->echo] continue=false
  547. Dialplan: sofia/external/0833573222 Regex (FAIL) [echo] destination_number(ANSWEREDCALL) =~ /^9196$/ break=on-false
  548. Dialplan: sofia/external/0833573222 parsing [default->milliwatt] continue=false
  549. Dialplan: sofia/external/0833573222 Regex (FAIL) [milliwatt] destination_number(ANSWEREDCALL) =~ /^9197$/ break=on-false
  550. Dialplan: sofia/external/0833573222 parsing [default->tone_stream] continue=false
  551. Dialplan: sofia/external/0833573222 Regex (FAIL) [tone_stream] destination_number(ANSWEREDCALL) =~ /^9198$/ break=on-false
  552. Dialplan: sofia/external/0833573222 parsing [default->zrtp_enrollement] continue=false
  553. Dialplan: sofia/external/0833573222 Regex (FAIL) [zrtp_enrollement] destination_number(ANSWEREDCALL) =~ /^9787$/ break=on-false
  554. Dialplan: sofia/external/0833573222 parsing [default->hold_music] continue=false
  555. Dialplan: sofia/external/0833573222 Regex (FAIL) [hold_music] destination_number(ANSWEREDCALL) =~ /^9664$/ break=on-false
  556. Dialplan: sofia/external/0833573222 parsing [default->laugh break] continue=false
  557. Dialplan: sofia/external/0833573222 Regex (FAIL) [laugh break] destination_number(ANSWEREDCALL) =~ /^9386$/ break=on-false
  558. Dialplan: sofia/external/0833573222 parsing [default->101] continue=false
  559. Dialplan: sofia/external/0833573222 Regex (FAIL) [101] destination_number(ANSWEREDCALL) =~ /^101$/ break=on-false
  560. Dialplan: sofia/external/0833573222 parsing [default->pizza_demo] continue=false
  561. Dialplan: sofia/external/0833573222 Regex (FAIL) [pizza_demo] destination_number(ANSWEREDCALL) =~ /^(pizza|74992)$/ break=on-false
  562. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Time] continue=false
  563. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Time] destination_number(ANSWEREDCALL) =~ /^9170$/ break=on-false
  564. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Date] continue=false
  565. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Date] destination_number(ANSWEREDCALL) =~ /^9171$/ break=on-false
  566. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Date and Time] continue=false
  567. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Date and Time] destination_number(ANSWEREDCALL) =~ /^9172$/ break=on-false
  568. Dialplan: sofia/external/0833573222 parsing [default->exe_on_ans] continue=false
  569. Dialplan: sofia/external/0833573222 Regex (FAIL) [exe_on_ans] destination_number(ANSWEREDCALL) =~ /^0870980890$/ break=on-false
  570. Dialplan: sofia/external/0833573222 parsing [default->nb_conferences_answered] continue=false
  571. Dialplan: sofia/external/0833573222 Regex (PASS) [nb_conferences_answered] destination_number(ANSWEREDCALL) =~ /^ANSWEREDCALL$/ break=on-false
  572. Dialplan: sofia/external/0833573222 Action answer()
  573. Dialplan: sofia/external/0833573222 Action set(hangup_after_bridge=true)
  574. Dialplan: sofia/external/0833573222 Action set(continue_on_fail=true)
  575. Dialplan: sofia/external/0833573222 Action callcenter(agent8003@default)
  576. 2016-11-04 08:56:55.397461 [DEBUG] switch_core_state_machine.c:286 (sofia/external/0833573222) State Change CS_ROUTING -> CS_EXECUTE
  577. 2016-11-04 08:56:55.397461 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING going to sleep
  578. 2016-11-04 08:56:55.397461 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_EXECUTE
  579. 2016-11-04 08:56:55.397461 [DEBUG] switch_core_state_machine.c:609 (sofia/external/0833573222) State EXECUTE
  580. 2016-11-04 08:56:55.397461 [DEBUG] mod_sofia.c:197 sofia/external/0833573222 SOFIA EXECUTE
  581. 2016-11-04 08:56:55.397461 [DEBUG] switch_core_state_machine.c:328 sofia/external/0833573222 Standard EXECUTE
  582. EXECUTE sofia/external/0833573222 answer()
  583. EXECUTE sofia/external/0833573222 set(hangup_after_bridge=true)
  584. 2016-11-04 08:56:55.397461 [DEBUG] mod_dptools.c:1519 SET sofia/external/0833573222 [hangup_after_bridge]=[true]
  585. EXECUTE sofia/external/0833573222 set(continue_on_fail=true)
  586. 2016-11-04 08:56:55.397461 [DEBUG] mod_dptools.c:1519 SET sofia/external/0833573222 [continue_on_fail]=[true]
  587. EXECUTE sofia/external/0833573222 callcenter(agent8003@default)
  588. 2016-11-04 08:56:55.397461 [DEBUG] mod_callcenter.c:2731 Member Outbound Call <D0820833573222> joining queue agent8003@default
  589. 2016-11-04 08:56:55.397461 [DEBUG] mod_local_stream.c:843 Opening Stream [moh/8000] 8000hz
  590. 2016-11-04 08:56:55.397461 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 60ms
  591. 2016-11-04 08:56:55.417461 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = Receiving
  592. 2016-11-04 08:56:55.417461 [DEBUG] mod_callcenter.c:1236 Updated tier: Agent 8003 in Queue agent8003@default set state = Active Inbound
  593. 2016-11-04 08:56:55.417461 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = In a queue call
  594. 2016-11-04 08:56:55.417461 [DEBUG] switch_ivr.c:623 sofia/internal/8003@172.xx.xx.3 Command Execute playback(tone_stream://%(200,0,500,600,700);loops=3)
  595. EXECUTE sofia/internal/8003@172.xx.xx.3 playback(tone_stream://%(200,0,500,600,700);loops=3)
  596. 2016-11-04 08:56:55.417461 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
  597. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_media_bug.c:828 Attaching BUG to sofia/external/0833573222
  598. 2016-11-04 08:56:55.437459 [DEBUG] mod_callcenter.c:1776 Agent 8003 answered "Outbound Call" <D0820833573222> from queue agent8003@default (Recorded)
  599. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_bridge.c:2010 (sofia/external/0833573222) State Change CS_EXECUTE -> CS_HIBERNATE
  600. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_bridge.c:2012 (sofia/internal/8003@172.xx.xx.3) State Change CS_EXECUTE -> CS_HIBERNATE
  601. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_async.c:1496 No silence detection configured; assuming start of speech
  602. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_play_say.c:1910 done playing file local_stream://moh
  603. 2016-11-04 08:56:55.437459 [DEBUG] mod_callcenter.c:2887 Member Outbound Call <D0820833573222> is answered by an agent in queue agent8003@default
  604. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_play_say.c:1910 done playing file tone_stream://%(200,0,500,600,700);loops=3
  605. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:609 (sofia/external/0833573222) State EXECUTE going to sleep
  606. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_HIBERNATE
  607. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:624 (sofia/external/0833573222) State HIBERNATE
  608. 2016-11-04 08:56:55.437459 [DEBUG] mod_sofia.c:179 sofia/external/0833573222 SOFIA HIBERNATE
  609. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_bridge.c:964 (sofia/external/0833573222) State Change CS_HIBERNATE -> CS_RESET
  610. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:624 (sofia/external/0833573222) State HIBERNATE going to sleep
  611. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_RESET
  612. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:605 (sofia/external/0833573222) State RESET
  613. 2016-11-04 08:56:55.437459 [DEBUG] mod_sofia.c:160 sofia/external/0833573222 SOFIA RESET
  614. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_bridge.c:949 sofia/external/0833573222 CUSTOM RESET
  615. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_bridge.c:956 (sofia/external/0833573222) State Change CS_RESET -> CS_SOFT_EXECUTE
  616. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:605 (sofia/external/0833573222) State RESET going to sleep
  617. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_SOFT_EXECUTE
  618. 2016-11-04 08:56:55.437459 [DEBUG] switch_core_state_machine.c:615 (sofia/external/0833573222) State SOFT_EXECUTE
  619. 2016-11-04 08:56:55.437459 [DEBUG] mod_sofia.c:634 SOFIA SOFT_EXECUTE
  620. 2016-11-04 08:56:55.437459 [DEBUG] switch_ivr_bridge.c:974 sofia/external/0833573222 CUSTOM SOFT_EXECUTE
  621. 2016-11-04 08:56:55.457460 [DEBUG] switch_ivr_play_say.c:1910 done playing file local_stream://moh
  622. 2016-11-04 08:56:55.457460 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/8003@172.xx.xx.3) State EXECUTE going to sleep
  623. 2016-11-04 08:56:55.457460 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_HIBERNATE
  624. 2016-11-04 08:56:55.457460 [DEBUG] switch_core_state_machine.c:624 (sofia/internal/8003@172.xx.xx.3) State HIBERNATE
  625. 2016-11-04 08:56:55.457460 [DEBUG] mod_sofia.c:179 sofia/internal/8003@172.xx.xx.3 SOFIA HIBERNATE
  626. 2016-11-04 08:56:55.457460 [DEBUG] switch_ivr_bridge.c:964 (sofia/internal/8003@172.xx.xx.3) State Change CS_HIBERNATE -> CS_RESET
  627. 2016-11-04 08:56:55.457460 [DEBUG] switch_core_state_machine.c:624 (sofia/internal/8003@172.xx.xx.3) State HIBERNATE going to sleep
  628. 2016-11-04 08:56:55.457460 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_RESET
  629. 2016-11-04 08:56:55.457460 [DEBUG] switch_core_state_machine.c:605 (sofia/internal/8003@172.xx.xx.3) State RESET
  630. 2016-11-04 08:56:55.457460 [DEBUG] mod_sofia.c:160 sofia/internal/8003@172.xx.xx.3 SOFIA RESET
  631. 2016-11-04 08:56:55.457460 [DEBUG] switch_ivr_bridge.c:949 sofia/internal/8003@172.xx.xx.3 CUSTOM RESET
  632. 2016-11-04 08:56:55.457460 [DEBUG] switch_core_state_machine.c:188 sofia/internal/8003@172.xx.xx.3 Standard RESET
  633. 2016-11-04 08:56:55.457460 [DEBUG] switch_core_state_machine.c:605 (sofia/internal/8003@172.xx.xx.3) State RESET going to sleep
  634. 2016-11-04 08:56:55.477469 [DEBUG] switch_ivr_bridge.c:1006 (sofia/internal/8003@172.xx.xx.3) State Change CS_RESET -> CS_SOFT_EXECUTE
  635. 2016-11-04 08:56:55.477469 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_SOFT_EXECUTE
  636. 2016-11-04 08:56:55.477469 [DEBUG] switch_core_state_machine.c:615 (sofia/internal/8003@172.xx.xx.3) State SOFT_EXECUTE
  637. 2016-11-04 08:56:55.477469 [DEBUG] mod_sofia.c:634 SOFIA SOFT_EXECUTE
  638. 2016-11-04 08:56:55.477469 [DEBUG] switch_ivr_bridge.c:974 sofia/internal/8003@172.xx.xx.3 CUSTOM SOFT_EXECUTE
  639. 2016-11-04 08:56:55.477469 [DEBUG] switch_core_state_machine.c:400 sofia/internal/8003@172.xx.xx.3 Standard SOFT_EXECUTE
  640. 2016-11-04 08:56:55.477469 [DEBUG] switch_core_state_machine.c:615 (sofia/internal/8003@172.xx.xx.3) State SOFT_EXECUTE going to sleep
  641. 2016-11-04 08:56:55.497474 [DEBUG] switch_ivr_bridge.c:1489 (sofia/internal/8003@172.xx.xx.3) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA
  642. 2016-11-04 08:56:55.497474 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_CONSUME_MEDIA
  643. 2016-11-04 08:56:55.497474 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/8003@172.xx.xx.3) State CONSUME_MEDIA
  644. 2016-11-04 08:56:55.497474 [DEBUG] switch_ivr_bridge.c:913 sofia/internal/8003@172.xx.xx.3 CUSTOM HOLD
  645. 2016-11-04 08:56:55.497474 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/8003@172.xx.xx.3) State CONSUME_MEDIA going to sleep
  646. 2016-11-04 08:56:55.497474 [DEBUG] switch_ivr_bridge.c:1594 (sofia/internal/8003@172.xx.xx.3) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  647. 2016-11-04 08:56:55.497474 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_EXCHANGE_MEDIA
  648. 2016-11-04 08:56:55.497474 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/8003@172.xx.xx.3) State EXCHANGE_MEDIA
  649. 2016-11-04 08:56:55.497474 [DEBUG] mod_sofia.c:628 SOFIA EXCHANGE_MEDIA
  650. 2016-11-04 08:56:55.517460 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc204b8908 0x7efc204b8908
  651. 2016-11-04 08:56:55.517460 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc204b8908 0x7efc204b8908
  652. 2016-11-04 08:56:55.517460 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc204b8908 0x7efc204b8910
  653. 2016-11-04 08:56:55.517460 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc204b8908 0x7efc204b8910
  654. 2016-11-04 08:56:55.517460 [DEBUG] switch_core_io.c:1448 Engaging Write Buffer at 960 bytes to accommodate 320->960
  655. 2016-11-04 08:56:55.537475 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc000475b8 0x7efc000475b8
  656. 2016-11-04 08:56:55.537475 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc000475b8 0x7efc000475b8
  657. 2016-11-04 08:56:55.557467 [DEBUG] switch_rtp.c:6670 Correct audio ip/port confirmed.
  658. 2016-11-04 08:56:55.557467 [DEBUG] switch_core_io.c:448 Setting BUG Codec G729:18
  659. 2016-11-04 08:56:55.557467 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc182a28d0 0x7efc182a28d8
  660. 2016-11-04 08:56:55.557467 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc182a28d0 0x7efc182a28d8
  661. 2016-11-04 08:56:55.557467 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc000475d8 0x7efc000475e0
  662. 2016-11-04 08:56:55.557467 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc000475d8 0x7efc000475e0
  663. 2016-11-04 08:56:55.557467 [DEBUG] switch_core_io.c:1448 Engaging Write Buffer at 320 bytes to accommodate 960->320
  664. 2016-11-04 08:56:57.037500 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc000475b8 0x7efc000475b8
  665. 2016-11-04 08:56:57.037500 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc000475b8 0x7efc000475b8
  666. 2016-11-04 08:56:57.037500 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc000475f8 0x7efc000475f8
  667. 2016-11-04 08:56:57.037500 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc000475f8 0x7efc000475f8
  668. 2016-11-04 08:56:57.057477 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc000475d8 0x7efc000475e0
  669. 2016-11-04 08:56:57.057477 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc000475d8 0x7efc000475e0
  670. 2016-11-04 08:56:57.057477 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047618 0x7efc00047620
  671. 2016-11-04 08:56:57.057477 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047618 0x7efc00047620
  672. freeswitch@GCNDialer>
  673. freeswitch@GCNDialer>
  674. freeswitch@GCNDialer>
  675. freeswitch@GCNDialer>
  676. freeswitch@GCNDialer>
  677. 2016-11-04 08:56:59.337476 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc000475f8 0x7efc000475f8
  678. 2016-11-04 08:56:59.337476 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc000475f8 0x7efc000475f8
  679. 2016-11-04 08:56:59.377595 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047638 0x7efc00047638
  680. 2016-11-04 08:56:59.377595 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047638 0x7efc00047638
  681. 2016-11-04 08:56:59.397473 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047618 0x7efc00047620
  682. 2016-11-04 08:56:59.397473 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047618 0x7efc00047620
  683. 2016-11-04 08:56:59.397473 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047658 0x7efc00047660
  684. 2016-11-04 08:56:59.397473 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047658 0x7efc00047660
  685. 2016-11-04 08:57:00.797626 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047638 0x7efc00047638
  686. 2016-11-04 08:57:00.897491 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047638 0x7efc00047638
  687. 2016-11-04 08:57:00.897491 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047658 0x7efc00047660
  688. 2016-11-04 08:57:00.897491 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047658 0x7efc00047660
  689. 2016-11-04 08:57:00.897491 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047678 0x7efc00047678
  690. 2016-11-04 08:57:00.897491 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047678 0x7efc00047678
  691. 2016-11-04 08:57:00.897491 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047698 0x7efc000476a0
  692. 2016-11-04 08:57:00.897491 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047698 0x7efc000476a0
  693. 2016-11-04 08:57:02.777481 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047678 0x7efc00047678
  694. 2016-11-04 08:57:02.777481 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047678 0x7efc00047678
  695. 2016-11-04 08:57:02.817478 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047698 0x7efc000476a0
  696. 2016-11-04 08:57:02.817478 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047698 0x7efc000476a0
  697. 2016-11-04 08:57:02.817478 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc000476b8 0x7efc000476b8
  698. 2016-11-04 08:57:02.817478 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc000476b8 0x7efc000476b8
  699. 2016-11-04 08:57:02.817478 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc000476d8 0x7efc000476e0
  700. 2016-11-04 08:57:02.817478 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc000476d8 0x7efc000476e0
  701. 2016-11-04 08:57:04.797553 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc000476b8 0x7efc000476b8
  702. 2016-11-04 08:57:04.797553 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc000476b8 0x7efc000476b8
  703. 2016-11-04 08:57:04.797553 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc000476f8 0x7efc000476f8
  704. 2016-11-04 08:57:04.797553 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc000476f8 0x7efc000476f8
  705. 2016-11-04 08:57:04.857471 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc000476d8 0x7efc000476e0
  706. 2016-11-04 08:57:04.857471 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc000476d8 0x7efc000476e0
  707. 2016-11-04 08:57:04.857471 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047718 0x7efc00047720
  708. 2016-11-04 08:57:04.857471 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047718 0x7efc00047720
  709. 2016-11-04 08:57:05.237469 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc000476f8 0x7efc000476f8
  710. 2016-11-04 08:57:05.237469 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc000476f8 0x7efc000476f8
  711. 2016-11-04 08:57:05.277563 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047718 0x7efc00047720
  712. 2016-11-04 08:57:05.277563 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047718 0x7efc00047720
  713. 2016-11-04 08:57:05.277563 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047750 0x7efc00047750
  714. 2016-11-04 08:57:05.277563 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047750 0x7efc00047750
  715. 2016-11-04 08:57:05.277563 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047770 0x7efc00047778
  716. 2016-11-04 08:57:05.277563 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047770 0x7efc00047778
  717. 2016-11-04 08:57:05.817536 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047750 0x7efc00047750
  718. 2016-11-04 08:57:05.817536 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047750 0x7efc00047750
  719. 2016-11-04 08:57:05.817536 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047790 0x7efc00047790
  720. 2016-11-04 08:57:05.817536 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047790 0x7efc00047790
  721. 2016-11-04 08:57:05.877472 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047770 0x7efc00047778
  722. 2016-11-04 08:57:05.877472 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047770 0x7efc00047778
  723. 2016-11-04 08:57:05.877472 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc000477b0 0x7efc000477b8
  724. 2016-11-04 08:57:05.877472 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc000477b0 0x7efc000477b8
  725. 2016-11-04 08:57:07.157470 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047790 0x7efc00047790
  726. 2016-11-04 08:57:07.157470 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047790 0x7efc00047790
  727. 2016-11-04 08:57:07.197473 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc000477b0 0x7efc000477b8
  728. 2016-11-04 08:57:07.197473 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc000477b0 0x7efc000477b8
  729. 2016-11-04 08:57:07.197473 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc000477f0 0x7efc000477f8
  730. 2016-11-04 08:57:07.197473 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc000477f0 0x7efc000477f8
  731. 2016-11-04 08:57:07.197473 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc000477d0 0x7efc000477d0
  732. 2016-11-04 08:57:07.197473 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc000477d0 0x7efc000477d0
  733. 2016-11-04 08:57:07.425236 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc000477d0 0x7efc000477d0
  734. 2016-11-04 08:57:07.425236 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc000477d0 0x7efc000477d0
  735. 2016-11-04 08:57:07.425236 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047810 0x7efc00047810
  736. 2016-11-04 08:57:07.425236 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047810 0x7efc00047810
  737. 2016-11-04 08:57:07.497492 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc000477f0 0x7efc000477f8
  738. 2016-11-04 08:57:07.497492 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc000477f0 0x7efc000477f8
  739. 2016-11-04 08:57:07.497492 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047830 0x7efc00047838
  740. 2016-11-04 08:57:07.497492 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047830 0x7efc00047838
  741. 2016-11-04 08:57:09.357527 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047810 0x7efc00047810
  742. 2016-11-04 08:57:09.357527 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047810 0x7efc00047810
  743. 2016-11-04 08:57:09.357527 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047850 0x7efc00047850
  744. 2016-11-04 08:57:09.357527 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047850 0x7efc00047850
  745. 2016-11-04 08:57:09.417468 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047830 0x7efc00047838
  746. 2016-11-04 08:57:09.417468 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047830 0x7efc00047838
  747. 2016-11-04 08:57:09.417468 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047870 0x7efc00047878
  748. 2016-11-04 08:57:09.417468 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047870 0x7efc00047878
  749. 2016-11-04 08:57:10.117530 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047850 0x7efc00047850
  750. 2016-11-04 08:57:10.117530 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047850 0x7efc00047850
  751. 2016-11-04 08:57:10.137474 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047870 0x7efc00047878
  752. 2016-11-04 08:57:10.137474 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047870 0x7efc00047878
  753. 2016-11-04 08:57:10.137474 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc000478b0 0x7efc000478b8
  754. 2016-11-04 08:57:10.137474 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc000478b0 0x7efc000478b8
  755. 2016-11-04 08:57:10.137474 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047890 0x7efc00047890
  756. 2016-11-04 08:57:10.137474 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047890 0x7efc00047890
  757. 2016-11-04 08:57:11.017473 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047890 0x7efc00047890
  758. 2016-11-04 08:57:11.017473 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047890 0x7efc00047890
  759. 2016-11-04 08:57:11.037469 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc000478b0 0x7efc000478b8
  760. 2016-11-04 08:57:11.037469 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc000478b0 0x7efc000478b8
  761. 2016-11-04 08:57:11.037469 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc000478f0 0x7efc000478f8
  762. 2016-11-04 08:57:11.037469 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc000478f0 0x7efc000478f8
  763. 2016-11-04 08:57:11.037469 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc000478d0 0x7efc000478d0
  764. 2016-11-04 08:57:11.037469 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc000478d0 0x7efc000478d0
  765. 2016-11-04 08:57:12.517479 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc000478d0 0x7efc000478d0
  766. 2016-11-04 08:57:12.517479 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc000478d0 0x7efc000478d0
  767. 2016-11-04 08:57:12.537533 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc000478f0 0x7efc000478f8
  768. 2016-11-04 08:57:12.537533 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc000478f0 0x7efc000478f8
  769. 2016-11-04 08:57:12.537533 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047910 0x7efc00047910
  770. 2016-11-04 08:57:12.537533 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047910 0x7efc00047910
  771. 2016-11-04 08:57:12.537533 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047930 0x7efc00047938
  772. 2016-11-04 08:57:12.537533 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047930 0x7efc00047938
  773. 2016-11-04 08:57:17.777467 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047910 0x7efc00047910
  774. 2016-11-04 08:57:17.777467 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047910 0x7efc00047910
  775. 2016-11-04 08:57:17.817484 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047930 0x7efc00047938
  776. 2016-11-04 08:57:17.817484 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047930 0x7efc00047938
  777. 2016-11-04 08:57:17.817484 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047988 0x7efc00047990
  778. 2016-11-04 08:57:17.817484 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047988 0x7efc00047990
  779. 2016-11-04 08:57:17.817484 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047968 0x7efc00047968
  780. 2016-11-04 08:57:17.817484 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047968 0x7efc00047968
  781. 2016-11-04 08:57:21.417472 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047968 0x7efc00047968
  782. 2016-11-04 08:57:21.417472 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047968 0x7efc00047968
  783. 2016-11-04 08:57:21.437467 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc000479a8 0x7efc000479a8
  784. 2016-11-04 08:57:21.437467 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc000479a8 0x7efc000479a8
  785. 2016-11-04 08:57:21.477481 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047988 0x7efc00047990
  786. 2016-11-04 08:57:21.477481 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047988 0x7efc00047990
  787. 2016-11-04 08:57:21.477481 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc000479c8 0x7efc000479d0
  788. 2016-11-04 08:57:21.477481 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc000479c8 0x7efc000479d0
  789. 2016-11-04 08:57:22.517536 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc000479a8 0x7efc000479a8
  790. 2016-11-04 08:57:22.517536 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc000479a8 0x7efc000479a8
  791. 2016-11-04 08:57:22.517536 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc000479e8 0x7efc000479e8
  792. 2016-11-04 08:57:22.517536 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc000479e8 0x7efc000479e8
  793. 2016-11-04 08:57:22.557568 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc000479c8 0x7efc000479d0
  794. 2016-11-04 08:57:22.557568 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc000479c8 0x7efc000479d0
  795. 2016-11-04 08:57:22.557568 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047a08 0x7efc00047a10
  796. 2016-11-04 08:57:22.557568 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047a08 0x7efc00047a10
  797. 2016-11-04 08:57:26.257490 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc000479e8 0x7efc000479e8
  798. 2016-11-04 08:57:26.257490 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc000479e8 0x7efc000479e8
  799. 2016-11-04 08:57:26.277481 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047a08 0x7efc00047a10
  800. 2016-11-04 08:57:26.277481 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047a08 0x7efc00047a10
  801. 2016-11-04 08:57:26.277481 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047a48 0x7efc00047a50
  802. 2016-11-04 08:57:26.277481 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047a48 0x7efc00047a50
  803. 2016-11-04 08:57:26.297487 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047a28 0x7efc00047a28
  804. 2016-11-04 08:57:26.297487 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047a28 0x7efc00047a28
  805. 2016-11-04 08:57:26.717561 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047a28 0x7efc00047a28
  806. 2016-11-04 08:57:26.717561 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047a28 0x7efc00047a28
  807. 2016-11-04 08:57:26.717561 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047a68 0x7efc00047a68
  808. 2016-11-04 08:57:26.717561 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047a68 0x7efc00047a68
  809. 2016-11-04 08:57:26.757471 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047a48 0x7efc00047a50
  810. 2016-11-04 08:57:26.757471 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047a48 0x7efc00047a50
  811. 2016-11-04 08:57:26.757471 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc00047a88 0x7efc00047a90
  812. 2016-11-04 08:57:26.757471 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc00047a88 0x7efc00047a90
  813. 2016-11-04 08:57:29.397566 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047a68 0x7efc00047a68
  814. 2016-11-04 08:57:29.397566 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047a68 0x7efc00047a68
  815. 2016-11-04 08:57:29.417478 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc00047aa8 0x7efc00047aa8
  816. 2016-11-04 08:57:29.417478 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc00047aa8 0x7efc00047aa8
  817. 2016-11-04 08:57:29.457494 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc00047a88 0x7efc00047a90
  818. 2016-11-04 08:57:29.457494 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc00047a88 0x7efc00047a90
  819. 2016-11-04 08:57:29.457494 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc14056a08 0x7efc14056a10
  820. 2016-11-04 08:57:29.457494 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc14056a08 0x7efc14056a10
  821. 2016-11-04 08:57:29.677470 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc00047aa8 0x7efc00047aa8
  822. 2016-11-04 08:57:29.677470 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc00047aa8 0x7efc00047aa8
  823. 2016-11-04 08:57:29.697474 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc14056a08 0x7efc14056a10
  824. 2016-11-04 08:57:29.697474 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc14056a08 0x7efc14056a10
  825. 2016-11-04 08:57:29.697474 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc14056a48 0x7efc14056a50
  826. 2016-11-04 08:57:29.697474 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc14056a48 0x7efc14056a50
  827. 2016-11-04 08:57:29.717470 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc14056a28 0x7efc14056a28
  828. 2016-11-04 08:57:29.717470 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc14056a28 0x7efc14056a28
  829. 2016-11-04 08:57:31.497477 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc14056a28 0x7efc14056a28
  830. 2016-11-04 08:57:31.497477 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc14056a28 0x7efc14056a28
  831. 2016-11-04 08:57:31.517469 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc14056a68 0x7efc14056a68
  832. 2016-11-04 08:57:31.517469 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc14056a68 0x7efc14056a68
  833. 2016-11-04 08:57:31.557565 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc14056a48 0x7efc14056a50
  834. 2016-11-04 08:57:31.557565 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc14056a48 0x7efc14056a50
  835. 2016-11-04 08:57:31.557565 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc14056a88 0x7efc14056a90
  836. 2016-11-04 08:57:31.557565 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc14056a88 0x7efc14056a90
  837. 2016-11-04 08:57:32.837484 [NOTICE] sofia.c:988 Hangup sofia/internal/8003@172.xx.xx.3 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  838. 2016-11-04 08:57:32.837484 [DEBUG] switch_ivr_bridge.c:780 BRIDGE THREAD DONE [sofia/internal/8003@172.xx.xx.3]
  839. 2016-11-04 08:57:32.837484 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/8003@172.xx.xx.3) State EXCHANGE_MEDIA going to sleep
  840. 2016-11-04 08:57:32.837484 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_HANGUP
  841. 2016-11-04 08:57:32.837484 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/8003@172.xx.xx.3) Callstate Change ACTIVE -> HANGUP
  842. 2016-11-04 08:57:32.837484 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/8003@172.xx.xx.3) State HANGUP
  843. 2016-11-04 08:57:32.837484 [DEBUG] mod_sofia.c:437 Channel sofia/internal/8003@172.xx.xx.3 hanging up, cause: NORMAL_CLEARING
  844. 2016-11-04 08:57:32.857474 [DEBUG] switch_core_state_machine.c:60 sofia/internal/8003@172.xx.xx.3 Standard HANGUP, cause: NORMAL_CLEARING
  845. 2016-11-04 08:57:32.857474 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/8003@172.xx.xx.3) State HANGUP going to sleep
  846. 2016-11-04 08:57:32.857474 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/8003@172.xx.xx.3) State Change CS_HANGUP -> CS_REPORTING
  847. 2016-11-04 08:57:32.857474 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_REPORTING
  848. 2016-11-04 08:57:32.857474 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/8003@172.xx.xx.3) State REPORTING
  849. 2016-11-04 08:57:32.857474 [DEBUG] mod_odbc_cdr.c:309 sql INSERT INTO cdr_table_both (start_stamp, CallId, TEST_id, end_stamp) VALUES ('2016-11-04 08:56:37', '41267c3a-f0ed-4ab5-a836-0fea886d3863', '8003@172.xx.xx.3', '2016-11-04 08:57:32')
  850. 2016-11-04 08:57:32.857474 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = Waiting
  851. 2016-11-04 08:57:32.879809 [DEBUG] switch_ivr_bridge.c:701 sofia/internal/8003@172.xx.xx.3 ending bridge by request from write function
  852. 2016-11-04 08:57:32.879809 [DEBUG] switch_ivr_bridge.c:780 BRIDGE THREAD DONE [sofia/external/0833573222]
  853. 2016-11-04 08:57:32.879809 [DEBUG] switch_ivr_bridge.c:1692 sofia/internal/8003@172.xx.xx.3 skip receive message [UNBRIDGE] (channel is hungup already)
  854. 2016-11-04 08:57:32.879809 [NOTICE] switch_ivr_bridge.c:1744 Hangup sofia/external/0833573222 [CS_SOFT_EXECUTE] [NORMAL_CLEARING]
  855. 2016-11-04 08:57:32.879809 [DEBUG] switch_core_state_machine.c:615 (sofia/external/0833573222) State SOFT_EXECUTE going to sleep
  856. 2016-11-04 08:57:32.879809 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_HANGUP
  857. 2016-11-04 08:57:32.879809 [DEBUG] switch_ivr_async.c:1312 Stop recording file /usr/local/freeswitch/recordings/2016-11-04-08-56-55.ANSWEREDCALL.D0820833573222.f53f74c1-62f0-4608-90e3-ecf01e2e4020.wav
  858. 2016-11-04 08:57:32.897471 [DEBUG] switch_ivr_async.c:1376 Channel is hung up
  859. 2016-11-04 08:57:32.897471 [DEBUG] switch_core_media_bug.c:1120 Removing BUG from sofia/external/0833573222
  860. 2016-11-04 08:57:32.897471 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc182a28d0 0x7efc182a28d8
  861. 2016-11-04 08:57:32.897471 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc182a28d0 0x7efc182a28d8
  862. 2016-11-04 08:57:32.897471 [DEBUG] switch_core_state_machine.c:809 (sofia/external/0833573222) Callstate Change ACTIVE -> HANGUP
  863. 2016-11-04 08:57:32.897471 [DEBUG] switch_core_state_machine.c:811 (sofia/external/0833573222) State HANGUP
  864. 2016-11-04 08:57:32.897471 [DEBUG] mod_sofia.c:431 sofia/external/0833573222 Overriding SIP cause 480 with 200 from the other leg
  865. 2016-11-04 08:57:32.897471 [DEBUG] mod_sofia.c:437 Channel sofia/external/0833573222 hanging up, cause: NORMAL_CLEARING
  866. 2016-11-04 08:57:32.897471 [DEBUG] mod_sofia.c:490 Sending BYE to sofia/external/0833573222
  867. 2016-11-04 08:57:32.897471 [DEBUG] switch_core_state_machine.c:60 sofia/external/0833573222 Standard HANGUP, cause: NORMAL_CLEARING
  868. 2016-11-04 08:57:32.897471 [DEBUG] switch_core_state_machine.c:811 (sofia/external/0833573222) State HANGUP going to sleep
  869. 2016-11-04 08:57:32.897471 [DEBUG] switch_core_state_machine.c:578 (sofia/external/0833573222) State Change CS_HANGUP -> CS_REPORTING
  870. 2016-11-04 08:57:32.897471 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_REPORTING
  871. 2016-11-04 08:57:32.897471 [DEBUG] switch_core_state_machine.c:897 (sofia/external/0833573222) State REPORTING
  872. 2016-11-04 08:57:32.897471 [DEBUG] mod_odbc_cdr.c:309 sql INSERT INTO cdr_table_both (start_stamp, CallId, TEST_id, end_stamp) VALUES ('2016-11-04 08:56:47', 'f53f74c1-62f0-4608-90e3-ecf01e2e4020', 'Enav8Pre@172.xx.xx.61', '2016-11-04 08:57:32')
  873. 2016-11-04 08:57:32.957480 [DEBUG] mod_odbc_cdr.c:309 sql INSERT INTO cdr_table_b_leg (start_stamp, TelNumberFull, TelNumber, CallId, sip_endpoint_disposition, orig_id, CallerID, CODEC, sip_current_application, directGateway, IPInternal, IP, term_id, ClientId, end_stamp) VALUES ('2016-11-04 08:56:37', '4099', '4099', '41267c3a-f0ed-4ab5-a836-0fea886d3863', 'ANSWER', '41267c3a-f0ed-4ab5-a836-0fea886d3863', '8003', 'G729', 'playback', '172.31.35.60', '172.xx.xx.3', '172.xx.xx.3', '88EA9227-A19111E6-BC79C7B1-C40D3FF0@172.xx.xx.3', '41267c3a-f0ed-4ab5-a836-0fea886d3863', '2016-11-04 08:57:32')
  874. 2016-11-04 08:57:32.977554 [DEBUG] mod_odbc_cdr.c:309 sql INSERT INTO cdr_table_a_leg (start_stamp, TelNumberFull, CallId, sip_endpoint_disposition, orig_id, CallerID, CODEC, sip_current_application, IP, term_id, ClientId, end_stamp) VALUES ('2016-11-04 08:56:47', '0833573222', 'f53f74c1-62f0-4608-90e3-ecf01e2e4020', 'ANSWER', 'f53f74c1-62f0-4608-90e3-ecf01e2e4020', 'Enav8Pre', 'G729', 'callcenter', '172.xx.xx.61', 'b1c4c109-1cfe-1235-3b80-0050568b0ff8', 'f53f74c1-62f0-4608-90e3-ecf01e2e4020', '2016-11-04 08:57:32')
  875. 2016-11-04 08:57:33.037526 [DEBUG] switch_core_state_machine.c:174 sofia/internal/8003@172.xx.xx.3 Standard REPORTING, cause: NORMAL_CLEARING
  876. 2016-11-04 08:57:33.037526 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/8003@172.xx.xx.3) State REPORTING going to sleep
  877. 2016-11-04 08:57:33.037526 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/8003@172.xx.xx.3) State Change CS_REPORTING -> CS_DESTROY
  878. 2016-11-04 08:57:33.037526 [DEBUG] switch_core_session.c:1646 Session 98 (sofia/internal/8003@172.xx.xx.3) Locked, Waiting on external entities
  879. 2016-11-04 08:57:33.037526 [NOTICE] switch_core_session.c:1664 Session 98 (sofia/internal/8003@172.xx.xx.3) Ended
  880. 2016-11-04 08:57:33.037526 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/8003@172.xx.xx.3 [CS_DESTROY]
  881. 2016-11-04 08:57:33.037526 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_DESTROY
  882. 2016-11-04 08:57:33.037526 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/8003@172.xx.xx.3) State DESTROY
  883. 2016-11-04 08:57:33.037526 [DEBUG] mod_sofia.c:342 sofia/internal/8003@172.xx.xx.3 SOFIA DESTROY
  884. 2016-11-04 08:57:33.037526 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc204b8908 0x7efc204b8908
  885. 2016-11-04 08:57:33.037526 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc204b8908 0x7efc204b8908
  886. 2016-11-04 08:57:33.037526 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc204b8908 0x7efc204b8910
  887. 2016-11-04 08:57:33.060853 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc204b8908 0x7efc204b8910
  888. 2016-11-04 08:57:33.060853 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc204b8988 0x7efc204b8988
  889. 2016-11-04 08:57:33.060853 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc204b8988 0x7efc204b8988
  890. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:181 sofia/internal/8003@172.xx.xx.3 Standard DESTROY
  891. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/8003@172.xx.xx.3) State DESTROY going to sleep
  892. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:174 sofia/external/0833573222 Standard REPORTING, cause: NORMAL_CLEARING
  893. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:897 (sofia/external/0833573222) State REPORTING going to sleep
  894. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:569 (sofia/external/0833573222) State Change CS_REPORTING -> CS_DESTROY
  895. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_session.c:1646 Session 99 (sofia/external/0833573222) Locked, Waiting on external entities
  896. 2016-11-04 08:57:33.060853 [NOTICE] switch_core_session.c:1664 Session 99 (sofia/external/0833573222) Ended
  897. 2016-11-04 08:57:33.060853 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/0833573222 [CS_DESTROY]
  898. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:700 (sofia/external/0833573222) Running State Change CS_DESTROY
  899. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:710 (sofia/external/0833573222) State DESTROY
  900. 2016-11-04 08:57:33.060853 [DEBUG] mod_sofia.c:342 sofia/external/0833573222 SOFIA DESTROY
  901. 2016-11-04 08:57:33.060853 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc14056a88 0x7efc14056a90
  902. 2016-11-04 08:57:33.060853 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc14056a88 0x7efc14056a90
  903. 2016-11-04 08:57:33.060853 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc14056a68 0x7efc14056a68
  904. 2016-11-04 08:57:33.060853 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc14056a68 0x7efc14056a68
  905. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:181 sofia/external/0833573222 Standard DESTROY
  906. 2016-11-04 08:57:33.060853 [DEBUG] switch_core_state_machine.c:710 (sofia/external/0833573222) State DESTROY going to sleep

Reply to "FS Cli Log"

captcha