From Sam, 7 Years ago, written in FreeSWITCH.
- view diff
Embed
  1.  
  2. recv 1138 bytes from udp/[172.xx.xx.3]:65516 at 09:01:06.315556:
  3.    ------------------------------------------------------------------------
  4.    INVITE sip:4099@172.31.35.60:5060 SIP/2.0
  5.    Via: SIP/2.0/UDP 172.xx.xx.3:5060;branch=z9hG4bK109A23191E
  6.    From: <sip:8003@172.xx.xx.3>;tag=2E56AF64-2516
  7.    To: <sip:4099@172.31.35.60>
  8.    Date: Fri, 04 Nov 2016 06:53:00 GMT
  9.    Call-ID: 2906F641-A19211E6-BC7FC7B1-C40D3FF0@172.xx.xx.3
  10.    Supported: 100rel,timer,resource-priority,replaces,sdp-anat
  11.    Min-SE:  1800
  12.    Cisco-Guid: 2077484416-0000065536-0000007231-1678944448
  13.    User-Agent: Cisco-SIPGateway/IOS-12.x
  14.   Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
  15.    CSeq: 101 INVITE
  16.    Timestamp: 1478242380
  17.    Contact: <sip:8003@172.xx.xx.3:5060>
  18.    Expires: 180
  19.    Allow-Events: telephone-event
  20.    Max-Forwards: 68
  21.    Session-Expires:  1800
  22.    Content-Type: application/sdp
  23.    Content-Disposition: session;handling=required
  24.    Content-Length: 324
  25.    
  26.    v=0
  27.    o=CiscoSystemsSIP-GW-UserAgent 6493 8918 IN IP4 172.xx.xx.3
  28.    s=SIP Call
  29.    c=IN IP4 172.xx.xx.3
  30.    t=0 0
  31.    m=audio 18436 RTP/AVP 18 101 121
  32.    c=IN IP4 172.xx.xx.3
  33.    a=rtpmap:18 G729/8000
  34.    a=fmtp:18 annexb=no
  35.    a=rtpmap:101 telephone-event/8000
  36.    a=fmtp:101 0-16
  37.    a=rtpmap:121 frf-dialed-digit/8000
  38.    a=fmtp:121 0-15
  39.    a=ptime:20
  40.    ------------------------------------------------------------------------
  41. send 364 bytes to udp/[172.xx.xx.3]:5060 at 09:01:06.315896:
  42.    ------------------------------------------------------------------------
  43.    SIP/2.0 100 Trying
  44.    Via: SIP/2.0/UDP 172.xx.xx.3:5060;branch=z9hG4bK109A23191E
  45.    From: <sip:8003@172.xx.xx.3>;tag=2E56AF64-2516
  46.    To: <sip:4099@172.31.35.60>
  47.    Call-ID: 2906F641-A19211E6-BC7FC7B1-C40D3FF0@172.xx.xx.3
  48.    CSeq: 101 INVITE
  49.    Timestamp: 1478242380 0.000131
  50.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  51.    Content-Length: 0
  52.    
  53.    ------------------------------------------------------------------------
  54. 2016-11-04 09:01:06.297602 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/8003@172.xx.xx.3 [ffefcfb3-be10-472d-8469-40692b7457da]
  55. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_NEW
  56. 2016-11-04 09:01:06.297602 [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
  57. 2016-11-04 09:01:06.297602 [DEBUG] sofia.c:9486 IP 172.xx.xx.3 Approved by acl "domains[]". Access Granted.
  58. 2016-11-04 09:01:06.297602 [DEBUG] sofia.c:10676 Setting NAT mode based on via port
  59. 2016-11-04 09:01:06.297602 [DEBUG] sofia.c:6858 Channel sofia/internal/8003@172.xx.xx.3 entering state [received][100]
  60. 2016-11-04 09:01:06.297602 [DEBUG] sofia.c:6868 Remote SDP:
  61. v=0
  62. o=CiscoSystemsSIP-GW-UserAgent 6493 8918 IN IP4 172.xx.xx.3
  63. s=SIP Call
  64. c=IN IP4 172.xx.xx.3
  65. t=0 0
  66. m=audio 18436 RTP/AVP 18 101 121
  67. c=IN IP4 172.xx.xx.3
  68. a=rtpmap:18 G729/8000
  69. a=fmtp:18 annexb=no
  70. a=rtpmap:101 telephone-event/8000
  71. a=fmtp:101 0-16
  72. a=rtpmap:121 frf-dialed-digit/8000
  73. a=fmtp:121 0-15
  74. a=ptime:20
  75.  
  76. 2016-11-04 09:01:06.297602 [DEBUG] sofia.c:7227 (sofia/internal/8003@172.xx.xx.3) State Change CS_NEW -> CS_INIT
  77. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:562 (sofia/internal/8003@172.xx.xx.3) State NEW
  78. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_INIT
  79. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/8003@172.xx.xx.3) State INIT
  80. 2016-11-04 09:01:06.297602 [DEBUG] mod_sofia.c:89 sofia/internal/8003@172.xx.xx.3 SOFIA INIT
  81. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:40 sofia/internal/8003@172.xx.xx.3 Standard INIT
  82. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/8003@172.xx.xx.3) State Change CS_INIT -> CS_ROUTING
  83. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/8003@172.xx.xx.3) State INIT going to sleep
  84. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_ROUTING
  85. 2016-11-04 09:01:06.297602 [DEBUG] switch_channel.c:2249 (sofia/internal/8003@172.xx.xx.3) Callstate Change DOWN -> RINGING
  86. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/8003@172.xx.xx.3) State ROUTING
  87. 2016-11-04 09:01:06.297602 [DEBUG] mod_sofia.c:142 sofia/internal/8003@172.xx.xx.3 SOFIA ROUTING
  88. 2016-11-04 09:01:06.297602 [DEBUG] switch_core_state_machine.c:236 sofia/internal/8003@172.xx.xx.3 Standard ROUTING
  89. 2016-11-04 09:01:06.297602 [INFO] mod_dialplan_xml.c:637 Processing 8003 <8003>->4099 in context public
  90. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->unloop] continue=false
  91. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  92. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  93. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->group_dial_huntgroup] continue=false
  94. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [group_dial_huntgroup] destination_number(4099) =~ /^0870980891$/ break=on-false
  95. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->group_dial_did_to_local] continue=false
  96. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [group_dial_did_to_local] destination_number(4099) =~ /^0870980892$/ break=on-false
  97. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->outside_call] continue=true
  98. Dialplan: sofia/internal/8003@172.xx.xx.3 Absolute Condition [outside_call]
  99. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(outside_call=true)
  100. Dialplan: sofia/internal/8003@172.xx.xx.3 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  101. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->call_debug] continue=true
  102. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  103. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_extensions] continue=false
  104. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_extensions] destination_number(4099) =~ /^(10[01][0-9])$/ break=on-false
  105. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_did] continue=false
  106. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_did] destination_number(4099) =~ /^(5551212)$/ break=on-false
  107. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_did] continue=false
  108. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_did] destination_number(4099) =~ /^(0870980892)$/ break=on-false
  109. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->nb_conferences] continue=false
  110. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [nb_conferences] destination_number(4099) =~ /^0870980890$/ break=on-false
  111. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->nb_conferences] continue=false
  112. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [nb_conferences] destination_number(4099) =~ /^0870980893$/ break=on-false
  113. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->conf11001] continue=false
  114. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [conf11001] destination_number(4099) =~ /^11001$/ break=on-false
  115. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->UNKNOWN] continue=false
  116. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (PASS) [UNKNOWN] destination_number(4099) =~ /^(4099)$/ break=on-false
  117. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(transfer_after_bridge=4099)
  118. Dialplan: sofia/internal/8003@172.xx.xx.3 Action sleep(300)
  119. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set uuid ${caller_id_number} '${uuid}')})
  120. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set type ${caller_id_number} 'uuid-standby')})
  121. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set status ${caller_id_number} 'Available')})
  122. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set state ${caller_id_number} 'idle')})
  123. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(cc_warning_tone=tone_stream://%(200,0,500,600,700);loops=3)
  124. Dialplan: sofia/internal/8003@172.xx.xx.3 Action answer()
  125. Dialplan: sofia/internal/8003@172.xx.xx.3 Action playback(local_stream://moh)
  126. 2016-11-04 09:01:06.317476 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/8003@172.xx.xx.3) State Change CS_ROUTING -> CS_EXECUTE
  127. 2016-11-04 09:01:06.317476 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/8003@172.xx.xx.3) State ROUTING going to sleep
  128. 2016-11-04 09:01:06.317476 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_EXECUTE
  129. 2016-11-04 09:01:06.317476 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/8003@172.xx.xx.3) State EXECUTE
  130. 2016-11-04 09:01:06.317476 [DEBUG] mod_sofia.c:197 sofia/internal/8003@172.xx.xx.3 SOFIA EXECUTE
  131. 2016-11-04 09:01:06.317476 [DEBUG] switch_core_state_machine.c:328 sofia/internal/8003@172.xx.xx.3 Standard EXECUTE
  132. EXECUTE sofia/internal/8003@172.xx.xx.3 set(outside_call=true)
  133. 2016-11-04 09:01:06.317476 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [outside_call]=[true]
  134. EXECUTE sofia/internal/8003@172.xx.xx.3 export(RFC2822_DATE=Fri, 04 Nov 2016 09:01:06 +0200)
  135. 2016-11-04 09:01:06.317476 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 04 Nov 2016 09:01:06 +0200]
  136. EXECUTE sofia/internal/8003@172.xx.xx.3 set(transfer_after_bridge=4099)
  137. 2016-11-04 09:01:06.317476 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [transfer_after_bridge]=[4099]
  138. EXECUTE sofia/internal/8003@172.xx.xx.3 sleep(300)
  139. 2016-11-04 09:01:06.617569 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set uuid = ffefcfb3-be10-472d-8469-40692b7457da
  140. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  141. )
  142. 2016-11-04 09:01:06.617569 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  143. ]
  144. 2016-11-04 09:01:06.617569 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set type = uuid-standby
  145. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  146. )
  147. 2016-11-04 09:01:06.617569 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  148. ]
  149. 2016-11-04 09:01:06.617569 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set status = Available
  150. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  151. )
  152. 2016-11-04 09:01:06.617569 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  153. ]
  154. 2016-11-04 09:01:06.617569 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = idle
  155. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  156. )
  157. 2016-11-04 09:01:06.617569 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  158. ]
  159. EXECUTE sofia/internal/8003@172.xx.xx.3 set(cc_warning_tone=tone_stream://%(200,0,500,600,700);loops=3)
  160. 2016-11-04 09:01:06.617569 [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]
  161. EXECUTE sofia/internal/8003@172.xx.xx.3 answer()
  162. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:3720 Activate Buggy RFC2833 Mode!
  163. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:4211 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  164. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:4266 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  165. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:4127 Set telephone-event payload to 101@8000
  166. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:4211 Audio Codec Compare [frf-dialed-digit:121:8000:20:0:1]/[G729:18:8000:20:8000:1]
  167. 2016-11-04 09:01:06.617569 [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
  168. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_codec.c:111 sofia/internal/8003@172.xx.xx.3 Original read codec set to G729:18
  169. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:4479 Set telephone-event payload to 101@8000
  170. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:4537 sofia/internal/8003@172.xx.xx.3 Set 2833 dtmf send payload to 101 recv payload to 101
  171. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:6320 AUDIO RTP [sofia/internal/8003@172.xx.xx.3] 172.31.35.60 port 23388 -> 172.xx.xx.3 port 18436 codec: 18 ms: 20
  172. 2016-11-04 09:01:06.617569 [DEBUG] switch_rtp.c:3812 Starting timer [soft] 160 bytes per 20ms
  173. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:6619 sofia/internal/8003@172.xx.xx.3 Set 2833 dtmf send payload to 101
  174. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:6626 sofia/internal/8003@172.xx.xx.3 Set 2833 dtmf receive payload to 101
  175. 2016-11-04 09:01:06.617569 [DEBUG] switch_core_media.c:6649 sofia/internal/8003@172.xx.xx.3 Set rtp dtmf delay to 40
  176. 2016-11-04 09:01:06.617569 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/8003@172.xx.xx.3!
  177. 2016-11-04 09:01:06.617569 [DEBUG] switch_channel.c:3471 (sofia/internal/8003@172.xx.xx.3) Callstate Change RINGING -> EARLY
  178. 2016-11-04 09:01:06.617569 [DEBUG] mod_sofia.c:814 Local SDP sofia/internal/8003@172.xx.xx.3:
  179. v=0
  180. o=FreeSWITCH 1478219478 1478219479 IN IP4 172.31.35.60
  181. s=FreeSWITCH
  182. c=IN IP4 172.31.35.60
  183. t=0 0
  184. m=audio 23388 RTP/AVP 18 101
  185. a=rtpmap:18 G729/8000
  186. a=fmtp:18 annexb=no
  187. a=rtpmap:101 telephone-event/8000
  188. a=fmtp:101 0-16
  189. a=ptime:20
  190. a=sendrecv
  191.  
  192. send 1183 bytes to udp/[172.xx.xx.3]:5060 at 09:01:06.627947:
  193.    ------------------------------------------------------------------------
  194.    SIP/2.0 200 OK
  195.    Via: SIP/2.0/UDP 172.xx.xx.3:5060;branch=z9hG4bK109A23191E
  196.    From: <sip:8003@172.xx.xx.3>;tag=2E56AF64-2516
  197.    To: <sip:4099@172.31.35.60>;tag=80540NSm23ZHN
  198.    Call-ID: 2906F641-A19211E6-BC7FC7B1-C40D3FF0@172.xx.xx.3
  199.    CSeq: 101 INVITE
  200.    Contact: <sip:4099@172.31.35.60:5060;transport=udp>
  201.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  202.    Accept: application/sdp
  203.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  204. 2016-11-04 09:01:06.617569 [NOTICE] mod_dptools.c:1309 Channel [sofia/internal/8003@172.xx.xx.3] has been answered
  205.    Require: timer
  206.    Supported: timer, path, replaces
  207.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  208.    Session-Expires: 1800;refresher=uac
  209.    Content-Type: application/sdp
  210.    Content-Disposition: session
  211.    Content-Length: 243
  212.    Remote-Party-ID: "4099" <sip:4099@172.31.35.60>;party=calling;privacy=off;screen=no
  213.    
  214.    v=0
  215.    o=FreeSWITCH 1478219478 1478219479 IN IP4 172.31.35.60
  216.    s=FreeSWITCH
  217.    c=IN IP4 172.31.35.60
  218.    t=0 0
  219.    m=audio 23388 RTP/AVP 18 101
  220.    a=rtpmap:18 G729/8000
  221.    a=fmtp:18 annexb=no
  222.    a=rtpmap:101 telephone-event/8000
  223.    a=fmtp:101 0-16
  224.    a=ptime:20
  225.    ------------------------------------------------------------------------
  226. 2016-11-04 09:01:06.617569 [DEBUG] switch_channel.c:3770 (sofia/internal/8003@172.xx.xx.3) Callstate Change EARLY -> ACTIVE
  227. 2016-11-04 09:01:06.617569 [DEBUG] sofia.c:6858 Channel sofia/internal/8003@172.xx.xx.3 entering state [completed][200]
  228. EXECUTE sofia/internal/8003@172.xx.xx.3 playback(local_stream://moh)
  229. 2016-11-04 09:01:06.617569 [DEBUG] mod_local_stream.c:843 Opening Stream [moh/8000] 8000hz
  230. 2016-11-04 09:01:06.617569 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
  231. recv 389 bytes from udp/[172.xx.xx.3]:65516 at 09:01:06.637041:
  232.    ------------------------------------------------------------------------
  233.    ACK sip:4099@172.31.35.60:5060;transport=udp SIP/2.0
  234.    Via: SIP/2.0/UDP 172.xx.xx.3:5060;branch=z9hG4bK109A241C3B
  235.    From: <sip:8003@172.xx.xx.3>;tag=2E56AF64-2516
  236.    To: <sip:4099@172.31.35.60>;tag=80540NSm23ZHN
  237.    Date: Fri, 04 Nov 2016 06:53:00 GMT
  238.    Call-ID: 2906F641-A19211E6-BC7FC7B1-C40D3FF0@172.xx.xx.3
  239.    Max-Forwards: 70
  240.    CSeq: 101 ACK
  241.    Allow-Events: telephone-event
  242.    Content-Length: 0
  243.    
  244.    ------------------------------------------------------------------------
  245. 2016-11-04 09:01:06.637471 [DEBUG] sofia.c:6858 Channel sofia/internal/8003@172.xx.xx.3 entering state [ready][200]
  246. 2016-11-04 09:01:06.637471 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc180591a8 0x7efc180591a8
  247. 2016-11-04 09:01:06.637471 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc180591a8 0x7efc180591a8
  248. 2016-11-04 09:01:06.857459 [DEBUG] switch_rtp.c:6670 Correct audio ip/port confirmed.
  249. 2016-11-04 09:01:17.737477 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = waiting
  250. 2016-11-04 09:01:24.917526 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
  251. 2016-11-04 09:01:24.917526 [NOTICE] switch_channel.c:1104 New Channel sofia/external/0833573222 [7f749ef1-ec7d-40f9-b17f-ef1883f6f7db]
  252. 2016-11-04 09:01:24.917526 [DEBUG] mod_sofia.c:4799 (sofia/external/0833573222) State Change CS_NEW -> CS_INIT
  253. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_INIT
  254. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:586 (sofia/external/0833573222) State INIT
  255. 2016-11-04 09:01:24.917526 [DEBUG] mod_sofia.c:89 sofia/external/0833573222 SOFIA INIT
  256. 2016-11-04 09:01:24.917526 [DEBUG] sofia_glue.c:1257 sofia/external/0833573222 sending invite version: 1.6.7 git f0c3870 2016-04-01 13:40:07Z 64bit
  257. Local SDP:
  258. v=0
  259. o=FreeSWITCH 1478221714 1478221715 IN IP4 172.xx.xx.85
  260. s=FreeSWITCH
  261. c=IN IP4 172.xx.xx.85
  262. t=0 0
  263. m=audio 21170 RTP/AVP 18
  264. a=rtpmap:18 G729/8000
  265. a=fmtp:18 annexb=no
  266. a=ptime:20
  267. a=sendrecv
  268.  
  269. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:40 sofia/external/0833573222 Standard INIT
  270. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:48 (sofia/external/0833573222) State Change CS_INIT -> CS_ROUTING
  271. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:586 (sofia/external/0833573222) State INIT going to sleep
  272. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_ROUTING
  273. send 997 bytes to udp/[172.xx.xx.61]:5060 at 09:01:24.932068:
  274.    ------------------------------------------------------------------------
  275.    INVITE sip:0833573222@172.xx.xx.61 SIP/2.0
  276.    Via: SIP/2.0/UDP 172.xx.xx.85;rport;branch=z9hG4bKmrm75mDKv6jgK
  277.    Max-Forwards: 70
  278.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  279.    To: <sip:0833573222@172.xx.xx.61>
  280.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  281.    CSeq: 98785378 INVITE
  282.    Contact: <sip:gw+Enav8@172.xx.xx.85:5060;transport=udp;gw=Enav8>
  283.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  284.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  285.    Supported: timer, path, replaces
  286.    Allow-Events: talk, hold, conference, refer
  287.    Content-Type: application/sdp
  288.    Content-Disposition: session
  289.    Content-Length: 187
  290.    X-FS-Support: update_display,send_info
  291.    Remote-Party-ID: <sip:0870980000@172.xx.xx.61>;party=calling;screen=yes;privacy=off
  292.    
  293.    v=0
  294.    o=FreeSWITCH 1478221714 1478221715 IN IP4 172.xx.xx.85
  295.    s=FreeSWITCH
  296.    c=IN IP4 172.xx.xx.85
  297.    t=0 0
  298.    m=audio 21170 RTP/AVP 18
  299.    a=rtpmap:18 G729/8000
  300.    a=fmtp:18 annexb=no
  301.    a=ptime:20
  302.    ------------------------------------------------------------------------
  303. 2016-11-04 09:01:24.917526 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [calling][0]
  304. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING
  305. 2016-11-04 09:01:24.917526 [DEBUG] mod_sofia.c:142 sofia/external/0833573222 SOFIA ROUTING
  306. 2016-11-04 09:01:24.917526 [DEBUG] switch_ivr_originate.c:67 (sofia/external/0833573222) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  307. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING going to sleep
  308. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_CONSUME_MEDIA
  309. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:621 (sofia/external/0833573222) State CONSUME_MEDIA
  310. 2016-11-04 09:01:24.917526 [DEBUG] switch_core_state_machine.c:621 (sofia/external/0833573222) State CONSUME_MEDIA going to sleep
  311. recv 291 bytes from udp/[172.xx.xx.61]:5060 at 09:01:24.932603:
  312.    ------------------------------------------------------------------------
  313.    SIP/2.0 100 Trying
  314.    Via: SIP/2.0/UDP 172.xx.xx.85;rport=5060;branch=z9hG4bKmrm75mDKv6jgK
  315.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  316.    To: <sip:0833573222@172.xx.xx.61>
  317.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  318.    CSeq: 98785378 INVITE
  319.    User-Agent: FS
  320.    Content-Length: 0
  321.    
  322.    ------------------------------------------------------------------------
  323. recv 780 bytes from udp/[172.xx.xx.61]:5060 at 09:01:24.933589:
  324.    ------------------------------------------------------------------------
  325.    SIP/2.0 407 Proxy Authentication Required
  326.    Via: SIP/2.0/UDP 172.xx.xx.85;rport=5060;branch=z9hG4bKmrm75mDKv6jgK
  327.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  328.    To: <sip:0833573222@172.xx.xx.61>;tag=jcDrBKcUKeeve
  329.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  330.    CSeq: 98785378 INVITE
  331.    User-Agent: FS
  332.    Accept: application/sdp
  333.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
  334.    Supported: path, replaces
  335.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  336.    Proxy-Authenticate: Digest realm="172.xx.xx.61", nonce="8c34bb6a-8fe3-4cd1-83c0-1008c0f9cf95", algorithm=MD5, qop="auth"
  337.    Content-Length: 0
  338.    
  339.    ------------------------------------------------------------------------
  340. send 321 bytes to udp/[172.xx.xx.61]:5060 at 09:01:24.933754:
  341.    ------------------------------------------------------------------------
  342.    ACK sip:0833573222@172.xx.xx.61 SIP/2.0
  343.    Via: SIP/2.0/UDP 172.xx.xx.85;rport;branch=z9hG4bKmrm75mDKv6jgK
  344.    Max-Forwards: 70
  345.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  346.    To: <sip:0833573222@172.xx.xx.61>;tag=jcDrBKcUKeeve
  347.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  348.    CSeq: 98785378 ACK
  349.    Content-Length: 0
  350.    
  351.    ------------------------------------------------------------------------
  352. send 1265 bytes to udp/[172.xx.xx.61]:5060 at 09:01:24.934090:
  353.    ------------------------------------------------------------------------
  354.    INVITE sip:0833573222@172.xx.xx.61 SIP/2.0
  355.    Via: SIP/2.0/UDP 172.xx.xx.85;rport;branch=z9hG4bKN1D07FypSF92e
  356.    Max-Forwards: 70
  357.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  358.    To: <sip:0833573222@172.xx.xx.61>
  359.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  360.    CSeq: 98785379 INVITE
  361.    Contact: <sip:gw+Enav8@172.xx.xx.85:5060;transport=udp;gw=Enav8>
  362.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  363.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  364.    Supported: timer, path, replaces
  365.    Allow-Events: talk, hold, conference, refer
  366.    Proxy-Authorization: Digest username="Enav8Pre", realm="172.xx.xx.61", nonce="8c34bb6a-8fe3-4cd1-83c0-1008c0f9cf95", cnonce="V1r4Cxz/EjWAOwBQVosP+A", algorithm=MD5, uri="sip:0833573222@172.xx.xx.61", response="89a3cb645152d7f9faa5fb08c47cfbad", qop=auth, nc=00000001
  367.    Content-Type: application/sdp
  368.    Content-Disposition: session
  369.    Content-Length: 187
  370.    X-FS-Support: update_display,send_info
  371.    Remote-Party-ID: <sip:0870980000@172.xx.xx.61>;party=calling;screen=yes;privacy=off
  372.    
  373.    v=0
  374.    o=FreeSWITCH 1478221714 1478221715 IN IP4 172.xx.xx.85
  375.    s=FreeSWITCH
  376.    c=IN IP4 172.xx.xx.85
  377.    t=0 0
  378.    m=audio 21170 RTP/AVP 18
  379.    a=rtpmap:18 G729/8000
  380.    a=fmtp:18 annexb=no
  381.    a=ptime:20
  382.    ------------------------------------------------------------------------
  383. 2016-11-04 09:01:24.917526 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [calling][0]
  384. recv 291 bytes from udp/[172.xx.xx.61]:5060 at 09:01:24.934567:
  385.    ------------------------------------------------------------------------
  386.    SIP/2.0 100 Trying
  387.    Via: SIP/2.0/UDP 172.xx.xx.85;rport=5060;branch=z9hG4bKN1D07FypSF92e
  388.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  389.    To: <sip:0833573222@172.xx.xx.61>
  390.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  391.    CSeq: 98785379 INVITE
  392.    User-Agent: FS
  393.    Content-Length: 0
  394.    
  395.    ------------------------------------------------------------------------
  396. recv 1186 bytes from udp/[172.xx.xx.61]:5060 at 09:01:26.258940:
  397.    ------------------------------------------------------------------------
  398.    SIP/2.0 183 Session Progress
  399.    Via: SIP/2.0/UDP 172.xx.xx.85;rport=5060;branch=z9hG4bKN1D07FypSF92e
  400.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  401.    To: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  402.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  403.    CSeq: 98785379 INVITE
  404.    Contact: <sip:0833573222@172.xx.xx.61:5060;transport=udp>
  405.    User-Agent: FS
  406.    Accept: application/sdp
  407.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
  408.    Supported: path, replaces
  409.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  410.    Content-Type: application/sdp
  411.    Content-Disposition: session
  412.    Content-Length: 187
  413.    X-FS-Display-Name: Outbound Call
  414.    X-FS-Display-Number: sip:D0820833573222@172.xx.xx.61
  415.    X-FS-Support: update_display,send_info
  416.    Remote-Party-ID: "Outbound Call" <sip:D0820833573222@172.xx.xx.61>;party=calling;privacy=off;screen=no
  417.    
  418.    v=0
  419.    o=FreeSWITCH 1478221402 1478221403 IN IP4 172.xx.xx.61
  420.    s=FreeSWITCH
  421.    c=IN IP4 172.xx.xx.61
  422.    t=0 0
  423.    m=audio 21498 RTP/AVP 18
  424.    a=rtpmap:18 G729/8000
  425.    a=fmtp:18 annexb=no
  426.    a=ptime:20
  427.    ------------------------------------------------------------------------
  428. 2016-11-04 09:01:26.257556 [INFO] sofia.c:1255 sofia/external/0833573222 Update Callee ID to "Outbound Call" <sip:D0820833573222@172.xx.xx.61>
  429. 2016-11-04 09:01:26.257556 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [proceeding][183]
  430. 2016-11-04 09:01:26.257556 [DEBUG] sofia.c:6868 Remote SDP:
  431. v=0
  432. o=FreeSWITCH 1478221402 1478221403 IN IP4 172.xx.xx.61
  433. s=FreeSWITCH
  434. c=IN IP4 172.xx.xx.61
  435. t=0 0
  436. m=audio 21498 RTP/AVP 18
  437. a=rtpmap:18 G729/8000
  438. a=fmtp:18 annexb=no
  439. a=ptime:20
  440.  
  441. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:4211 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  442. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:4266 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  443. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:2946 Set Codec sofia/external/0833573222 G729/8000 20 ms 160 samples 8000 bits 1 channels
  444. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_codec.c:111 sofia/external/0833573222 Original read codec set to G729:18
  445. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:4509 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  446. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:4537 sofia/external/0833573222 Set 2833 dtmf send payload to 101 recv payload to 101
  447. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:6320 AUDIO RTP [sofia/external/0833573222] 172.xx.xx.85 port 21170 -> 172.xx.xx.61 port 21498 codec: 18 ms: 20
  448. 2016-11-04 09:01:26.257556 [DEBUG] switch_rtp.c:3812 Starting timer [soft] 160 bytes per 20ms
  449. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:6619 sofia/external/0833573222 Set 2833 dtmf send payload to 101
  450. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:6626 sofia/external/0833573222 Set 2833 dtmf receive payload to 101
  451. 2016-11-04 09:01:26.257556 [DEBUG] switch_core_media.c:6649 sofia/external/0833573222 Set rtp dtmf delay to 40
  452. 2016-11-04 09:01:26.257556 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/0833573222!
  453. 2016-11-04 09:01:26.257556 [DEBUG] switch_channel.c:3471 (sofia/external/0833573222) Callstate Change DOWN -> EARLY
  454. 2016-11-04 09:01:26.277460 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/external/0833573222]
  455. 2016-11-04 09:01:26.277460 [DEBUG] switch_ivr.c:2160 (sofia/external/0833573222) State Change CS_CONSUME_MEDIA -> CS_ROUTING
  456. 2016-11-04 09:01:26.277460 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/0833573222 to XML[0870980890@default]
  457. 2016-11-04 09:01:26.277460 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_ROUTING
  458. 2016-11-04 09:01:26.277460 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING
  459. 2016-11-04 09:01:26.277460 [DEBUG] mod_sofia.c:142 sofia/external/0833573222 SOFIA ROUTING
  460. 2016-11-04 09:01:26.277460 [DEBUG] switch_core_state_machine.c:236 sofia/external/0833573222 Standard ROUTING
  461. 2016-11-04 09:01:26.277460 [INFO] mod_dialplan_xml.c:637 Processing  <0870980000>->0870980890 in context default
  462. Dialplan: sofia/external/0833573222 parsing [default->unloop] continue=false
  463. Dialplan: sofia/external/0833573222 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  464. Dialplan: sofia/external/0833573222 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  465. Dialplan: sofia/external/0833573222 parsing [default->tod_example] continue=true
  466. Dialplan: sofia/external/0833573222 Date/Time Match (PASS) [tod_example] break=on-false
  467. Dialplan: sofia/external/0833573222 Action set(open=true)
  468. Dialplan: sofia/external/0833573222 parsing [default->holiday_example] continue=true
  469. Dialplan: sofia/external/0833573222 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  470. Dialplan: sofia/external/0833573222 parsing [default->global-intercept] continue=false
  471. Dialplan: sofia/external/0833573222 Regex (FAIL) [global-intercept] destination_number(0870980890) =~ /^886$/ break=on-false
  472. Dialplan: sofia/external/0833573222 parsing [default->group-intercept] continue=false
  473. Dialplan: sofia/external/0833573222 Regex (FAIL) [group-intercept] destination_number(0870980890) =~ /^\*8$/ break=on-false
  474. Dialplan: sofia/external/0833573222 parsing [default->intercept-ext] continue=false
  475. Dialplan: sofia/external/0833573222 Regex (FAIL) [intercept-ext] destination_number(0870980890) =~ /^\*\*(\d+)$/ break=on-false
  476. Dialplan: sofia/external/0833573222 parsing [default->redial] continue=false
  477. Dialplan: sofia/external/0833573222 Regex (FAIL) [redial] destination_number(0870980890) =~ /^(redial|870)$/ break=on-false
  478. Dialplan: sofia/external/0833573222 parsing [default->global] continue=true
  479. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  480. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${default_password}(4321) =~ /^1234$/ break=never
  481. 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
  482. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${endpoint_disposition}(EARLY MEDIA) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  483. Dialplan: sofia/external/0833573222 parsing [default->snom-demo-2] continue=false
  484. Dialplan: sofia/external/0833573222 Regex (FAIL) [snom-demo-2] destination_number(0870980890) =~ /^9001$/ break=on-false
  485. Dialplan: sofia/external/0833573222 parsing [default->snom-demo-1] continue=false
  486. Dialplan: sofia/external/0833573222 Regex (FAIL) [snom-demo-1] destination_number(0870980890) =~ /^9000$/ break=on-false
  487. Dialplan: sofia/external/0833573222 parsing [default->eavesdrop] continue=false
  488. Dialplan: sofia/external/0833573222 Regex (FAIL) [eavesdrop] destination_number(0870980890) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  489. Dialplan: sofia/external/0833573222 parsing [default->eavesdrop] continue=false
  490. Dialplan: sofia/external/0833573222 Regex (FAIL) [eavesdrop] destination_number(0870980890) =~ /^779$/ break=on-false
  491. Dialplan: sofia/external/0833573222 parsing [default->call_return] continue=false
  492. Dialplan: sofia/external/0833573222 Regex (FAIL) [call_return] destination_number(0870980890) =~ /^\*69$|^869$|^lcr$/ break=on-false
  493. Dialplan: sofia/external/0833573222 parsing [default->del-group] continue=false
  494. Dialplan: sofia/external/0833573222 Regex (FAIL) [del-group] destination_number(0870980890) =~ /^80(\d{2})$/ break=on-false
  495. Dialplan: sofia/external/0833573222 parsing [default->add-group] continue=false
  496. Dialplan: sofia/external/0833573222 Regex (FAIL) [add-group] destination_number(0870980890) =~ /^81(\d{2})$/ break=on-false
  497. Dialplan: sofia/external/0833573222 parsing [default->call-group-simo] continue=false
  498. Dialplan: sofia/external/0833573222 Regex (FAIL) [call-group-simo] destination_number(0870980890) =~ /^82(\d{2})$/ break=on-false
  499. Dialplan: sofia/external/0833573222 parsing [default->call-group-order] continue=false
  500. Dialplan: sofia/external/0833573222 Regex (FAIL) [call-group-order] destination_number(0870980890) =~ /^83(\d{2})$/ break=on-false
  501. Dialplan: sofia/external/0833573222 parsing [default->extension-intercom] continue=false
  502. Dialplan: sofia/external/0833573222 Regex (FAIL) [extension-intercom] destination_number(0870980890) =~ /^8(10[01][0-9])$/ break=on-false
  503. Dialplan: sofia/external/0833573222 parsing [default->Local_Ext] continue=false
  504. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Ext] destination_number(0870980890) =~ /^(30[01][0-9])$/ break=on-false
  505. Dialplan: sofia/external/0833573222 parsing [default->Local_Extension] continue=false
  506. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Extension] destination_number(0870980890) =~ /^(10[01][0-9])$/ break=on-false
  507. Dialplan: sofia/external/0833573222 parsing [default->Local_Extension_Skinny] continue=false
  508. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Extension_Skinny] destination_number(0870980890) =~ /^(11[01][0-9])$/ break=on-false
  509. Dialplan: sofia/external/0833573222 parsing [default->group_dial_sales] continue=false
  510. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_sales] destination_number(0870980890) =~ /^2000$/ break=on-false
  511. Dialplan: sofia/external/0833573222 parsing [default->group_dial_support] continue=false
  512. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_support] destination_number(0870980890) =~ /^2001$/ break=on-false
  513. Dialplan: sofia/external/0833573222 parsing [default->group_dial_billing] continue=false
  514. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_billing] destination_number(0870980890) =~ /^2002$/ break=on-false
  515. Dialplan: sofia/external/0833573222 parsing [default->group_dial_huntgroup] continue=false
  516. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_huntgroup] destination_number(0870980890) =~ /^0870980891$/ break=on-false
  517. Dialplan: sofia/external/0833573222 parsing [default->group_dial_did_to_local] continue=false
  518. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_did_to_local] destination_number(0870980890) =~ /^0870980892$/ break=on-false
  519. Dialplan: sofia/external/0833573222 parsing [default->operator] continue=false
  520. Dialplan: sofia/external/0833573222 Regex (FAIL) [operator] destination_number(0870980890) =~ /^(operator|0)$/ break=on-false
  521. Dialplan: sofia/external/0833573222 parsing [default->vmain] continue=false
  522. Dialplan: sofia/external/0833573222 Regex (FAIL) [vmain] destination_number(0870980890) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  523. Dialplan: sofia/external/0833573222 parsing [default->sip_uri] continue=false
  524. Dialplan: sofia/external/0833573222 Regex (FAIL) [sip_uri] destination_number(0870980890) =~ /^sip:(.*)$/ break=on-false
  525. Dialplan: sofia/external/0833573222 parsing [default->nb_conferences] continue=false
  526. Dialplan: sofia/external/0833573222 Regex (FAIL) [nb_conferences] destination_number(0870980890) =~ /^(30\d{2})$/ break=on-false
  527. Dialplan: sofia/external/0833573222 parsing [default->wb_conferences] continue=false
  528. Dialplan: sofia/external/0833573222 Regex (FAIL) [wb_conferences] destination_number(0870980890) =~ /^(31\d{2})$/ break=on-false
  529. Dialplan: sofia/external/0833573222 parsing [default->uwb_conferences] continue=false
  530. Dialplan: sofia/external/0833573222 Regex (FAIL) [uwb_conferences] destination_number(0870980890) =~ /^(32\d{2})$/ break=on-false
  531. Dialplan: sofia/external/0833573222 parsing [default->cdquality_conferences] continue=false
  532. Dialplan: sofia/external/0833573222 Regex (FAIL) [cdquality_conferences] destination_number(0870980890) =~ /^(33\d{2})$/ break=on-false
  533. Dialplan: sofia/external/0833573222 parsing [default->cdquality_conferences] continue=false
  534. Dialplan: sofia/external/0833573222 Regex (FAIL) [cdquality_conferences] destination_number(0870980890) =~ /^(35\d{2})$/ break=on-false
  535. Dialplan: sofia/external/0833573222 parsing [default->freeswitch_public_conf_via_sip] continue=false
  536. Dialplan: sofia/external/0833573222 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(0870980890) =~ /^9(888|8888|1616|3232)$/ break=on-false
  537. Dialplan: sofia/external/0833573222 parsing [default->mad_boss_intercom] continue=false
  538. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss_intercom] destination_number(0870980890) =~ /^0911$/ break=on-false
  539. Dialplan: sofia/external/0833573222 parsing [default->mad_boss_intercom] continue=false
  540. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss_intercom] destination_number(0870980890) =~ /^0912$/ break=on-false
  541. Dialplan: sofia/external/0833573222 parsing [default->mad_boss] continue=false
  542. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss] destination_number(0870980890) =~ /^0913$/ break=on-false
  543. Dialplan: sofia/external/0833573222 parsing [default->ivr_demo] continue=false
  544. Dialplan: sofia/external/0833573222 Regex (FAIL) [ivr_demo] destination_number(0870980890) =~ /^5000$/ break=on-false
  545. Dialplan: sofia/external/0833573222 parsing [default->dynamic_conference] continue=false
  546. Dialplan: sofia/external/0833573222 Regex (FAIL) [dynamic_conference] destination_number(0870980890) =~ /^5001$/ break=on-false
  547. Dialplan: sofia/external/0833573222 parsing [default->rtp_multicast_page] continue=false
  548. Dialplan: sofia/external/0833573222 Regex (FAIL) [rtp_multicast_page] destination_number(0870980890) =~ /^pagegroup$|^7243$/ break=on-false
  549. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  550. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] destination_number(0870980890) =~ /^5900$/ break=on-false
  551. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  552. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] destination_number(0870980890) =~ /^5901$/ break=on-false
  553. Dialplan: sofia/external/0833573222 parsing [default->valet_park] continue=false
  554. Dialplan: sofia/external/0833573222 Regex (FAIL) [valet_park] destination_number(0870980890) =~ /^(6000)$/ break=on-false
  555. Dialplan: sofia/external/0833573222 parsing [default->valet_park] continue=false
  556. Dialplan: sofia/external/0833573222 Regex (FAIL) [valet_park] destination_number(0870980890) =~ /^((?!6000)60\d{2})$/ break=on-false
  557. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  558. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  559. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  560. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  561. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  562. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  563. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  564. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  565. Dialplan: sofia/external/0833573222 parsing [default->wait] continue=false
  566. Dialplan: sofia/external/0833573222 Regex (FAIL) [wait] destination_number(0870980890) =~ /^wait$/ break=on-false
  567. Dialplan: sofia/external/0833573222 parsing [default->fax_receive] continue=false
  568. Dialplan: sofia/external/0833573222 Regex (FAIL) [fax_receive] destination_number(0870980890) =~ /^9178$/ break=on-false
  569. Dialplan: sofia/external/0833573222 parsing [default->fax_transmit] continue=false
  570. Dialplan: sofia/external/0833573222 Regex (FAIL) [fax_transmit] destination_number(0870980890) =~ /^9179$/ break=on-false
  571. Dialplan: sofia/external/0833573222 parsing [default->ringback_180] continue=false
  572. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_180] destination_number(0870980890) =~ /^9180$/ break=on-false
  573. Dialplan: sofia/external/0833573222 parsing [default->ringback_183_uk_ring] continue=false
  574. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_183_uk_ring] destination_number(0870980890) =~ /^9181$/ break=on-false
  575. Dialplan: sofia/external/0833573222 parsing [default->ringback_183_music_ring] continue=false
  576. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_183_music_ring] destination_number(0870980890) =~ /^9182$/ break=on-false
  577. Dialplan: sofia/external/0833573222 parsing [default->ringback_post_answer_uk_ring] continue=false
  578. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(0870980890) =~ /^9183$/ break=on-false
  579. Dialplan: sofia/external/0833573222 parsing [default->ringback_post_answer_music] continue=false
  580. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_post_answer_music] destination_number(0870980890) =~ /^9184$/ break=on-false
  581. Dialplan: sofia/external/0833573222 parsing [default->ClueCon] continue=false
  582. Dialplan: sofia/external/0833573222 Regex (FAIL) [ClueCon] destination_number(0870980890) =~ /^9191$/ break=on-false
  583. Dialplan: sofia/external/0833573222 parsing [default->show_info] continue=false
  584. Dialplan: sofia/external/0833573222 Regex (FAIL) [show_info] destination_number(0870980890) =~ /^9192$/ break=on-false
  585. Dialplan: sofia/external/0833573222 parsing [default->video_record] continue=false
  586. Dialplan: sofia/external/0833573222 Regex (FAIL) [video_record] destination_number(0870980890) =~ /^9193$/ break=on-false
  587. Dialplan: sofia/external/0833573222 parsing [default->video_playback] continue=false
  588. Dialplan: sofia/external/0833573222 Regex (FAIL) [video_playback] destination_number(0870980890) =~ /^9194$/ break=on-false
  589. Dialplan: sofia/external/0833573222 parsing [default->delay_echo] continue=false
  590. Dialplan: sofia/external/0833573222 Regex (FAIL) [delay_echo] destination_number(0870980890) =~ /^9195$/ break=on-false
  591. Dialplan: sofia/external/0833573222 parsing [default->echo] continue=false
  592. Dialplan: sofia/external/0833573222 Regex (FAIL) [echo] destination_number(0870980890) =~ /^9196$/ break=on-false
  593. Dialplan: sofia/external/0833573222 parsing [default->milliwatt] continue=false
  594. Dialplan: sofia/external/0833573222 Regex (FAIL) [milliwatt] destination_number(0870980890) =~ /^9197$/ break=on-false
  595. Dialplan: sofia/external/0833573222 parsing [default->tone_stream] continue=false
  596. Dialplan: sofia/external/0833573222 Regex (FAIL) [tone_stream] destination_number(0870980890) =~ /^9198$/ break=on-false
  597. Dialplan: sofia/external/0833573222 parsing [default->zrtp_enrollement] continue=false
  598. Dialplan: sofia/external/0833573222 Regex (FAIL) [zrtp_enrollement] destination_number(0870980890) =~ /^9787$/ break=on-false
  599. Dialplan: sofia/external/0833573222 parsing [default->hold_music] continue=false
  600. Dialplan: sofia/external/0833573222 Regex (FAIL) [hold_music] destination_number(0870980890) =~ /^9664$/ break=on-false
  601. Dialplan: sofia/external/0833573222 parsing [default->laugh break] continue=false
  602. Dialplan: sofia/external/0833573222 Regex (FAIL) [laugh break] destination_number(0870980890) =~ /^9386$/ break=on-false
  603. Dialplan: sofia/external/0833573222 parsing [default->101] continue=false
  604. Dialplan: sofia/external/0833573222 Regex (FAIL) [101] destination_number(0870980890) =~ /^101$/ break=on-false
  605. Dialplan: sofia/external/0833573222 parsing [default->pizza_demo] continue=false
  606. Dialplan: sofia/external/0833573222 Regex (FAIL) [pizza_demo] destination_number(0870980890) =~ /^(pizza|74992)$/ break=on-false
  607. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Time] continue=false
  608. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Time] destination_number(0870980890) =~ /^9170$/ break=on-false
  609. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Date] continue=false
  610. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Date] destination_number(0870980890) =~ /^9171$/ break=on-false
  611. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Date and Time] continue=false
  612. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Date and Time] destination_number(0870980890) =~ /^9172$/ break=on-false
  613. Dialplan: sofia/external/0833573222 parsing [default->exe_on_ans] continue=false
  614. Dialplan: sofia/external/0833573222 Regex (PASS) [exe_on_ans] destination_number(0870980890) =~ /^0870980890$/ break=on-false
  615. Dialplan: sofia/external/0833573222 Action set(execute_on_answer=transfer ANSWEREDCALL XML default)
  616. Dialplan: sofia/external/0833573222 Action log(INFO Waiting 30 seconds for $1 to answer...)
  617. Dialplan: sofia/external/0833573222 Action sleep(30000)
  618. Dialplan: sofia/external/0833573222 Action log(INFO Call to $1 was not answered, taking alternative action...)
  619. Dialplan: sofia/external/0833573222 Action transfer(UNANSWEREDCALL XML default)
  620. 2016-11-04 09:01:26.277460 [INFO] switch_channel.c:3127 sofia/external/0833573222 Flipping CID from "" <0870980000> to "Outbound Call" <D0820833573222>
  621. 2016-11-04 09:01:26.277460 [DEBUG] switch_core_state_machine.c:286 (sofia/external/0833573222) State Change CS_ROUTING -> CS_EXECUTE
  622. 2016-11-04 09:01:26.277460 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING going to sleep
  623. 2016-11-04 09:01:26.277460 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_EXECUTE
  624. 2016-11-04 09:01:26.277460 [DEBUG] switch_core_state_machine.c:609 (sofia/external/0833573222) State EXECUTE
  625. 2016-11-04 09:01:26.277460 [DEBUG] mod_sofia.c:197 sofia/external/0833573222 SOFIA EXECUTE
  626. 2016-11-04 09:01:26.277460 [DEBUG] switch_core_state_machine.c:328 sofia/external/0833573222 Standard EXECUTE
  627. EXECUTE sofia/external/0833573222 set(open=true)
  628. 2016-11-04 09:01:26.277460 [DEBUG] mod_dptools.c:1519 SET sofia/external/0833573222 [open]=[true]
  629. EXECUTE sofia/external/0833573222 set(execute_on_answer=transfer ANSWEREDCALL XML default)
  630. 2016-11-04 09:01:26.277460 [DEBUG] mod_dptools.c:1519 SET sofia/external/0833573222 [execute_on_answer]=[transfer ANSWEREDCALL XML default]
  631. EXECUTE sofia/external/0833573222 log(INFO Waiting 30 seconds for $1 to answer...)
  632. 2016-11-04 09:01:26.277460 [INFO] mod_dptools.c:1713 Waiting 30 seconds for $1 to answer...
  633. EXECUTE sofia/external/0833573222 sleep(30000)
  634. 2016-11-04 09:01:26.297461 [DEBUG] switch_rtp.c:6670 Correct audio ip/port confirmed.
  635. 2016-11-04 09:01:26.957563 [DEBUG] switch_core_media.c:2386 Correcting calculated ptime value from 300 to 150 to compensate for 1 lost packet(s)
  636. 2016-11-04 09:01:27.077565 [WARNING] switch_core_media.c:2418 Asynchronous PTIME not supported, changing our end from 20 to 60
  637. 2016-11-04 09:01:27.097469 [DEBUG] switch_core_media.c:2844 Changing Codec from G729@20ms@8000hz to G729@60ms@8000hz
  638. 2016-11-04 09:01:27.137491 [DEBUG] switch_rtp.c:3690 RE-Starting timer [soft] 480 bytes per 60ms
  639. 2016-11-04 09:01:27.137491 [DEBUG] switch_core_media.c:2946 Set Codec sofia/external/0833573222 G729/8000 60 ms 480 samples 8000 bits 1 channels
  640. 2016-11-04 09:01:27.137491 [DEBUG] switch_core_codec.c:123 sofia/external/0833573222 Original read codec replaced with G729:18
  641. recv 1147 bytes from udp/[172.xx.xx.61]:5060 at 09:01:30.094877:
  642.    ------------------------------------------------------------------------
  643.    SIP/2.0 200 OK
  644.    Via: SIP/2.0/UDP 172.xx.xx.85;rport=5060;branch=z9hG4bKN1D07FypSF92e
  645.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  646.    To: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  647.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  648.    CSeq: 98785379 INVITE
  649.    Contact: <sip:0833573222@172.xx.xx.61:5060;transport=udp>
  650.    User-Agent: FS
  651.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
  652.    Supported: path, replaces
  653.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  654.    Content-Type: application/sdp
  655.    Content-Disposition: session
  656.    Content-Length: 187
  657.    X-FS-Display-Name: Outbound Call
  658.    X-FS-Display-Number: sip:D0820833573222@172.xx.xx.61
  659.    X-FS-Support: update_display,send_info
  660.    Remote-Party-ID: "Outbound Call" <sip:D0820833573222@172.xx.xx.61>;party=calling;privacy=off;screen=no
  661.    
  662.    v=0
  663.    o=FreeSWITCH 1478221402 1478221403 IN IP4 172.xx.xx.61
  664.    s=FreeSWITCH
  665.    c=IN IP4 172.xx.xx.61
  666.    t=0 0
  667.    m=audio 21498 RTP/AVP 18
  668.    a=rtpmap:18 G729/8000
  669.    a=fmtp:18 annexb=no
  670.    a=ptime:20
  671.    ------------------------------------------------------------------------
  672. 2016-11-04 09:01:30.137539 [INFO] sofia.c:1244 sofia/external/0833573222 Update Caller ID to "Outbound Call" <sip:D0820833573222@172.xx.xx.61>
  673. 2016-11-04 09:01:30.137539 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [completing][200]
  674. 2016-11-04 09:01:30.137539 [DEBUG] sofia.c:6865 Duplicate SDP
  675. v=0
  676. o=FreeSWITCH 1478221402 1478221403 IN IP4 172.xx.xx.61
  677. s=FreeSWITCH
  678. c=IN IP4 172.xx.xx.61
  679. t=0 0
  680. m=audio 21498 RTP/AVP 18
  681. a=rtpmap:18 G729/8000
  682. a=fmtp:18 annexb=no
  683. a=ptime:20
  684.  
  685. send 674 bytes to udp/[172.xx.xx.61]:5060 at 09:01:30.142336:
  686.    ------------------------------------------------------------------------
  687.    ACK sip:0833573222@172.xx.xx.61:5060;transport=udp SIP/2.0
  688.    Via: SIP/2.0/UDP 172.xx.xx.85;rport;branch=z9hG4bKpa7r9aFtprZNa
  689.    Max-Forwards: 70
  690.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  691.    To: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  692.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  693.    CSeq: 98785379 ACK
  694.    Contact: <sip:gw+Enav8@172.xx.xx.85:5060;transport=udp;gw=Enav8>
  695.    Proxy-Authorization: Digest username="Enav8Pre", realm="172.xx.xx.61", nonce="8c34bb6a-8fe3-4cd1-83c0-1008c0f9cf95", cnonce="V1r4Cxz/EjWAOwBQVosP+A", algorithm=MD5, uri="sip:0833573222@172.xx.xx.61", response="89a3cb645152d7f9faa5fb08c47cfbad", qop=auth, nc=00000001
  696.    Content-Length: 0
  697.    
  698.    ------------------------------------------------------------------------
  699. recv 666 bytes from udp/[172.xx.xx.61]:5060 at 09:01:30.174255:
  700.    ------------------------------------------------------------------------
  701.   INFO sip:gw+Enav8@172.xx.xx.85:5060;transport=udp;gw=Enav8 SIP/2.0
  702.    Via: SIP/2.0/UDP 172.xx.xx.61;rport;branch=z9hG4bKH46X1Z83c5mDg
  703.    Max-Forwards: 70
  704.    From: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  705.    To: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  706.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  707.   CSeq: 98785388 INFO
  708.    Contact: <sip:0833573222@172.xx.xx.61:5060;transport=udp>
  709.    User-Agent: FS
  710.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
  711.    Supported: path, replaces
  712.    Content-Type: message/update_display
  713.    Content-Length: 0
  714.    X-FS-Display-Name: Outbound Call
  715.    X-FS-Display-Number: D0820833573222
  716.    
  717.    ------------------------------------------------------------------------
  718. 2016-11-04 09:01:30.197459 [DEBUG] sofia.c:6858 Channel sofia/external/0833573222 entering state [ready][200]
  719. 2016-11-04 09:01:30.197459 [NOTICE] sofia.c:7765 Channel [sofia/external/0833573222] has been answered
  720. EXECUTE sofia/external/0833573222 transfer(ANSWEREDCALL XML default)
  721. 2016-11-04 09:01:30.197459 [DEBUG] switch_ivr.c:2160 (sofia/external/0833573222) State Change CS_EXECUTE -> CS_ROUTING
  722. 2016-11-04 09:01:30.197459 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/0833573222 to XML[ANSWEREDCALL@default]
  723. 2016-11-04 09:01:30.197459 [DEBUG] switch_channel.c:3770 (sofia/external/0833573222) Callstate Change EARLY -> ACTIVE
  724. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:609 (sofia/external/0833573222) State EXECUTE going to sleep
  725. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_ROUTING
  726. send 486 bytes to udp/[172.xx.xx.61]:5060 at 09:01:30.203681:
  727.    ------------------------------------------------------------------------
  728.    SIP/2.0 200 OK
  729.    Via: SIP/2.0/UDP 172.xx.xx.61;rport=5060;branch=z9hG4bKH46X1Z83c5mDg
  730.    From: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  731.    To: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  732.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  733.   CSeq: 98785388 INFO
  734.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  735.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  736.    Supported: timer, path, replaces
  737.    Content-Length: 0
  738.    
  739.    ------------------------------------------------------------------------
  740. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING
  741. 2016-11-04 09:01:30.197459 [DEBUG] mod_sofia.c:142 sofia/external/0833573222 SOFIA ROUTING
  742. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:236 sofia/external/0833573222 Standard ROUTING
  743. 2016-11-04 09:01:30.197459 [INFO] mod_dialplan_xml.c:637 Processing Outbound Call <D0820833573222>->ANSWEREDCALL in context default
  744. Dialplan: sofia/external/0833573222 parsing [default->unloop] continue=false
  745. Dialplan: sofia/external/0833573222 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  746. Dialplan: sofia/external/0833573222 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  747. Dialplan: sofia/external/0833573222 parsing [default->tod_example] continue=true
  748. Dialplan: sofia/external/0833573222 Date/Time Match (PASS) [tod_example] break=on-false
  749. Dialplan: sofia/external/0833573222 Action set(open=true)
  750. Dialplan: sofia/external/0833573222 parsing [default->holiday_example] continue=true
  751. Dialplan: sofia/external/0833573222 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  752. Dialplan: sofia/external/0833573222 parsing [default->global-intercept] continue=false
  753. Dialplan: sofia/external/0833573222 Regex (FAIL) [global-intercept] destination_number(ANSWEREDCALL) =~ /^886$/ break=on-false
  754. Dialplan: sofia/external/0833573222 parsing [default->group-intercept] continue=false
  755. Dialplan: sofia/external/0833573222 Regex (FAIL) [group-intercept] destination_number(ANSWEREDCALL) =~ /^\*8$/ break=on-false
  756. Dialplan: sofia/external/0833573222 parsing [default->intercept-ext] continue=false
  757. Dialplan: sofia/external/0833573222 Regex (FAIL) [intercept-ext] destination_number(ANSWEREDCALL) =~ /^\*\*(\d+)$/ break=on-false
  758. Dialplan: sofia/external/0833573222 parsing [default->redial] continue=false
  759. Dialplan: sofia/external/0833573222 Regex (FAIL) [redial] destination_number(ANSWEREDCALL) =~ /^(redial|870)$/ break=on-false
  760. Dialplan: sofia/external/0833573222 parsing [default->global] continue=true
  761. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  762. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${default_password}(4321) =~ /^1234$/ break=never
  763. 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
  764. Dialplan: sofia/external/0833573222 Regex (FAIL) [global] ${endpoint_disposition}(ANSWER) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  765. Dialplan: sofia/external/0833573222 parsing [default->snom-demo-2] continue=false
  766. Dialplan: sofia/external/0833573222 Regex (FAIL) [snom-demo-2] destination_number(ANSWEREDCALL) =~ /^9001$/ break=on-false
  767. Dialplan: sofia/external/0833573222 parsing [default->snom-demo-1] continue=false
  768. Dialplan: sofia/external/0833573222 Regex (FAIL) [snom-demo-1] destination_number(ANSWEREDCALL) =~ /^9000$/ break=on-false
  769. Dialplan: sofia/external/0833573222 parsing [default->eavesdrop] continue=false
  770. Dialplan: sofia/external/0833573222 Regex (FAIL) [eavesdrop] destination_number(ANSWEREDCALL) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  771. Dialplan: sofia/external/0833573222 parsing [default->eavesdrop] continue=false
  772. Dialplan: sofia/external/0833573222 Regex (FAIL) [eavesdrop] destination_number(ANSWEREDCALL) =~ /^779$/ break=on-false
  773. Dialplan: sofia/external/0833573222 parsing [default->call_return] continue=false
  774. Dialplan: sofia/external/0833573222 Regex (FAIL) [call_return] destination_number(ANSWEREDCALL) =~ /^\*69$|^869$|^lcr$/ break=on-false
  775. Dialplan: sofia/external/0833573222 parsing [default->del-group] continue=false
  776. Dialplan: sofia/external/0833573222 Regex (FAIL) [del-group] destination_number(ANSWEREDCALL) =~ /^80(\d{2})$/ break=on-false
  777. Dialplan: sofia/external/0833573222 parsing [default->add-group] continue=false
  778. Dialplan: sofia/external/0833573222 Regex (FAIL) [add-group] destination_number(ANSWEREDCALL) =~ /^81(\d{2})$/ break=on-false
  779. Dialplan: sofia/external/0833573222 parsing [default->call-group-simo] continue=false
  780. Dialplan: sofia/external/0833573222 Regex (FAIL) [call-group-simo] destination_number(ANSWEREDCALL) =~ /^82(\d{2})$/ break=on-false
  781. Dialplan: sofia/external/0833573222 parsing [default->call-group-order] continue=false
  782. Dialplan: sofia/external/0833573222 Regex (FAIL) [call-group-order] destination_number(ANSWEREDCALL) =~ /^83(\d{2})$/ break=on-false
  783. Dialplan: sofia/external/0833573222 parsing [default->extension-intercom] continue=false
  784. Dialplan: sofia/external/0833573222 Regex (FAIL) [extension-intercom] destination_number(ANSWEREDCALL) =~ /^8(10[01][0-9])$/ break=on-false
  785. Dialplan: sofia/external/0833573222 parsing [default->Local_Ext] continue=false
  786. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Ext] destination_number(ANSWEREDCALL) =~ /^(30[01][0-9])$/ break=on-false
  787. Dialplan: sofia/external/0833573222 parsing [default->Local_Extension] continue=false
  788. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Extension] destination_number(ANSWEREDCALL) =~ /^(10[01][0-9])$/ break=on-false
  789. Dialplan: sofia/external/0833573222 parsing [default->Local_Extension_Skinny] continue=false
  790. Dialplan: sofia/external/0833573222 Regex (FAIL) [Local_Extension_Skinny] destination_number(ANSWEREDCALL) =~ /^(11[01][0-9])$/ break=on-false
  791. Dialplan: sofia/external/0833573222 parsing [default->group_dial_sales] continue=false
  792. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_sales] destination_number(ANSWEREDCALL) =~ /^2000$/ break=on-false
  793. Dialplan: sofia/external/0833573222 parsing [default->group_dial_support] continue=false
  794. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_support] destination_number(ANSWEREDCALL) =~ /^2001$/ break=on-false
  795. Dialplan: sofia/external/0833573222 parsing [default->group_dial_billing] continue=false
  796. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_billing] destination_number(ANSWEREDCALL) =~ /^2002$/ break=on-false
  797. Dialplan: sofia/external/0833573222 parsing [default->group_dial_huntgroup] continue=false
  798. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_huntgroup] destination_number(ANSWEREDCALL) =~ /^0870980891$/ break=on-false
  799. Dialplan: sofia/external/0833573222 parsing [default->group_dial_did_to_local] continue=false
  800. Dialplan: sofia/external/0833573222 Regex (FAIL) [group_dial_did_to_local] destination_number(ANSWEREDCALL) =~ /^0870980892$/ break=on-false
  801. Dialplan: sofia/external/0833573222 parsing [default->operator] continue=false
  802. Dialplan: sofia/external/0833573222 Regex (FAIL) [operator] destination_number(ANSWEREDCALL) =~ /^(operator|0)$/ break=on-false
  803. Dialplan: sofia/external/0833573222 parsing [default->vmain] continue=false
  804. Dialplan: sofia/external/0833573222 Regex (FAIL) [vmain] destination_number(ANSWEREDCALL) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  805. Dialplan: sofia/external/0833573222 parsing [default->sip_uri] continue=false
  806. Dialplan: sofia/external/0833573222 Regex (FAIL) [sip_uri] destination_number(ANSWEREDCALL) =~ /^sip:(.*)$/ break=on-false
  807. Dialplan: sofia/external/0833573222 parsing [default->nb_conferences] continue=false
  808. Dialplan: sofia/external/0833573222 Regex (FAIL) [nb_conferences] destination_number(ANSWEREDCALL) =~ /^(30\d{2})$/ break=on-false
  809. Dialplan: sofia/external/0833573222 parsing [default->wb_conferences] continue=false
  810. Dialplan: sofia/external/0833573222 Regex (FAIL) [wb_conferences] destination_number(ANSWEREDCALL) =~ /^(31\d{2})$/ break=on-false
  811. Dialplan: sofia/external/0833573222 parsing [default->uwb_conferences] continue=false
  812. Dialplan: sofia/external/0833573222 Regex (FAIL) [uwb_conferences] destination_number(ANSWEREDCALL) =~ /^(32\d{2})$/ break=on-false
  813. Dialplan: sofia/external/0833573222 parsing [default->cdquality_conferences] continue=false
  814. Dialplan: sofia/external/0833573222 Regex (FAIL) [cdquality_conferences] destination_number(ANSWEREDCALL) =~ /^(33\d{2})$/ break=on-false
  815. Dialplan: sofia/external/0833573222 parsing [default->cdquality_conferences] continue=false
  816. Dialplan: sofia/external/0833573222 Regex (FAIL) [cdquality_conferences] destination_number(ANSWEREDCALL) =~ /^(35\d{2})$/ break=on-false
  817. Dialplan: sofia/external/0833573222 parsing [default->freeswitch_public_conf_via_sip] continue=false
  818. Dialplan: sofia/external/0833573222 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(ANSWEREDCALL) =~ /^9(888|8888|1616|3232)$/ break=on-false
  819. Dialplan: sofia/external/0833573222 parsing [default->mad_boss_intercom] continue=false
  820. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss_intercom] destination_number(ANSWEREDCALL) =~ /^0911$/ break=on-false
  821. Dialplan: sofia/external/0833573222 parsing [default->mad_boss_intercom] continue=false
  822. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss_intercom] destination_number(ANSWEREDCALL) =~ /^0912$/ break=on-false
  823. Dialplan: sofia/external/0833573222 parsing [default->mad_boss] continue=false
  824. Dialplan: sofia/external/0833573222 Regex (FAIL) [mad_boss] destination_number(ANSWEREDCALL) =~ /^0913$/ break=on-false
  825. Dialplan: sofia/external/0833573222 parsing [default->ivr_demo] continue=false
  826. Dialplan: sofia/external/0833573222 Regex (FAIL) [ivr_demo] destination_number(ANSWEREDCALL) =~ /^5000$/ break=on-false
  827. Dialplan: sofia/external/0833573222 parsing [default->dynamic_conference] continue=false
  828. Dialplan: sofia/external/0833573222 Regex (FAIL) [dynamic_conference] destination_number(ANSWEREDCALL) =~ /^5001$/ break=on-false
  829. Dialplan: sofia/external/0833573222 parsing [default->rtp_multicast_page] continue=false
  830. Dialplan: sofia/external/0833573222 Regex (FAIL) [rtp_multicast_page] destination_number(ANSWEREDCALL) =~ /^pagegroup$|^7243$/ break=on-false
  831. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  832. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] destination_number(ANSWEREDCALL) =~ /^5900$/ break=on-false
  833. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  834. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] destination_number(ANSWEREDCALL) =~ /^5901$/ break=on-false
  835. Dialplan: sofia/external/0833573222 parsing [default->valet_park] continue=false
  836. Dialplan: sofia/external/0833573222 Regex (FAIL) [valet_park] destination_number(ANSWEREDCALL) =~ /^(6000)$/ break=on-false
  837. Dialplan: sofia/external/0833573222 parsing [default->valet_park] continue=false
  838. Dialplan: sofia/external/0833573222 Regex (FAIL) [valet_park] destination_number(ANSWEREDCALL) =~ /^((?!6000)60\d{2})$/ break=on-false
  839. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  840. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  841. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  842. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  843. Dialplan: sofia/external/0833573222 parsing [default->park] continue=false
  844. Dialplan: sofia/external/0833573222 Regex (FAIL) [park] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  845. Dialplan: sofia/external/0833573222 parsing [default->unpark] continue=false
  846. Dialplan: sofia/external/0833573222 Regex (FAIL) [unpark] source(src/switch_ivr_originate.c) =~ /mod_sofia/ break=on-false
  847. Dialplan: sofia/external/0833573222 parsing [default->wait] continue=false
  848. Dialplan: sofia/external/0833573222 Regex (FAIL) [wait] destination_number(ANSWEREDCALL) =~ /^wait$/ break=on-false
  849. Dialplan: sofia/external/0833573222 parsing [default->fax_receive] continue=false
  850. Dialplan: sofia/external/0833573222 Regex (FAIL) [fax_receive] destination_number(ANSWEREDCALL) =~ /^9178$/ break=on-false
  851. Dialplan: sofia/external/0833573222 parsing [default->fax_transmit] continue=false
  852. Dialplan: sofia/external/0833573222 Regex (FAIL) [fax_transmit] destination_number(ANSWEREDCALL) =~ /^9179$/ break=on-false
  853. Dialplan: sofia/external/0833573222 parsing [default->ringback_180] continue=false
  854. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_180] destination_number(ANSWEREDCALL) =~ /^9180$/ break=on-false
  855. Dialplan: sofia/external/0833573222 parsing [default->ringback_183_uk_ring] continue=false
  856. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_183_uk_ring] destination_number(ANSWEREDCALL) =~ /^9181$/ break=on-false
  857. Dialplan: sofia/external/0833573222 parsing [default->ringback_183_music_ring] continue=false
  858. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_183_music_ring] destination_number(ANSWEREDCALL) =~ /^9182$/ break=on-false
  859. Dialplan: sofia/external/0833573222 parsing [default->ringback_post_answer_uk_ring] continue=false
  860. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(ANSWEREDCALL) =~ /^9183$/ break=on-false
  861. Dialplan: sofia/external/0833573222 parsing [default->ringback_post_answer_music] continue=false
  862. Dialplan: sofia/external/0833573222 Regex (FAIL) [ringback_post_answer_music] destination_number(ANSWEREDCALL) =~ /^9184$/ break=on-false
  863. Dialplan: sofia/external/0833573222 parsing [default->ClueCon] continue=false
  864. Dialplan: sofia/external/0833573222 Regex (FAIL) [ClueCon] destination_number(ANSWEREDCALL) =~ /^9191$/ break=on-false
  865. Dialplan: sofia/external/0833573222 parsing [default->show_info] continue=false
  866. Dialplan: sofia/external/0833573222 Regex (FAIL) [show_info] destination_number(ANSWEREDCALL) =~ /^9192$/ break=on-false
  867. Dialplan: sofia/external/0833573222 parsing [default->video_record] continue=false
  868. Dialplan: sofia/external/0833573222 Regex (FAIL) [video_record] destination_number(ANSWEREDCALL) =~ /^9193$/ break=on-false
  869. Dialplan: sofia/external/0833573222 parsing [default->video_playback] continue=false
  870. Dialplan: sofia/external/0833573222 Regex (FAIL) [video_playback] destination_number(ANSWEREDCALL) =~ /^9194$/ break=on-false
  871. Dialplan: sofia/external/0833573222 parsing [default->delay_echo] continue=false
  872. Dialplan: sofia/external/0833573222 Regex (FAIL) [delay_echo] destination_number(ANSWEREDCALL) =~ /^9195$/ break=on-false
  873. Dialplan: sofia/external/0833573222 parsing [default->echo] continue=false
  874. Dialplan: sofia/external/0833573222 Regex (FAIL) [echo] destination_number(ANSWEREDCALL) =~ /^9196$/ break=on-false
  875. Dialplan: sofia/external/0833573222 parsing [default->milliwatt] continue=false
  876. Dialplan: sofia/external/0833573222 Regex (FAIL) [milliwatt] destination_number(ANSWEREDCALL) =~ /^9197$/ break=on-false
  877. Dialplan: sofia/external/0833573222 parsing [default->tone_stream] continue=false
  878. Dialplan: sofia/external/0833573222 Regex (FAIL) [tone_stream] destination_number(ANSWEREDCALL) =~ /^9198$/ break=on-false
  879. Dialplan: sofia/external/0833573222 parsing [default->zrtp_enrollement] continue=false
  880. Dialplan: sofia/external/0833573222 Regex (FAIL) [zrtp_enrollement] destination_number(ANSWEREDCALL) =~ /^9787$/ break=on-false
  881. Dialplan: sofia/external/0833573222 parsing [default->hold_music] continue=false
  882. Dialplan: sofia/external/0833573222 Regex (FAIL) [hold_music] destination_number(ANSWEREDCALL) =~ /^9664$/ break=on-false
  883. Dialplan: sofia/external/0833573222 parsing [default->laugh break] continue=false
  884. Dialplan: sofia/external/0833573222 Regex (FAIL) [laugh break] destination_number(ANSWEREDCALL) =~ /^9386$/ break=on-false
  885. Dialplan: sofia/external/0833573222 parsing [default->101] continue=false
  886. Dialplan: sofia/external/0833573222 Regex (FAIL) [101] destination_number(ANSWEREDCALL) =~ /^101$/ break=on-false
  887. Dialplan: sofia/external/0833573222 parsing [default->pizza_demo] continue=false
  888. Dialplan: sofia/external/0833573222 Regex (FAIL) [pizza_demo] destination_number(ANSWEREDCALL) =~ /^(pizza|74992)$/ break=on-false
  889. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Time] continue=false
  890. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Time] destination_number(ANSWEREDCALL) =~ /^9170$/ break=on-false
  891. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Date] continue=false
  892. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Date] destination_number(ANSWEREDCALL) =~ /^9171$/ break=on-false
  893. Dialplan: sofia/external/0833573222 parsing [default->Talking Clock Date and Time] continue=false
  894. Dialplan: sofia/external/0833573222 Regex (FAIL) [Talking Clock Date and Time] destination_number(ANSWEREDCALL) =~ /^9172$/ break=on-false
  895. Dialplan: sofia/external/0833573222 parsing [default->exe_on_ans] continue=false
  896. Dialplan: sofia/external/0833573222 Regex (FAIL) [exe_on_ans] destination_number(ANSWEREDCALL) =~ /^0870980890$/ break=on-false
  897. Dialplan: sofia/external/0833573222 parsing [default->nb_conferences_answered] continue=false
  898. Dialplan: sofia/external/0833573222 Regex (PASS) [nb_conferences_answered] destination_number(ANSWEREDCALL) =~ /^ANSWEREDCALL$/ break=on-false
  899. Dialplan: sofia/external/0833573222 Action answer()
  900. Dialplan: sofia/external/0833573222 Action set(hangup_after_bridge=true)
  901. Dialplan: sofia/external/0833573222 Action set(continue_on_fail=true)
  902. Dialplan: sofia/external/0833573222 Action callcenter(agent8003@default)
  903. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:286 (sofia/external/0833573222) State Change CS_ROUTING -> CS_EXECUTE
  904. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:602 (sofia/external/0833573222) State ROUTING going to sleep
  905. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_EXECUTE
  906. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:609 (sofia/external/0833573222) State EXECUTE
  907. 2016-11-04 09:01:30.197459 [DEBUG] mod_sofia.c:197 sofia/external/0833573222 SOFIA EXECUTE
  908. 2016-11-04 09:01:30.197459 [DEBUG] switch_core_state_machine.c:328 sofia/external/0833573222 Standard EXECUTE
  909. EXECUTE sofia/external/0833573222 set(open=true)
  910. 2016-11-04 09:01:30.197459 [DEBUG] mod_dptools.c:1519 SET sofia/external/0833573222 [open]=[true]
  911. EXECUTE sofia/external/0833573222 answer()
  912. EXECUTE sofia/external/0833573222 set(hangup_after_bridge=true)
  913. 2016-11-04 09:01:30.197459 [DEBUG] mod_dptools.c:1519 SET sofia/external/0833573222 [hangup_after_bridge]=[true]
  914. EXECUTE sofia/external/0833573222 set(continue_on_fail=true)
  915. 2016-11-04 09:01:30.197459 [DEBUG] mod_dptools.c:1519 SET sofia/external/0833573222 [continue_on_fail]=[true]
  916. EXECUTE sofia/external/0833573222 callcenter(agent8003@default)
  917. 2016-11-04 09:01:30.197459 [DEBUG] mod_callcenter.c:2731 Member Outbound Call <D0820833573222> joining queue agent8003@default
  918. 2016-11-04 09:01:30.197459 [DEBUG] mod_local_stream.c:843 Opening Stream [moh/8000] 8000hz
  919. 2016-11-04 09:01:30.197459 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 60ms
  920. 2016-11-04 09:01:30.197459 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = Receiving
  921. 2016-11-04 09:01:30.197459 [DEBUG] mod_callcenter.c:1236 Updated tier: Agent 8003 in Queue agent8003@default set state = Active Inbound
  922. 2016-11-04 09:01:30.197459 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = In a queue call
  923. 2016-11-04 09:01:30.217462 [DEBUG] switch_ivr.c:623 sofia/internal/8003@172.xx.xx.3 Command Execute playback(tone_stream://%(200,0,500,600,700);loops=3)
  924. EXECUTE sofia/internal/8003@172.xx.xx.3 playback(tone_stream://%(200,0,500,600,700);loops=3)
  925. 2016-11-04 09:01:30.217462 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
  926. 2016-11-04 09:01:30.217462 [DEBUG] switch_core_media_bug.c:828 Attaching BUG to sofia/external/0833573222
  927. 2016-11-04 09:01:30.217462 [DEBUG] mod_callcenter.c:1776 Agent 8003 answered "Outbound Call" <D0820833573222> from queue agent8003@default (Recorded)
  928. 2016-11-04 09:01:30.217462 [DEBUG] switch_ivr_bridge.c:2010 (sofia/external/0833573222) State Change CS_EXECUTE -> CS_HIBERNATE
  929. 2016-11-04 09:01:30.217462 [DEBUG] switch_ivr_bridge.c:2012 (sofia/internal/8003@172.xx.xx.3) State Change CS_EXECUTE -> CS_HIBERNATE
  930. 2016-11-04 09:01:30.237495 [DEBUG] switch_ivr_play_say.c:1910 done playing file tone_stream://%(200,0,500,600,700);loops=3
  931. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_async.c:1496 No silence detection configured; assuming start of speech
  932. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_play_say.c:1910 done playing file local_stream://moh
  933. 2016-11-04 09:01:30.257460 [DEBUG] mod_callcenter.c:2887 Member Outbound Call <D0820833573222> is answered by an agent in queue agent8003@default
  934. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:609 (sofia/external/0833573222) State EXECUTE going to sleep
  935. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_HIBERNATE
  936. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:624 (sofia/external/0833573222) State HIBERNATE
  937. 2016-11-04 09:01:30.257460 [DEBUG] mod_sofia.c:179 sofia/external/0833573222 SOFIA HIBERNATE
  938. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_bridge.c:964 (sofia/external/0833573222) State Change CS_HIBERNATE -> CS_RESET
  939. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:624 (sofia/external/0833573222) State HIBERNATE going to sleep
  940. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_RESET
  941. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:605 (sofia/external/0833573222) State RESET
  942. 2016-11-04 09:01:30.257460 [DEBUG] mod_sofia.c:160 sofia/external/0833573222 SOFIA RESET
  943. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_bridge.c:949 sofia/external/0833573222 CUSTOM RESET
  944. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_bridge.c:956 (sofia/external/0833573222) State Change CS_RESET -> CS_SOFT_EXECUTE
  945. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:605 (sofia/external/0833573222) State RESET going to sleep
  946. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_SOFT_EXECUTE
  947. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:615 (sofia/external/0833573222) State SOFT_EXECUTE
  948. 2016-11-04 09:01:30.257460 [DEBUG] mod_sofia.c:634 SOFIA SOFT_EXECUTE
  949. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_bridge.c:974 sofia/external/0833573222 CUSTOM SOFT_EXECUTE
  950. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_play_say.c:1910 done playing file local_stream://moh
  951. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/8003@172.xx.xx.3) State EXECUTE going to sleep
  952. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_HIBERNATE
  953. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:624 (sofia/internal/8003@172.xx.xx.3) State HIBERNATE
  954. 2016-11-04 09:01:30.257460 [DEBUG] mod_sofia.c:179 sofia/internal/8003@172.xx.xx.3 SOFIA HIBERNATE
  955. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_bridge.c:964 (sofia/internal/8003@172.xx.xx.3) State Change CS_HIBERNATE -> CS_RESET
  956. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:624 (sofia/internal/8003@172.xx.xx.3) State HIBERNATE going to sleep
  957. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_RESET
  958. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:605 (sofia/internal/8003@172.xx.xx.3) State RESET
  959. 2016-11-04 09:01:30.257460 [DEBUG] mod_sofia.c:160 sofia/internal/8003@172.xx.xx.3 SOFIA RESET
  960. 2016-11-04 09:01:30.257460 [DEBUG] switch_ivr_bridge.c:949 sofia/internal/8003@172.xx.xx.3 CUSTOM RESET
  961. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:188 sofia/internal/8003@172.xx.xx.3 Standard RESET
  962. 2016-11-04 09:01:30.257460 [DEBUG] switch_core_state_machine.c:605 (sofia/internal/8003@172.xx.xx.3) State RESET going to sleep
  963. 2016-11-04 09:01:30.277462 [DEBUG] switch_ivr_bridge.c:1006 (sofia/internal/8003@172.xx.xx.3) State Change CS_RESET -> CS_SOFT_EXECUTE
  964. 2016-11-04 09:01:30.277462 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_SOFT_EXECUTE
  965. 2016-11-04 09:01:30.277462 [DEBUG] switch_core_state_machine.c:615 (sofia/internal/8003@172.xx.xx.3) State SOFT_EXECUTE
  966. 2016-11-04 09:01:30.277462 [DEBUG] mod_sofia.c:634 SOFIA SOFT_EXECUTE
  967. 2016-11-04 09:01:30.277462 [DEBUG] switch_ivr_bridge.c:974 sofia/internal/8003@172.xx.xx.3 CUSTOM SOFT_EXECUTE
  968. 2016-11-04 09:01:30.277462 [DEBUG] switch_core_state_machine.c:400 sofia/internal/8003@172.xx.xx.3 Standard SOFT_EXECUTE
  969. 2016-11-04 09:01:30.277462 [DEBUG] switch_core_state_machine.c:615 (sofia/internal/8003@172.xx.xx.3) State SOFT_EXECUTE going to sleep
  970. 2016-11-04 09:01:30.297486 [DEBUG] switch_ivr_bridge.c:1489 (sofia/internal/8003@172.xx.xx.3) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA
  971. 2016-11-04 09:01:30.297486 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_CONSUME_MEDIA
  972. 2016-11-04 09:01:30.297486 [DEBUG] switch_ivr_bridge.c:1594 (sofia/internal/8003@172.xx.xx.3) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  973. 2016-11-04 09:01:30.297486 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/8003@172.xx.xx.3) State CONSUME_MEDIA
  974. 2016-11-04 09:01:30.297486 [DEBUG] switch_ivr_bridge.c:913 sofia/internal/8003@172.xx.xx.3 CUSTOM HOLD
  975. 2016-11-04 09:01:30.297486 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/8003@172.xx.xx.3) State CONSUME_MEDIA going to sleep
  976. 2016-11-04 09:01:30.297486 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_EXCHANGE_MEDIA
  977. 2016-11-04 09:01:30.297486 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/8003@172.xx.xx.3) State EXCHANGE_MEDIA
  978. 2016-11-04 09:01:30.297486 [DEBUG] mod_sofia.c:628 SOFIA EXCHANGE_MEDIA
  979. 2016-11-04 09:01:30.297486 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc18059128 0x7efc18059128
  980. 2016-11-04 09:01:30.297486 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc18059128 0x7efc18059128
  981. 2016-11-04 09:01:30.297486 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc18059128 0x7efc18059130
  982. 2016-11-04 09:01:30.297486 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc18059128 0x7efc18059130
  983. 2016-11-04 09:01:30.297486 [DEBUG] switch_core_io.c:1448 Engaging Write Buffer at 960 bytes to accommodate 320->960
  984. send 987 bytes to udp/[172.xx.xx.61]:5060 at 09:01:30.321326:
  985.    ------------------------------------------------------------------------
  986.   INFO sip:0833573222@172.xx.xx.61:5060;transport=udp SIP/2.0
  987.    Via: SIP/2.0/UDP 172.xx.xx.85;rport;branch=z9hG4bKQK0HB6ZXK1N8N
  988.    Max-Forwards: 70
  989.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  990.    To: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  991.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  992.   CSeq: 98785380 INFO
  993.    Contact: <sip:gw+Enav8@172.xx.xx.85:5060;transport=udp;gw=Enav8>
  994.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  995.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  996.    Supported: timer, path, replaces
  997.    Proxy-Authorization: Digest username="Enav8Pre", realm="172.xx.xx.61", nonce="8c34bb6a-8fe3-4cd1-83c0-1008c0f9cf95", cnonce="V1r4Cxz/EjWAOwBQVosP+A", algorithm=MD5, uri="sip:0833573222@172.xx.xx.61:5060;transport=udp", response="3373021804fd53a94bf425cd71cbdf4e", qop=auth, nc=00000002
  998.    Content-Type: message/update_display
  999.    Content-Length: 0
  1000.    X-FS-Display-Name: 8003
  1001.    X-FS-Display-Number: 8003
  1002.    
  1003.    ------------------------------------------------------------------------
  1004. 2016-11-04 09:01:30.317459 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc1400cab8 0x7efc1400cab8
  1005. 2016-11-04 09:01:30.317459 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc1400cab8 0x7efc1400cab8
  1006. recv 433 bytes from udp/[172.xx.xx.61]:5060 at 09:01:30.337160:
  1007.    ------------------------------------------------------------------------
  1008.    SIP/2.0 200 OK
  1009.    Via: SIP/2.0/UDP 172.xx.xx.85;rport=5060;branch=z9hG4bKQK0HB6ZXK1N8N
  1010.    From: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  1011.    To: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  1012.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  1013.   CSeq: 98785380 INFO
  1014.    User-Agent: FS
  1015.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
  1016.    Supported: path, replaces
  1017.    Content-Length: 0
  1018.    
  1019.    ------------------------------------------------------------------------
  1020. 2016-11-04 09:01:30.377545 [DEBUG] switch_rtp.c:6670 Correct audio ip/port confirmed.
  1021. 2016-11-04 09:01:30.377545 [DEBUG] switch_core_io.c:448 Setting BUG Codec G729:18
  1022. 2016-11-04 09:01:30.377545 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc202c5060 0x7efc202c5068
  1023. 2016-11-04 09:01:30.377545 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc202c5060 0x7efc202c5068
  1024. 2016-11-04 09:01:30.377545 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc1400cad8 0x7efc1400cae0
  1025. 2016-11-04 09:01:30.377545 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc1400cad8 0x7efc1400cae0
  1026. 2016-11-04 09:01:30.377545 [DEBUG] switch_core_io.c:1448 Engaging Write Buffer at 320 bytes to accommodate 960->320
  1027. 2016-11-04 09:01:47.757473 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc1400cab8 0x7efc1400cab8
  1028. 2016-11-04 09:01:47.757473 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc1400cab8 0x7efc1400cab8
  1029. 2016-11-04 09:01:47.777485 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc1400cad8 0x7efc1400cae0
  1030. 2016-11-04 09:01:47.777485 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc1400cad8 0x7efc1400cae0
  1031. 2016-11-04 09:01:47.777485 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc1400cb18 0x7efc1400cb20
  1032. 2016-11-04 09:01:47.777485 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc1400cb18 0x7efc1400cb20
  1033. 2016-11-04 09:01:47.777485 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc1400caf8 0x7efc1400caf8
  1034. 2016-11-04 09:01:47.777485 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc1400caf8 0x7efc1400caf8
  1035. 2016-11-04 09:01:47.857472 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc1400caf8 0x7efc1400caf8
  1036. 2016-11-04 09:01:47.857472 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc1400caf8 0x7efc1400caf8
  1037. 2016-11-04 09:01:47.897526 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc1400cb18 0x7efc1400cb20
  1038. 2016-11-04 09:01:47.897526 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc1400cb18 0x7efc1400cb20
  1039. 2016-11-04 09:01:47.897526 [INFO] mod_com_g729.c:164 DECODER LICENSE ALLOCATED--->0x7efc1400cb58 0x7efc1400cb60
  1040. 2016-11-04 09:01:47.897526 [INFO] mod_com_g729.c:171 DECODER CREATED------------->0x7efc1400cb58 0x7efc1400cb60
  1041. 2016-11-04 09:01:47.897526 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc1400cb38 0x7efc1400cb38
  1042. 2016-11-04 09:01:47.897526 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc1400cb38 0x7efc1400cb38
  1043. 2016-11-04 09:01:48.017561 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc1400cb38 0x7efc1400cb38
  1044. 2016-11-04 09:01:48.017561 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc1400cb38 0x7efc1400cb38
  1045. 2016-11-04 09:01:48.017561 [INFO] mod_com_g729.c:126 ENCODER LICENSE ALLOCATED--->0x7efc1400cb78 0x7efc1400cb78
  1046. 2016-11-04 09:01:48.017561 [INFO] mod_com_g729.c:133 ENCODER CREATED------------->0x7efc1400cb78 0x7efc1400cb78
  1047. 2016-11-04 09:01:48.077532 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc1400cb58 0x7efc1400cb60
  1048. 2016-11-04 09:01:48.077532 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc1400cb58 0x7efc1400cb60
  1049. recv 586 bytes from udp/[172.xx.xx.61]:5060 at 09:01:48.158992:
  1050.    ------------------------------------------------------------------------
  1051.    BYE sip:gw+Enav8@172.xx.xx.85:5060;transport=udp;gw=Enav8 SIP/2.0
  1052.    Via: SIP/2.0/UDP 172.xx.xx.61;rport;branch=z9hG4bK2Xm3ZB7ZUvFBN
  1053.    Max-Forwards: 70
  1054.    From: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  1055.    To: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  1056.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  1057.    CSeq: 98785389 BYE
  1058.    User-Agent: FS
  1059.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
  1060.    Supported: path, replaces
  1061.    Reason: Q.850;cause=16
  1062.    Content-Length: 0
  1063.    P-RTP-Stat: PS=364,OS=21840,PR=293,OR=17580,PL=0,JI=0,LA=0,DU=18
  1064.    
  1065.    ------------------------------------------------------------------------
  1066. 2016-11-04 09:01:48.197470 [NOTICE] sofia.c:988 Hangup sofia/external/0833573222 [CS_SOFT_EXECUTE] [NORMAL_CLEARING]
  1067. send 485 bytes to udp/[172.xx.xx.61]:5060 at 09:01:48.202744:
  1068.    ------------------------------------------------------------------------
  1069.    SIP/2.0 200 OK
  1070.    Via: SIP/2.0/UDP 172.xx.xx.61;rport=5060;branch=z9hG4bK2Xm3ZB7ZUvFBN
  1071.    From: <sip:0833573222@172.xx.xx.61>;tag=KN6gDeXygQ4ea
  1072.    To: "" <sip:Enav8Pre@172.xx.xx.61>;tag=gm3j46j4pNp5g
  1073.    Call-ID: 575aae37-1cff-1235-3b80-0050568b0ff8
  1074.    CSeq: 98785389 BYE
  1075.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  1076.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1077.    Supported: timer, path, replaces
  1078.    Content-Length: 0
  1079.    
  1080.    ------------------------------------------------------------------------
  1081. 2016-11-04 09:01:48.197470 [DEBUG] switch_ivr_bridge.c:780 BRIDGE THREAD DONE [sofia/external/0833573222]
  1082. 2016-11-04 09:01:48.197470 [DEBUG] switch_ivr_bridge.c:701 sofia/external/0833573222 ending bridge by request from write function
  1083. 2016-11-04 09:01:48.197470 [DEBUG] switch_ivr_bridge.c:780 BRIDGE THREAD DONE [sofia/internal/8003@172.xx.xx.3]
  1084. 2016-11-04 09:01:48.197470 [DEBUG] switch_ivr.c:2160 (sofia/internal/8003@172.xx.xx.3) State Change CS_EXCHANGE_MEDIA -> CS_ROUTING
  1085. 2016-11-04 09:01:48.197470 [NOTICE] switch_ivr.c:2167 Transfer sofia/internal/8003@172.xx.xx.3 to XML[4099@public]
  1086. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/8003@172.xx.xx.3) State EXCHANGE_MEDIA going to sleep
  1087. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_ROUTING
  1088. 2016-11-04 09:01:48.197470 [DEBUG] switch_channel.c:2249 (sofia/internal/8003@172.xx.xx.3) Callstate Change ACTIVE -> RINGING
  1089. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/8003@172.xx.xx.3) State ROUTING
  1090. 2016-11-04 09:01:48.197470 [DEBUG] mod_sofia.c:142 sofia/internal/8003@172.xx.xx.3 SOFIA ROUTING
  1091. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:236 sofia/internal/8003@172.xx.xx.3 Standard ROUTING
  1092. 2016-11-04 09:01:48.197470 [INFO] mod_dialplan_xml.c:637 Processing Outbound Call <D0820833573222>->4099 in context public
  1093. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->unloop] continue=false
  1094. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  1095. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  1096. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->group_dial_huntgroup] continue=false
  1097. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [group_dial_huntgroup] destination_number(4099) =~ /^0870980891$/ break=on-false
  1098. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->group_dial_did_to_local] continue=false
  1099. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [group_dial_did_to_local] destination_number(4099) =~ /^0870980892$/ break=on-false
  1100. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->outside_call] continue=true
  1101. Dialplan: sofia/internal/8003@172.xx.xx.3 Absolute Condition [outside_call]
  1102. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(outside_call=true)
  1103. Dialplan: sofia/internal/8003@172.xx.xx.3 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  1104. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->call_debug] continue=true
  1105. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  1106. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_extensions] continue=false
  1107. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_extensions] destination_number(4099) =~ /^(10[01][0-9])$/ break=on-false
  1108. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_did] continue=false
  1109. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_did] destination_number(4099) =~ /^(5551212)$/ break=on-false
  1110. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->public_did] continue=false
  1111. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [public_did] destination_number(4099) =~ /^(0870980892)$/ break=on-false
  1112. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->nb_conferences] continue=false
  1113. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [nb_conferences] destination_number(4099) =~ /^0870980890$/ break=on-false
  1114. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->nb_conferences] continue=false
  1115. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [nb_conferences] destination_number(4099) =~ /^0870980893$/ break=on-false
  1116. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->conf11001] continue=false
  1117. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (FAIL) [conf11001] destination_number(4099) =~ /^11001$/ break=on-false
  1118. Dialplan: sofia/internal/8003@172.xx.xx.3 parsing [public->UNKNOWN] continue=false
  1119. Dialplan: sofia/internal/8003@172.xx.xx.3 Regex (PASS) [UNKNOWN] destination_number(4099) =~ /^(4099)$/ break=on-false
  1120. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(transfer_after_bridge=4099)
  1121. Dialplan: sofia/internal/8003@172.xx.xx.3 Action sleep(300)
  1122. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set uuid ${caller_id_number} '${uuid}')})
  1123. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set type ${caller_id_number} 'uuid-standby')})
  1124. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set status ${caller_id_number} 'Available')})
  1125. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(res=${callcenter_config(agent set state ${caller_id_number} 'idle')})
  1126. Dialplan: sofia/internal/8003@172.xx.xx.3 Action set(cc_warning_tone=tone_stream://%(200,0,500,600,700);loops=3)
  1127. Dialplan: sofia/internal/8003@172.xx.xx.3 Action answer()
  1128. Dialplan: sofia/internal/8003@172.xx.xx.3 Action playback(local_stream://moh)
  1129. 2016-11-04 09:01:48.197470 [DEBUG] switch_ivr_bridge.c:1695 sofia/external/0833573222 skip receive message [UNBRIDGE] (channel is hungup already)
  1130. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:615 (sofia/external/0833573222) State SOFT_EXECUTE going to sleep
  1131. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_HANGUP
  1132. 2016-11-04 09:01:48.197470 [DEBUG] switch_ivr_async.c:1312 Stop recording file /usr/local/freeswitch/recordings/2016-11-04-09-01-30.ANSWEREDCALL.D0820833573222.7f749ef1-ec7d-40f9-b17f-ef1883f6f7db.wav
  1133. 2016-11-04 09:01:48.197470 [INFO] switch_channel.c:3127 sofia/internal/8003@172.xx.xx.3 Flipping CID from "Outbound Call" <D0820833573222> to "8003" <8003>
  1134. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/8003@172.xx.xx.3) State Change CS_ROUTING -> CS_EXECUTE
  1135. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/8003@172.xx.xx.3) State ROUTING going to sleep
  1136. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_EXECUTE
  1137. 2016-11-04 09:01:48.197470 [DEBUG] switch_channel.c:2251 (sofia/internal/8003@172.xx.xx.3) Callstate Change RINGING -> ACTIVE
  1138. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/8003@172.xx.xx.3) State EXECUTE
  1139. 2016-11-04 09:01:48.197470 [DEBUG] mod_sofia.c:197 sofia/internal/8003@172.xx.xx.3 SOFIA EXECUTE
  1140. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:328 sofia/internal/8003@172.xx.xx.3 Standard EXECUTE
  1141. EXECUTE sofia/internal/8003@172.xx.xx.3 set(outside_call=true)
  1142. 2016-11-04 09:01:48.197470 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [outside_call]=[true]
  1143. EXECUTE sofia/internal/8003@172.xx.xx.3 export(RFC2822_DATE=Fri, 04 Nov 2016 09:01:48 +0200)
  1144. 2016-11-04 09:01:48.197470 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 04 Nov 2016 09:01:48 +0200]
  1145. EXECUTE sofia/internal/8003@172.xx.xx.3 set(transfer_after_bridge=4099)
  1146. 2016-11-04 09:01:48.197470 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [transfer_after_bridge]=[4099]
  1147. EXECUTE sofia/internal/8003@172.xx.xx.3 sleep(300)
  1148. 2016-11-04 09:01:48.197470 [DEBUG] switch_ivr_async.c:1376 Channel is hung up
  1149. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_media_bug.c:1120 Removing BUG from sofia/external/0833573222
  1150. 2016-11-04 09:01:48.197470 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc202c5060 0x7efc202c5068
  1151. 2016-11-04 09:01:48.197470 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc202c5060 0x7efc202c5068
  1152. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:809 (sofia/external/0833573222) Callstate Change ACTIVE -> HANGUP
  1153. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:811 (sofia/external/0833573222) State HANGUP
  1154. 2016-11-04 09:01:48.197470 [DEBUG] mod_sofia.c:437 Channel sofia/external/0833573222 hanging up, cause: NORMAL_CLEARING
  1155. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:60 sofia/external/0833573222 Standard HANGUP, cause: NORMAL_CLEARING
  1156. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:811 (sofia/external/0833573222) State HANGUP going to sleep
  1157. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:578 (sofia/external/0833573222) State Change CS_HANGUP -> CS_REPORTING
  1158. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:543 (sofia/external/0833573222) Running State Change CS_REPORTING
  1159. 2016-11-04 09:01:48.197470 [DEBUG] switch_core_state_machine.c:897 (sofia/external/0833573222) State REPORTING
  1160. 2016-11-04 09:01:48.197470 [DEBUG] mod_odbc_cdr.c:309 sql INSERT INTO cdr_table_both (start_stamp, CallId, TEST_id, end_stamp) VALUES ('2016-11-04 09:01:24', '7f749ef1-ec7d-40f9-b17f-ef1883f6f7db', 'Enav8Pre@172.xx.xx.61', '2016-11-04 09:01:48')
  1161. 2016-11-04 09:01:48.217481 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = Waiting
  1162. 2016-11-04 09:01:48.417470 [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 09:01:24', '0833573222', '7f749ef1-ec7d-40f9-b17f-ef1883f6f7db', 'ANSWER', '7f749ef1-ec7d-40f9-b17f-ef1883f6f7db', 'Enav8Pre', 'G729', 'callcenter', '172.xx.xx.61', '575aae37-1cff-1235-3b80-0050568b0ff8', '7f749ef1-ec7d-40f9-b17f-ef1883f6f7db', '2016-11-04 09:01:48')
  1163. 2016-11-04 09:01:48.497495 [DEBUG] switch_core_state_machine.c:174 sofia/external/0833573222 Standard REPORTING, cause: NORMAL_CLEARING
  1164. 2016-11-04 09:01:48.497495 [DEBUG] switch_core_state_machine.c:897 (sofia/external/0833573222) State REPORTING going to sleep
  1165. 2016-11-04 09:01:48.497495 [DEBUG] switch_core_state_machine.c:569 (sofia/external/0833573222) State Change CS_REPORTING -> CS_DESTROY
  1166. 2016-11-04 09:01:48.497495 [DEBUG] switch_core_session.c:1646 Session 101 (sofia/external/0833573222) Locked, Waiting on external entities
  1167. 2016-11-04 09:01:48.497495 [NOTICE] switch_core_session.c:1664 Session 101 (sofia/external/0833573222) Ended
  1168. 2016-11-04 09:01:48.497495 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/0833573222 [CS_DESTROY]
  1169. 2016-11-04 09:01:48.497495 [DEBUG] switch_core_state_machine.c:700 (sofia/external/0833573222) Running State Change CS_DESTROY
  1170. 2016-11-04 09:01:48.497495 [DEBUG] switch_core_state_machine.c:710 (sofia/external/0833573222) State DESTROY
  1171. 2016-11-04 09:01:48.497495 [DEBUG] mod_sofia.c:342 sofia/external/0833573222 SOFIA DESTROY
  1172. 2016-11-04 09:01:48.497495 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc1400cb78 0x7efc1400cb78
  1173. 2016-11-04 09:01:48.497495 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc1400cb78 0x7efc1400cb78
  1174. 2016-11-04 09:01:48.497495 [DEBUG] switch_core_state_machine.c:181 sofia/external/0833573222 Standard DESTROY
  1175. 2016-11-04 09:01:48.497495 [DEBUG] switch_core_state_machine.c:710 (sofia/external/0833573222) State DESTROY going to sleep
  1176. 2016-11-04 09:01:48.497495 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set uuid = ffefcfb3-be10-472d-8469-40692b7457da
  1177. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  1178. )
  1179. 2016-11-04 09:01:48.497495 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  1180. ]
  1181. 2016-11-04 09:01:48.497495 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set type = uuid-standby
  1182. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  1183. )
  1184. 2016-11-04 09:01:48.497495 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  1185. ]
  1186. 2016-11-04 09:01:48.497495 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set status = Available
  1187. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  1188. )
  1189. 2016-11-04 09:01:48.497495 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  1190. ]
  1191. 2016-11-04 09:01:48.497495 [DEBUG] mod_callcenter.c:1115 Updated Agent 8003 set state = idle
  1192. EXECUTE sofia/internal/8003@172.xx.xx.3 set(res=+OK
  1193. )
  1194. 2016-11-04 09:01:48.497495 [DEBUG] mod_dptools.c:1519 SET sofia/internal/8003@172.xx.xx.3 [res]=[+OK
  1195. ]
  1196. EXECUTE sofia/internal/8003@172.xx.xx.3 set(cc_warning_tone=tone_stream://%(200,0,500,600,700);loops=3)
  1197. 2016-11-04 09:01:48.497495 [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]
  1198. EXECUTE sofia/internal/8003@172.xx.xx.3 answer()
  1199. EXECUTE sofia/internal/8003@172.xx.xx.3 playback(local_stream://moh)
  1200. 2016-11-04 09:01:48.497495 [DEBUG] mod_local_stream.c:843 Opening Stream [moh/8000] 8000hz
  1201. 2016-11-04 09:01:48.497495 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
  1202. recv 512 bytes from udp/[172.xx.xx.3]:65516 at 09:01:54.037344:
  1203.    ------------------------------------------------------------------------
  1204.    BYE sip:4099@172.31.35.60:5060;transport=udp SIP/2.0
  1205.    Via: SIP/2.0/UDP 172.xx.xx.3:5060;branch=z9hG4bK109A291F7A
  1206.    From: <sip:8003@172.xx.xx.3>;tag=2E56AF64-2516
  1207.    To: <sip:4099@172.31.35.60>;tag=80540NSm23ZHN
  1208.    Date: Fri, 04 Nov 2016 06:53:00 GMT
  1209.    Call-ID: 2906F641-A19211E6-BC7FC7B1-C40D3FF0@172.xx.xx.3
  1210.    User-Agent: Cisco-SIPGateway/IOS-12.x
  1211.    Max-Forwards: 70
  1212.    Timestamp: 1478242428
  1213.    CSeq: 102 BYE
  1214.    Reason: Q.850;cause=16
  1215.    P-RTP-Stat: PS=2361,OS=47220,PR=2322,OR=46440,PL=0,JI=0,LA=0,DU=47
  1216.    Content-Length: 0
  1217.    
  1218.    ------------------------------------------------------------------------
  1219. send 379 bytes to udp/[172.xx.xx.3]:5060 at 09:01:54.037615:
  1220.    ------------------------------------------------------------------------
  1221.    SIP/2.0 100 Trying
  1222.    Via: SIP/2.0/UDP 172.xx.xx.3:5060;branch=z9hG4bK109A291F7A
  1223.    From: <sip:8003@172.xx.xx.3>;tag=2E56AF64-2516
  1224.    To: <sip:4099@172.31.35.60>;tag=80540NSm23ZHN
  1225.    Call-ID: 2906F641-A19211E6-BC7FC7B1-C40D3FF0@172.xx.xx.3
  1226.    CSeq: 102 BYE
  1227.    Timestamp: 1478242428 0.000032
  1228.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  1229.    Content-Length: 0
  1230.    
  1231.    ------------------------------------------------------------------------
  1232. 2016-11-04 09:01:54.037485 [NOTICE] sofia.c:988 Hangup sofia/internal/8003@172.xx.xx.3 [CS_EXECUTE] [NORMAL_CLEARING]
  1233. 2016-11-04 09:01:54.037485 [DEBUG] switch_ivr_play_say.c:1910 done playing file local_stream://moh
  1234. send 553 bytes to udp/[172.xx.xx.3]:5060 at 09:01:54.046712:
  1235.    ------------------------------------------------------------------------
  1236.    SIP/2.0 200 OK
  1237.    Via: SIP/2.0/UDP 172.xx.xx.3:5060;branch=z9hG4bK109A291F7A
  1238.    From: <sip:8003@172.xx.xx.3>;tag=2E56AF64-2516
  1239.    To: <sip:4099@172.31.35.60>;tag=80540NSm23ZHN
  1240.    Call-ID: 2906F641-A19211E6-BC7FC7B1-C40D3FF0@172.xx.xx.3
  1241.    CSeq: 102 BYE
  1242.    User-Agent: FreeSWITCH-mod_sofia/1.6.7+git~20160401T134007Z~f0c3870be3~64bit
  1243.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1244.    Supported: timer, path, replaces
  1245.    Content-Length: 0
  1246.    P-RTP-Stat: PS=364,OS=21840,PR=293,OR=17580,PL=0,JI=0,LA=0,DU=18
  1247.    
  1248.    ------------------------------------------------------------------------
  1249. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_session.c:2796 sofia/internal/8003@172.xx.xx.3 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1250. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/8003@172.xx.xx.3) State EXECUTE going to sleep
  1251. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_HANGUP
  1252. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/8003@172.xx.xx.3) Callstate Change ACTIVE -> HANGUP
  1253. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/8003@172.xx.xx.3) State HANGUP
  1254. 2016-11-04 09:01:54.037485 [DEBUG] mod_sofia.c:431 sofia/internal/8003@172.xx.xx.3 Overriding SIP cause 480 with 200 from the other leg
  1255. 2016-11-04 09:01:54.037485 [DEBUG] mod_sofia.c:437 Channel sofia/internal/8003@172.xx.xx.3 hanging up, cause: NORMAL_CLEARING
  1256. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:60 sofia/internal/8003@172.xx.xx.3 Standard HANGUP, cause: NORMAL_CLEARING
  1257. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/8003@172.xx.xx.3) State HANGUP going to sleep
  1258. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/8003@172.xx.xx.3) State Change CS_HANGUP -> CS_REPORTING
  1259. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_REPORTING
  1260. 2016-11-04 09:01:54.037485 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/8003@172.xx.xx.3) State REPORTING
  1261. 2016-11-04 09:01:54.037485 [DEBUG] mod_odbc_cdr.c:309 sql INSERT INTO cdr_table_both (start_stamp, CallId, TEST_id, end_stamp) VALUES ('2016-11-04 09:01:06', 'ffefcfb3-be10-472d-8469-40692b7457da', '8003@172.xx.xx.3', '2016-11-04 09:01:54')
  1262. 2016-11-04 09:01:54.075486 [DEBUG] mod_odbc_cdr.c:309 sql INSERT INTO cdr_table_a_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 09:01:06', '4099', '4099', 'ffefcfb3-be10-472d-8469-40692b7457da', 'ANSWER', 'ffefcfb3-be10-472d-8469-40692b7457da', '8003', 'G729', 'playback', '172.31.35.60', '172.xx.xx.3', '172.xx.xx.3', '2906F641-A19211E6-BC7FC7B1-C40D3FF0@172.xx.xx.3', 'ffefcfb3-be10-472d-8469-40692b7457da', '2016-11-04 09:01:54')
  1263. 2016-11-04 09:01:54.075486 [DEBUG] switch_core_state_machine.c:174 sofia/internal/8003@172.xx.xx.3 Standard REPORTING, cause: NORMAL_CLEARING
  1264. 2016-11-04 09:01:54.075486 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/8003@172.xx.xx.3) State REPORTING going to sleep
  1265. 2016-11-04 09:01:54.075486 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/8003@172.xx.xx.3) State Change CS_REPORTING -> CS_DESTROY
  1266. 2016-11-04 09:01:54.075486 [DEBUG] switch_core_session.c:1646 Session 100 (sofia/internal/8003@172.xx.xx.3) Locked, Waiting on external entities
  1267. 2016-11-04 09:01:54.075486 [NOTICE] switch_core_session.c:1664 Session 100 (sofia/internal/8003@172.xx.xx.3) Ended
  1268. 2016-11-04 09:01:54.075486 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/8003@172.xx.xx.3 [CS_DESTROY]
  1269. 2016-11-04 09:01:54.075486 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/8003@172.xx.xx.3) Running State Change CS_DESTROY
  1270. 2016-11-04 09:01:54.075486 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/8003@172.xx.xx.3) State DESTROY
  1271. 2016-11-04 09:01:54.075486 [DEBUG] mod_sofia.c:342 sofia/internal/8003@172.xx.xx.3 SOFIA DESTROY
  1272. 2016-11-04 09:01:54.075486 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc18059128 0x7efc18059128
  1273. 2016-11-04 09:01:54.075486 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc18059128 0x7efc18059128
  1274. 2016-11-04 09:01:54.075486 [INFO] mod_com_g729.c:95 DECODER DESTROYED----------->0x7efc18059128 0x7efc18059130
  1275. 2016-11-04 09:01:54.075486 [INFO] mod_com_g729.c:98 DECODER LICENSE DEALLOCATED->0x7efc18059128 0x7efc18059130
  1276. 2016-11-04 09:01:54.075486 [INFO] mod_com_g729.c:84 ENCODER DESTROYED----------->0x7efc180591a8 0x7efc180591a8
  1277. 2016-11-04 09:01:54.075486 [INFO] mod_com_g729.c:87 ENCODER LICENSE DEALLOCATED->0x7efc180591a8 0x7efc180591a8
  1278. 2016-11-04 09:01:54.075486 [DEBUG] switch_core_state_machine.c:181 sofia/internal/8003@172.xx.xx.3 Standard DESTROY
  1279. 2016-11-04 09:01:54.075486 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/8003@172.xx.xx.3) State DESTROY going to sleep
  1280. freeswitch@GCNDialer>
  1281.