From Sweltering Guinea Pig, 1 Year ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: green; font-weight: bold;"> 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 8215408144
  2. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 268.5196
  3. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  4. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 1
  5. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
  6. 2018-12-13 14:23:19.855019 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  7. 2018-12-13 14:23:19.855019 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as  did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="3211177971" LIMIT 1
  8. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  9. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
  10. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 2
  11. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Code : ^.3211177971.*
  12. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Destination : 3211177971
  13. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
  14. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0
  15. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0.00000
  16. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Initial Increment : 0
  17. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Increment : 0
  18. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 5
  19. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
  20. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 100 minutes
  21. 2018-12-13 14:23:19.855019 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "5" OR id="5") AND status=0 AND deleted=0 AND (expiry >= '2018-12-13 13:24:22' OR expiry = '0000-00-00 00:00:00') limit 1
  22. 2018-12-13 14:23:19.855019 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  23. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  24. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
  25. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 0
  26. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Code : 3211177971
  27. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Destination : Local
  28. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0
  29. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0
  30. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0
  31. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Initial Increment : 60
  32. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Increment : 60
  33. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 5
  34. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
  35. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML:5
  36. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Userinfo XML:5
  37. 2018-12-13 14:23:19.855019 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML : 5
  38. 2018-12-13 14:23:19.855019 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLINBOUND] Caller ID Translation Starts
  39. 2018-12-13 14:23:19.855019 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLINBOUND] Caller ID Translation Ends
  40. 2018-12-13 14:23:19.855019 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  41. <document type="freeswitch/xml">
  42. <section name="dialplan" description="ASTPP Dialplan">
  43. <context name="default">
  44. <extension name="3211177971">
  45. <condition field="destination_number" expression="3211177971">
  46. <action application="set" data="effective_destination_number=3211177971"/>
  47. <action application="sched_hangup" data="+6000 normal_clearing"/>
  48. <action application="set" data="callstart=2018-12-13 13:24:22"/>
  49. <action application="set" data="hangup_after_bridge=true"/>
  50. <action application="set" data="continue_on_fail=!USER_BUSY"/>
  51. <action application="set" data="account_id=5"/>
  52. <action application="set" data="parent_id=0"/>
  53. <action application="set" data="entity_id=0"/>
  54. <action application="set" data="call_processed=internal"/>
  55. <action application="set" data="call_direction=inbound"/>
  56. <action application="set" data="accountname=EDGE"/>
  57. <action application="export" data="t38_passthru=true"/>
  58. <action application="set" data="fax_enable_t38=true"/>
  59. <action application="set" data="fax_enable_t38_request=true"/>
  60. <action application="set" data="origination_rates_did=ID:2|CODE:^.3211177971.*|DESTINATION:3211177971|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|ACCID:5"/>
  61. <action application="set" data="origination_rates=ID:0|CODE:3211177971|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|ACCID:5"/>
  62. <action application="set" data="original_caller_id_name=32490112449"/>
  63. <action application="set" data="original_caller_id_number=32490112449"/>
  64. <action application="set" data="effective_caller_id_name=32490112449"/>
  65. <action application="set" data="effective_caller_id_number=32490112449"/>
  66. <action application="set" data="receiver_accid=5"/>
  67. <action application="set" data="calltype=DIRECT-IP"/>
  68. <action application="bridge" data="[leg_timeout=0]sofia/${sofia_profile_name}/3211177971@109.68.167.132"/>
  69. </condition>
  70. </extension>
  71. </context>
  72. </section>
  73. </document>
  74. 2018-12-13 14:23:19.855019 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fbdcc0617e0 released.
  75. Dialplan: sofia/default/32490112449@109.68.167.132 parsing [default->3211177971] continue=false
  76. Dialplan: sofia/default/32490112449@109.68.167.132 Regex (PASS) [3211177971] destination_number(3211177971) =~ /3211177971/ break=on-false
  77. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(effective_destination_number=3211177971)
  78. Dialplan: sofia/default/32490112449@109.68.167.132 Action sched_hangup(+6000 normal_clearing)
  79. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(callstart=2018-12-13 13:24:22)
  80. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(hangup_after_bridge=true)
  81. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(continue_on_fail=!USER_BUSY)
  82. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(account_id=5)
  83. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(parent_id=0)
  84. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(entity_id=0)
  85. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(call_processed=internal)
  86. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(call_direction=inbound)
  87. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(accountname=EDGE)
  88. Dialplan: sofia/default/32490112449@109.68.167.132 Action export(t38_passthru=true)
  89. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(fax_enable_t38=true)
  90. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(fax_enable_t38_request=true)
  91. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(origination_rates_did=ID:2|CODE:^.3211177971.*|DESTINATION:3211177971|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|ACCID:5)
  92. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(origination_rates=ID:0|CODE:3211177971|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|ACCID:5)
  93. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(original_caller_id_name=32490112449)
  94. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(original_caller_id_number=32490112449)
  95. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(effective_caller_id_name=32490112449)
  96. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(effective_caller_id_number=32490112449)
  97. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(receiver_accid=5)
  98. Dialplan: sofia/default/32490112449@109.68.167.132 Action set(calltype=DIRECT-IP)
  99. Dialplan: sofia/default/32490112449@109.68.167.132 Action bridge([leg_timeout=0]sofia/${sofia_profile_name}/3211177971@109.68.167.132)
  100. 2018-12-13 14:23:19.855019 [DEBUG] switch_core_state_machine.c:286 (sofia/default/32490112449@109.68.167.132) State Change CS_ROUTING -> CS_EXECUTE
  101. 2018-12-13 14:23:19.855019 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@109.68.167.132) State ROUTING going to sleep
  102. 2018-12-13 14:23:19.855019 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_EXECUTE (Cur 31 Tot 3919)
  103. 2018-12-13 14:23:19.855019 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE
  104. 2018-12-13 14:23:19.855019 [DEBUG] mod_sofia.c:198 sofia/default/32490112449@109.68.167.132 SOFIA EXECUTE
  105. 2018-12-13 14:23:19.855019 [DEBUG] switch_core_state_machine.c:328 sofia/default/32490112449@109.68.167.132 Standard EXECUTE
  106. EXECUTE sofia/default/32490112449@109.68.167.132 set(effective_destination_number=3211177971)
  107. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [effective_destination_number]=[3211177971]
  108. EXECUTE sofia/default/32490112449@109.68.167.132 sched_hangup(+6000 normal_clearing)
  109. 2018-12-13 14:23:19.855019 [DEBUG] switch_scheduler.c:249 Added task 2989 switch_ivr_schedule_hangup (1155c53d-b450-4c82-ac7a-8f5a0b2ee02d) to run at 1544713399
  110. EXECUTE sofia/default/32490112449@109.68.167.132 set(callstart=2018-12-13 13:24:22)
  111. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [callstart]=[2018-12-13 13:24:22]
  112. EXECUTE sofia/default/32490112449@109.68.167.132 set(hangup_after_bridge=true)
  113. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [hangup_after_bridge]=[true]
  114. EXECUTE sofia/default/32490112449@109.68.167.132 set(continue_on_fail=!USER_BUSY)
  115. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [continue_on_fail]=[!USER_BUSY]
  116. EXECUTE sofia/default/32490112449@109.68.167.132 set(account_id=5)
  117. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [account_id]=[5]
  118. EXECUTE sofia/default/32490112449@109.68.167.132 set(parent_id=0)
  119. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [parent_id]=[0]
  120. EXECUTE sofia/default/32490112449@109.68.167.132 set(entity_id=0)
  121. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [entity_id]=[0]
  122. EXECUTE sofia/default/32490112449@109.68.167.132 set(call_processed=internal)
  123. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [call_processed]=[internal]
  124. EXECUTE sofia/default/32490112449@109.68.167.132 set(call_direction=inbound)
  125. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [call_direction]=[inbound]
  126. EXECUTE sofia/default/32490112449@109.68.167.132 set(accountname=EDGE)
  127. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [accountname]=[EDGE]
  128. EXECUTE sofia/default/32490112449@109.68.167.132 export(t38_passthru=true)
  129. 2018-12-13 14:23:19.855019 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [t38_passthru]=[true]
  130. EXECUTE sofia/default/32490112449@109.68.167.132 set(fax_enable_t38=true)
  131. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [fax_enable_t38]=[true]
  132. EXECUTE sofia/default/32490112449@109.68.167.132 set(fax_enable_t38_request=true)
  133. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [fax_enable_t38_request]=[true]
  134. EXECUTE sofia/default/32490112449@109.68.167.132 set(origination_rates_did=ID:2|CODE:^.3211177971.*|DESTINATION:3211177971|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|ACCID:5)
  135. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [origination_rates_did]=[ID:2|CODE:^.3211177971.*|DESTINATION:3211177971|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|ACCID:5]
  136. EXECUTE sofia/default/32490112449@109.68.167.132 set(origination_rates=ID:0|CODE:3211177971|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|ACCID:5)
  137. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [origination_rates]=[ID:0|CODE:3211177971|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|ACCID:5]
  138. EXECUTE sofia/default/32490112449@109.68.167.132 set(original_caller_id_name=32490112449)
  139. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [original_caller_id_name]=[32490112449]
  140. EXECUTE sofia/default/32490112449@109.68.167.132 set(original_caller_id_number=32490112449)
  141. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [original_caller_id_number]=[32490112449]
  142. EXECUTE sofia/default/32490112449@109.68.167.132 set(effective_caller_id_name=32490112449)
  143. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [effective_caller_id_name]=[32490112449]
  144. EXECUTE sofia/default/32490112449@109.68.167.132 set(effective_caller_id_number=32490112449)
  145. 2018-12-13 14:23:19.855019 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [effective_caller_id_number]=[32490112449]
  146. EXECUTE sofia/default/32490112449@109.68.167.132 set(receiver_accid=5)
  147. 2018-12-13 14:23:19.874999 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [receiver_accid]=[5]
  148. EXECUTE sofia/default/32490112449@109.68.167.132 set(calltype=DIRECT-IP)
  149. 2018-12-13 14:23:19.874999 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@109.68.167.132 [calltype]=[DIRECT-IP]
  150. EXECUTE sofia/default/32490112449@109.68.167.132 bridge([leg_timeout=0]sofia/default/3211177971@109.68.167.132)
  151. 2018-12-13 14:23:19.874999 [DEBUG] switch_channel.c:1250 sofia/default/32490112449@109.68.167.132 EXPORTING[export_vars] [t38_passthru]=[true] to event
  152. 2018-12-13 14:23:19.874999 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  153. 2018-12-13 14:23:19.874999 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  154. 2018-12-13 14:23:19.874999 [NOTICE] switch_channel.c:1104 New Channel sofia/default/3211177971@109.68.167.132 [b42492ee-1f98-45c8-b175-62e81acaa50d]
  155. 2018-12-13 14:23:19.874999 [DEBUG] mod_sofia.c:4819 (sofia/default/3211177971@109.68.167.132) State Change CS_NEW -> CS_INIT
  156. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_INIT (Cur 32 Tot 3920)
  157. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:627 (sofia/default/3211177971@109.68.167.132) State INIT
  158. 2018-12-13 14:23:19.874999 [DEBUG] mod_sofia.c:90 sofia/default/3211177971@109.68.167.132 SOFIA INIT
  159. 2018-12-13 14:23:19.874999 [DEBUG] sofia_glue.c:1295 sofia/default/3211177971@109.68.167.132 sending invite version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  160. Local SDP:
  161. v=0
  162. o=FreeSWITCH 1544684627 1544684628 IN IP4 109.68.167.132
  163. s=FreeSWITCH
  164. c=IN IP4 109.68.167.132
  165. t=0 0
  166. m=audio 22772 RTP/AVP 8 101
  167. a=rtpmap:8 PCMA/8000
  168. a=rtpmap:101 telephone-event/8000
  169. a=fmtp:101 0-16
  170. a=ptime:20
  171. a=sendrecv
  172.  
  173. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:40 sofia/default/3211177971@109.68.167.132 Standard INIT
  174. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:48 (sofia/default/3211177971@109.68.167.132) State Change CS_INIT -> CS_ROUTING
  175. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:627 (sofia/default/3211177971@109.68.167.132) State INIT going to sleep
  176. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_ROUTING (Cur 32 Tot 3920)
  177. 2018-12-13 14:23:19.874999 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [calling][0]
  178. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:643 (sofia/default/3211177971@109.68.167.132) State ROUTING
  179. 2018-12-13 14:23:19.874999 [DEBUG] mod_sofia.c:143 sofia/default/3211177971@109.68.167.132 SOFIA ROUTING
  180. 2018-12-13 14:23:19.874999 [DEBUG] switch_ivr_originate.c:67 (sofia/default/3211177971@109.68.167.132) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  181. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:643 (sofia/default/3211177971@109.68.167.132) State ROUTING going to sleep
  182. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_CONSUME_MEDIA (Cur 32 Tot 3920)
  183. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:662 (sofia/default/3211177971@109.68.167.132) State CONSUME_MEDIA
  184. 2018-12-13 14:23:19.874999 [CRIT] switch_core_session.c:2283 Throttle Error! 32
  185. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:662 (sofia/default/3211177971@109.68.167.132) State CONSUME_MEDIA going to sleep
  186. 2018-12-13 14:23:19.874999 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  187. 2018-12-13 14:23:19.874999 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  188. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 32 Tot 3920)
  189. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  190. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  191. 2018-12-13 14:23:19.874999 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  192. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  193. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  194. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  195. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 32 Tot 3920)
  196. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  197. 2018-12-13 14:23:19.874999 [INFO] mod_json_cdr.c:271 Process [b42492ee-1f98-45c8-b175-62e81acaa50d.cdr.json]
  198. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  199. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  200. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  201. 2018-12-13 14:23:19.874999 [DEBUG] switch_core_session.c:1665 Session 3920 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  202. 2018-12-13 14:23:19.895038 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  203. 2018-12-13 14:23:19.895038 [NOTICE] switch_core_session.c:1683 Session 3920 (sofia/default/3211177971@109.68.167.132) Ended
  204. 2018-12-13 14:23:19.895038 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  205. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 31 Tot 3920)
  206. 2018-12-13 14:23:19.895038 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  207. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  208. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  209. 2018-12-13 14:23:19.895038 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  210. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  211. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  212. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  213. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  214. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 31 Tot 3920)
  215. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  216. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  217. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  218. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  219. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  220. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  221. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  222. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  223. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 31 Tot 3920)
  224. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  225. 2018-12-13 14:23:19.895038 [DEBUG] sofia.c:6257 Remote Reason: 41
  226. 2018-12-13 14:23:19.895038 [INFO] mod_json_cdr.c:271 Process [1155c53d-b450-4c82-ac7a-8f5a0b2ee02d.cdr.json]
  227. 2018-12-13 14:23:19.895038 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  228. 2018-12-13 14:23:19.895038 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  229. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 31 Tot 3920)
  230. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  231. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  232. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  233. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  234. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  235. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  236. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 31 Tot 3920)
  237. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  238. 2018-12-13 14:23:19.895038 [INFO] mod_json_cdr.c:271 Process [55bc941f-8659-477f-830f-691ebe1ce3cb.cdr.json]
  239. 2018-12-13 14:23:19.895038 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  240. 2018-12-13 14:23:19.895038 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  241. 2018-12-13 14:23:19.895038 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  242. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  243. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  244. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 31 Tot 3920)
  245. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  246. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  247. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  248. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  249. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  250. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  251. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  252. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  253. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 31 Tot 3920)
  254. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  255. 2018-12-13 14:23:19.895038 [DEBUG] sofia.c:6257 Remote Reason: 41
  256. 2018-12-13 14:23:19.895038 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  257. 2018-12-13 14:23:19.895038 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  258. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 31 Tot 3920)
  259. 2018-12-13 14:23:19.895038 [INFO] mod_json_cdr.c:271 Process [5bc349c5-27bc-4241-9f49-c98e148a8712.cdr.json]
  260. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  261. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  262. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  263. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  264. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  265. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  266. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 31 Tot 3920)
  267. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  268. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  269. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  270. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  271. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  272. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  273. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_session.c:1665 Session 3918 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  274. 2018-12-13 14:23:19.895038 [NOTICE] switch_core_session.c:1683 Session 3918 (sofia/default/3211177971@109.68.167.132) Ended
  275. 2018-12-13 14:23:19.895038 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  276. 2018-12-13 14:23:19.895038 [INFO] mod_json_cdr.c:271 Process [0225eb46-a7e5-404e-83c0-98f0f6270f19.cdr.json]
  277. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  278. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 30 Tot 3920)
  279. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_session.c:1665 Session 3919 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  280. 2018-12-13 14:23:19.895038 [NOTICE] switch_core_session.c:1683 Session 3919 (sofia/default/32490112449@109.68.167.132) Ended
  281. 2018-12-13 14:23:19.895038 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  282. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  283. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 29 Tot 3920)
  284. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  285. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  286. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  287. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  288. 2018-12-13 14:23:19.895038 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  289. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  290. 2018-12-13 14:23:19.895038 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  291. 2018-12-13 14:23:19.915002 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  292. 2018-12-13 14:23:19.915002 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  293. 2018-12-13 14:23:19.915002 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  294. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  295. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  296. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 29 Tot 3920)
  297. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  298. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  299. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  300. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  301. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  302. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  303. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  304. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_session.c:1665 Session 3916 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  305. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1683 Session 3916 (sofia/default/3211177971@109.68.167.132) Ended
  306. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  307. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 28 Tot 3920)
  308. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  309. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  310. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  311. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  312. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  313. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  314. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  315. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  316. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 28 Tot 3920)
  317. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  318. 2018-12-13 14:23:19.915002 [DEBUG] sofia.c:6257 Remote Reason: 41
  319. 2018-12-13 14:23:19.915002 [INFO] mod_json_cdr.c:271 Process [86cc5a68-8bf0-413b-9e8b-96a6ae902602.cdr.json]
  320. 2018-12-13 14:23:19.915002 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  321. 2018-12-13 14:23:19.915002 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  322. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 28 Tot 3920)
  323. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  324. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  325. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  326. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  327. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  328. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  329. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 28 Tot 3920)
  330. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  331. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  332. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  333. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  334. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_session.c:1665 Session 3917 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  335. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1683 Session 3917 (sofia/default/32490112449@109.68.167.132) Ended
  336. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  337. 2018-12-13 14:23:19.915002 [INFO] mod_json_cdr.c:271 Process [ab28dab2-955f-4ed4-adcb-d2155b300009.cdr.json]
  338. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 27 Tot 3920)
  339. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  340. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  341. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  342. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  343. 2018-12-13 14:23:19.915002 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  344. 2018-12-13 14:23:19.915002 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  345. 2018-12-13 14:23:19.915002 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  346. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  347. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  348. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 27 Tot 3920)
  349. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  350. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  351. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  352. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  353. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  354. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  355. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  356. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  357. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 27 Tot 3920)
  358. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  359. 2018-12-13 14:23:19.915002 [DEBUG] sofia.c:6257 Remote Reason: 41
  360. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  361. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  362. 2018-12-13 14:23:19.915002 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  363. 2018-12-13 14:23:19.915002 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  364. 2018-12-13 14:23:19.915002 [INFO] mod_json_cdr.c:271 Process [4fab2a40-1fcd-49a4-8be5-40f46c1d6b80.cdr.json]
  365. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  366. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_session.c:1665 Session 3914 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  367. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 27 Tot 3920)
  368. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1683 Session 3914 (sofia/default/3211177971@109.68.167.132) Ended
  369. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  370. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 26 Tot 3920)
  371. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  372. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  373. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  374. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  375. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  376. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  377. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  378. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  379. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  380. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  381. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 26 Tot 3920)
  382. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  383. 2018-12-13 14:23:19.915002 [INFO] mod_json_cdr.c:271 Process [4849400c-fce4-4ad2-916b-6c8068a5fe7b.cdr.json]
  384. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  385. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  386. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  387. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_session.c:1665 Session 3915 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  388. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1683 Session 3915 (sofia/default/32490112449@109.68.167.132) Ended
  389. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  390. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 25 Tot 3920)
  391. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  392. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  393. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  394. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  395. 2018-12-13 14:23:19.915002 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  396. 2018-12-13 14:23:19.915002 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  397. 2018-12-13 14:23:19.915002 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  398. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  399. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  400. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 25 Tot 3920)
  401. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  402. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  403. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  404. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  405. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  406. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  407. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  408. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  409. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 25 Tot 3920)
  410. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  411. 2018-12-13 14:23:19.915002 [DEBUG] sofia.c:6257 Remote Reason: 41
  412. 2018-12-13 14:23:19.915002 [INFO] mod_json_cdr.c:271 Process [b9cce595-3fee-47d3-914e-30117275effa.cdr.json]
  413. 2018-12-13 14:23:19.915002 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  414. 2018-12-13 14:23:19.915002 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  415. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 25 Tot 3920)
  416. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  417. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  418. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  419. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  420. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  421. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  422. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 25 Tot 3920)
  423. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  424. 2018-12-13 14:23:19.915002 [INFO] mod_json_cdr.c:271 Process [c9be79ec-d207-435d-8521-32faf5d40b92.cdr.json]
  425. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  426. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  427. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  428. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_session.c:1665 Session 3912 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  429. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1683 Session 3912 (sofia/default/3211177971@109.68.167.132) Ended
  430. 2018-12-13 14:23:19.915002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  431. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 24 Tot 3920)
  432. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  433. 2018-12-13 14:23:19.915002 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  434. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  435. 2018-12-13 14:23:19.915002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  436. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  437. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  438. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  439. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_session.c:1665 Session 3913 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  440. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1683 Session 3913 (sofia/default/32490112449@109.68.167.132) Ended
  441. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  442. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 23 Tot 3920)
  443. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  444. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  445. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  446. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  447. 2018-12-13 14:23:19.935002 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  448. 2018-12-13 14:23:19.935002 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  449. 2018-12-13 14:23:19.935002 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  450. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  451. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  452. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 23 Tot 3920)
  453. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  454. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  455. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  456. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  457. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  458. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  459. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  460. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  461. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 23 Tot 3920)
  462. 2018-12-13 14:23:19.935002 [DEBUG] sofia.c:6257 Remote Reason: 41
  463. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  464. 2018-12-13 14:23:19.935002 [INFO] mod_json_cdr.c:271 Process [2db21c1c-caa0-4014-bcb6-4c31d3c0ddbe.cdr.json]
  465. 2018-12-13 14:23:19.935002 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  466. 2018-12-13 14:23:19.935002 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  467. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 23 Tot 3920)
  468. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  469. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  470. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  471. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  472. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  473. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  474. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 23 Tot 3920)
  475. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  476. 2018-12-13 14:23:19.935002 [INFO] mod_json_cdr.c:271 Process [6e8328b9-e05e-44b4-9462-c9bb73f27d7b.cdr.json]
  477. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  478. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  479. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  480. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_session.c:1665 Session 3910 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  481. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1683 Session 3910 (sofia/default/3211177971@109.68.167.132) Ended
  482. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  483. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 22 Tot 3920)
  484. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  485. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  486. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  487. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  488. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  489. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  490. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  491. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_session.c:1665 Session 3911 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  492. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1683 Session 3911 (sofia/default/32490112449@109.68.167.132) Ended
  493. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  494. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 21 Tot 3920)
  495. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  496. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  497. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  498. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  499. 2018-12-13 14:23:19.935002 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  500. 2018-12-13 14:23:19.935002 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  501. 2018-12-13 14:23:19.935002 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  502. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  503. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  504. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 21 Tot 3920)
  505. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  506. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  507. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  508. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  509. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  510. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  511. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  512. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  513. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 21 Tot 3920)
  514. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  515. 2018-12-13 14:23:19.935002 [DEBUG] sofia.c:6257 Remote Reason: 41
  516. 2018-12-13 14:23:19.935002 [INFO] mod_json_cdr.c:271 Process [51f7f579-7c9f-4270-9a6c-bba5570f4b36.cdr.json]
  517. 2018-12-13 14:23:19.935002 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  518. 2018-12-13 14:23:19.935002 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  519. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  520. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  521. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 21 Tot 3920)
  522. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  523. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_session.c:1665 Session 3908 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  524. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1683 Session 3908 (sofia/default/3211177971@109.68.167.132) Ended
  525. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  526. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  527. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 20 Tot 3920)
  528. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  529. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  530. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  531. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  532. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  533. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  534. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  535. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  536. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  537. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 20 Tot 3920)
  538. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  539. 2018-12-13 14:23:19.935002 [INFO] mod_json_cdr.c:271 Process [5c6f43bf-4728-435c-9399-a479f98e786b.cdr.json]
  540. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  541. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  542. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  543. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_session.c:1665 Session 3909 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  544. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1683 Session 3909 (sofia/default/32490112449@109.68.167.132) Ended
  545. 2018-12-13 14:23:19.935002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  546. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 19 Tot 3920)
  547. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  548. 2018-12-13 14:23:19.935002 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  549. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  550. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  551. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  552. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  553. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  554. 2018-12-13 14:23:19.935002 [DEBUG] switch_core_session.c:1665 Session 3906 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  555. 2018-12-13 14:23:19.955004 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  556. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1683 Session 3906 (sofia/default/3211177971@109.68.167.132) Ended
  557. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  558. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 18 Tot 3920)
  559. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  560. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  561. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  562. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  563. 2018-12-13 14:23:19.955004 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  564. 2018-12-13 14:23:19.955004 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  565. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  566. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  567. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 18 Tot 3920)
  568. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  569. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  570. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  571. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  572. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  573. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  574. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  575. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_session.c:1665 Session 3907 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  576. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1683 Session 3907 (sofia/default/32490112449@109.68.167.132) Ended
  577. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  578. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  579. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  580. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  581. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  582. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 17 Tot 3920)
  583. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 17 Tot 3920)
  584. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  585. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  586. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  587. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  588. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  589. 2018-12-13 14:23:19.955004 [DEBUG] sofia.c:6257 Remote Reason: 41
  590. 2018-12-13 14:23:19.955004 [INFO] mod_json_cdr.c:271 Process [b979dd9b-6d15-4990-bed7-0a2a2b014aaa.cdr.json]
  591. 2018-12-13 14:23:19.955004 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  592. 2018-12-13 14:23:19.955004 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  593. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 17 Tot 3920)
  594. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  595. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  596. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  597. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  598. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  599. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  600. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 17 Tot 3920)
  601. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  602. 2018-12-13 14:23:19.955004 [INFO] mod_json_cdr.c:271 Process [4cd2da34-8d15-42cb-90e4-ceeda0651c7a.cdr.json]
  603. 2018-12-13 14:23:19.955004 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  604. 2018-12-13 14:23:19.955004 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  605. 2018-12-13 14:23:19.955004 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  606. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  607. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  608. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 17 Tot 3920)
  609. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  610. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  611. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  612. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  613. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  614. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  615. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  616. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  617. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 17 Tot 3920)
  618. 2018-12-13 14:23:19.955004 [DEBUG] sofia.c:6257 Remote Reason: 41
  619. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  620. 2018-12-13 14:23:19.955004 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  621. 2018-12-13 14:23:19.955004 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  622. 2018-12-13 14:23:19.955004 [INFO] mod_json_cdr.c:271 Process [d6dda435-028b-4643-aa52-11b1b2287126.cdr.json]
  623. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 17 Tot 3920)
  624. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  625. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  626. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  627. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  628. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  629. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  630. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 17 Tot 3920)
  631. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  632. 2018-12-13 14:23:19.955004 [INFO] mod_json_cdr.c:271 Process [2b241875-796d-47ad-9a58-615dfa68f575.cdr.json]
  633. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  634. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  635. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  636. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_session.c:1665 Session 3904 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  637. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1683 Session 3904 (sofia/default/3211177971@109.68.167.132) Ended
  638. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  639. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 16 Tot 3920)
  640. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  641. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  642. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  643. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  644. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  645. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  646. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  647. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_session.c:1665 Session 3905 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  648. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1683 Session 3905 (sofia/default/32490112449@109.68.167.132) Ended
  649. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  650. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 15 Tot 3920)
  651. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  652. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  653. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  654. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  655. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  656. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  657. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  658. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_session.c:1665 Session 3902 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  659. 2018-12-13 14:23:19.955004 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  660. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1683 Session 3902 (sofia/default/3211177971@109.68.167.132) Ended
  661. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  662. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 14 Tot 3920)
  663. 2018-12-13 14:23:19.955004 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  664. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  665. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  666. 2018-12-13 14:23:19.955004 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  667. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  668. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  669. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  670. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  671. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 14 Tot 3920)
  672. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  673. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  674. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  675. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  676. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  677. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  678. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  679. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  680. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 14 Tot 3920)
  681. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  682. 2018-12-13 14:23:19.955004 [DEBUG] sofia.c:6257 Remote Reason: 41
  683. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  684. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  685. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  686. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_session.c:1665 Session 3903 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  687. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1683 Session 3903 (sofia/default/32490112449@109.68.167.132) Ended
  688. 2018-12-13 14:23:19.955004 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  689. 2018-12-13 14:23:19.955004 [INFO] mod_json_cdr.c:271 Process [2f1ec0a5-8052-4fd7-bf68-0014321c6a04.cdr.json]
  690. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 13 Tot 3920)
  691. 2018-12-13 14:23:19.955004 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  692. 2018-12-13 14:23:19.955004 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  693. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  694. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  695. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  696. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  697. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 13 Tot 3920)
  698. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  699. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  700. 2018-12-13 14:23:19.955004 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  701. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  702. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  703. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  704. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 13 Tot 3920)
  705. 2018-12-13 14:23:19.955004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  706. 2018-12-13 14:23:19.955004 [INFO] mod_json_cdr.c:271 Process [e5ab6e84-0385-465c-afd0-96115840621c.cdr.json]
  707. 2018-12-13 14:23:19.975014 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  708. 2018-12-13 14:23:19.975014 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  709. 2018-12-13 14:23:19.975014 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  710. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  711. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  712. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 13 Tot 3920)
  713. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  714. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  715. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  716. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  717. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  718. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  719. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  720. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  721. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 13 Tot 3920)
  722. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  723. 2018-12-13 14:23:19.975014 [DEBUG] sofia.c:6257 Remote Reason: 41
  724. 2018-12-13 14:23:19.975014 [INFO] mod_json_cdr.c:271 Process [42e42ff0-794c-4846-afb6-87bfaa3b614c.cdr.json]
  725. 2018-12-13 14:23:19.975014 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  726. 2018-12-13 14:23:19.975014 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  727. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 13 Tot 3920)
  728. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  729. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  730. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  731. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  732. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  733. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  734. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 13 Tot 3920)
  735. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  736. 2018-12-13 14:23:19.975014 [INFO] mod_json_cdr.c:271 Process [292d2299-82ea-442e-ba54-0e35b178f00c.cdr.json]
  737. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  738. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  739. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  740. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_session.c:1665 Session 3900 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  741. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1683 Session 3900 (sofia/default/3211177971@109.68.167.132) Ended
  742. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  743. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 12 Tot 3920)
  744. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  745. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  746. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  747. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  748. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  749. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  750. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  751. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_session.c:1665 Session 3901 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  752. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1683 Session 3901 (sofia/default/32490112449@109.68.167.132) Ended
  753. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  754. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 11 Tot 3920)
  755. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  756. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  757. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  758. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  759. 2018-12-13 14:23:19.975014 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  760. 2018-12-13 14:23:19.975014 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  761. 2018-12-13 14:23:19.975014 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  762. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  763. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  764. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 11 Tot 3920)
  765. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  766. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  767. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  768. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  769. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  770. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  771. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  772. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  773. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 11 Tot 3920)
  774. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  775. 2018-12-13 14:23:19.975014 [DEBUG] sofia.c:6257 Remote Reason: 41
  776. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  777. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  778. 2018-12-13 14:23:19.975014 [INFO] mod_json_cdr.c:271 Process [508ce523-1591-49cc-a1ac-954493cf6ab8.cdr.json]
  779. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  780. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_session.c:1665 Session 3898 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  781. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1683 Session 3898 (sofia/default/3211177971@109.68.167.132) Ended
  782. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  783. 2018-12-13 14:23:19.975014 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  784. 2018-12-13 14:23:19.975014 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  785. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 10 Tot 3920)
  786. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 10 Tot 3920)
  787. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  788. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  789. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  790. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  791. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  792. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  793. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  794. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  795. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  796. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  797. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 10 Tot 3920)
  798. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  799. 2018-12-13 14:23:19.975014 [INFO] mod_json_cdr.c:271 Process [ca544366-c54a-444b-b71f-88018be130d8.cdr.json]
  800. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  801. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  802. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  803. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_session.c:1665 Session 3899 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  804. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1683 Session 3899 (sofia/default/32490112449@109.68.167.132) Ended
  805. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  806. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 9 Tot 3920)
  807. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  808. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  809. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  810. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  811. 2018-12-13 14:23:19.975014 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  812. 2018-12-13 14:23:19.975014 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  813. 2018-12-13 14:23:19.975014 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  814. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  815. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  816. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 9 Tot 3920)
  817. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  818. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  819. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  820. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  821. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  822. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  823. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  824. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_session.c:1665 Session 3896 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  825. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1683 Session 3896 (sofia/default/3211177971@109.68.167.132) Ended
  826. 2018-12-13 14:23:19.975014 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  827. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 8 Tot 3920)
  828. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  829. 2018-12-13 14:23:19.975014 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  830. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  831. 2018-12-13 14:23:19.975014 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  832. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  833. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  834. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  835. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  836. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 8 Tot 3920)
  837. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  838. 2018-12-13 14:23:19.995010 [DEBUG] sofia.c:6257 Remote Reason: 41
  839. 2018-12-13 14:23:19.995010 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  840. 2018-12-13 14:23:19.995010 [INFO] mod_json_cdr.c:271 Process [c70dfed2-39ef-4b9e-b9ae-ec8ee9d7fdd9.cdr.json]
  841. 2018-12-13 14:23:19.995010 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  842. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 8 Tot 3920)
  843. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  844. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  845. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  846. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  847. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  848. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  849. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 8 Tot 3920)
  850. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  851. 2018-12-13 14:23:19.995010 [INFO] mod_json_cdr.c:271 Process [51ee9169-c3bf-4321-a3e3-80de4c5df675.cdr.json]
  852. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  853. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  854. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  855. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_session.c:1665 Session 3897 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  856. 2018-12-13 14:23:19.995010 [NOTICE] switch_core_session.c:1683 Session 3897 (sofia/default/32490112449@109.68.167.132) Ended
  857. 2018-12-13 14:23:19.995010 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  858. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 7 Tot 3920)
  859. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  860. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  861. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  862. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  863. 2018-12-13 14:23:19.995010 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  864. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  865. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  866. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  867. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_session.c:1665 Session 3894 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  868. 2018-12-13 14:23:19.995010 [NOTICE] switch_core_session.c:1683 Session 3894 (sofia/default/3211177971@109.68.167.132) Ended
  869. 2018-12-13 14:23:19.995010 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  870. 2018-12-13 14:23:19.995010 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  871. 2018-12-13 14:23:19.995010 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  872. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 6 Tot 3920)
  873. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  874. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  875. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  876. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  877. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  878. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  879. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 6 Tot 3920)
  880. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  881. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  882. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  883. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  884. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  885. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  886. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  887. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  888. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 6 Tot 3920)
  889. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  890. 2018-12-13 14:23:19.995010 [DEBUG] sofia.c:6257 Remote Reason: 41
  891. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  892. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  893. 2018-12-13 14:23:19.995010 [INFO] mod_json_cdr.c:271 Process [fbaa46e5-2dfa-47e2-988d-483f9b22fa15.cdr.json]
  894. 2018-12-13 14:23:19.995010 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  895. 2018-12-13 14:23:19.995010 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  896. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 6 Tot 3920)
  897. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  898. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_session.c:1665 Session 3895 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  899. 2018-12-13 14:23:19.995010 [NOTICE] switch_core_session.c:1683 Session 3895 (sofia/default/32490112449@109.68.167.132) Ended
  900. 2018-12-13 14:23:19.995010 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  901. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  902. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  903. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 5 Tot 3920)
  904. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  905. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  906. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  907. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  908. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  909. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  910. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  911. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  912. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 5 Tot 3920)
  913. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  914. 2018-12-13 14:23:19.995010 [INFO] mod_json_cdr.c:271 Process [483a23fe-3b87-4ed4-848f-a663c37ee4ae.cdr.json]
  915. 2018-12-13 14:23:19.995010 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  916. 2018-12-13 14:23:19.995010 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  917. 2018-12-13 14:23:19.995010 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@109.68.167.132 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  918. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@109.68.167.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  919. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@109.68.167.132) State EXECUTE going to sleep
  920. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_HANGUP (Cur 5 Tot 3920)
  921. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@109.68.167.132) Callstate Change RINGING -> HANGUP
  922. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP
  923. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@109.68.167.132 Overriding SIP cause 503 with 503 from the other leg
  924. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  925. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  926. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  927. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@109.68.167.132) State HANGUP going to sleep
  928. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  929. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@109.68.167.132) Running State Change CS_REPORTING (Cur 5 Tot 3920)
  930. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  931. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  932. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING
  933. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  934. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_session.c:1665 Session 3892 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  935. 2018-12-13 14:23:19.995010 [NOTICE] switch_core_session.c:1683 Session 3892 (sofia/default/3211177971@109.68.167.132) Ended
  936. 2018-12-13 14:23:19.995010 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  937. 2018-12-13 14:23:19.995010 [DEBUG] sofia.c:6257 Remote Reason: 41
  938. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 4 Tot 3920)
  939. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  940. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  941. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  942. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  943. 2018-12-13 14:23:19.995010 [INFO] mod_json_cdr.c:271 Process [5b92f1e5-1a48-41bd-8086-35caac3c5d53.cdr.json]
  944. 2018-12-13 14:23:19.995010 [DEBUG] sofia.c:7084 Channel sofia/default/3211177971@109.68.167.132 entering state [terminated][503]
  945. 2018-12-13 14:23:19.995010 [NOTICE] sofia.c:8273 Hangup sofia/default/3211177971@109.68.167.132 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  946. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_HANGUP (Cur 4 Tot 3920)
  947. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3211177971@109.68.167.132) Callstate Change DOWN -> HANGUP
  948. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP
  949. 2018-12-13 14:23:19.995010 [DEBUG] mod_sofia.c:438 Channel sofia/default/3211177971@109.68.167.132 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  950. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:60 sofia/default/3211177971@109.68.167.132 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  951. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:852 (sofia/default/3211177971@109.68.167.132) State HANGUP going to sleep
  952. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:619 (sofia/default/3211177971@109.68.167.132) State Change CS_HANGUP -> CS_REPORTING
  953. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:584 (sofia/default/3211177971@109.68.167.132) Running State Change CS_REPORTING (Cur 4 Tot 3920)
  954. 2018-12-13 14:23:19.995010 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING
  955. 2018-12-13 14:23:19.995010 [INFO] mod_json_cdr.c:271 Process [20fe56d8-c839-4ac6-9918-3f10b2a0e77f.cdr.json]
  956. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  957. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  958. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  959. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_session.c:1665 Session 3893 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  960. 2018-12-13 14:23:20.014999 [NOTICE] switch_core_session.c:1683 Session 3893 (sofia/default/32490112449@109.68.167.132) Ended
  961. 2018-12-13 14:23:20.014999 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  962. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 3 Tot 3920)
  963. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  964. 2018-12-13 14:23:20.014999 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  965. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  966. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  967. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:174 sofia/default/3211177971@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  968. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:938 (sofia/default/3211177971@109.68.167.132) State REPORTING going to sleep
  969. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:610 (sofia/default/3211177971@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  970. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_session.c:1665 Session 3890 (sofia/default/3211177971@109.68.167.132) Locked, Waiting on external entities
  971. 2018-12-13 14:23:20.014999 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  972. 2018-12-13 14:23:20.014999 [NOTICE] switch_core_session.c:1683 Session 3890 (sofia/default/3211177971@109.68.167.132) Ended
  973. 2018-12-13 14:23:20.014999 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/3211177971@109.68.167.132 [CS_DESTROY]
  974. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:741 (sofia/default/3211177971@109.68.167.132) Running State Change CS_DESTROY (Cur 2 Tot 3920)
  975. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY
  976. 2018-12-13 14:23:20.014999 [DEBUG] mod_sofia.c:343 sofia/default/3211177971@109.68.167.132 SOFIA DESTROY
  977. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:181 sofia/default/3211177971@109.68.167.132 Standard DESTROY
  978. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:751 (sofia/default/3211177971@109.68.167.132) State DESTROY going to sleep
  979. 2018-12-13 14:23:20.014999 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  980. 2018-12-13 14:23:20.014999 [NOTICE] switch_channel.c:4844 Hangup sofia/default/32490112449@sip.voip.united-telecom.be [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
  981. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@sip.voip.united-telecom.be skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  982. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE going to sleep
  983. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_HANGUP (Cur 2 Tot 3920)
  984. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change RINGING -> HANGUP
  985. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP
  986. 2018-12-13 14:23:20.014999 [DEBUG] mod_sofia.c:432 sofia/default/32490112449@sip.voip.united-telecom.be Overriding SIP cause 503 with 503 from the other leg
  987. 2018-12-13 14:23:20.014999 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@sip.voip.united-telecom.be hanging up, cause: NORMAL_TEMPORARY_FAILURE
  988. 2018-12-13 14:23:20.014999 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 503
  989. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@sip.voip.united-telecom.be Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  990. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP going to sleep
  991. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_HANGUP -> CS_REPORTING
  992. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_REPORTING (Cur 2 Tot 3920)
  993. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING
  994. 2018-12-13 14:23:20.014999 [INFO] mod_json_cdr.c:271 Process [475f845d-4d9c-4541-888f-1ea0f971bbc0.cdr.json]
  995. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@109.68.167.132 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  996. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@109.68.167.132) State REPORTING going to sleep
  997. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@109.68.167.132) State Change CS_REPORTING -> CS_DESTROY
  998. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_session.c:1665 Session 3891 (sofia/default/32490112449@109.68.167.132) Locked, Waiting on external entities
  999. 2018-12-13 14:23:20.014999 [NOTICE] switch_core_session.c:1683 Session 3891 (sofia/default/32490112449@109.68.167.132) Ended
  1000. 2018-12-13 14:23:20.014999 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@109.68.167.132 [CS_DESTROY]
  1001. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@109.68.167.132) Running State Change CS_DESTROY (Cur 1 Tot 3920)
  1002. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY
  1003. 2018-12-13 14:23:20.014999 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@109.68.167.132 SOFIA DESTROY
  1004. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@109.68.167.132 Standard DESTROY
  1005. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@109.68.167.132) State DESTROY going to sleep
  1006. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@sip.voip.united-telecom.be Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  1007. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING going to sleep
  1008. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_REPORTING -> CS_DESTROY
  1009. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_session.c:1665 Session 3889 (sofia/default/32490112449@sip.voip.united-telecom.be) Locked, Waiting on external entities
  1010. 2018-12-13 14:23:20.014999 [NOTICE] switch_core_session.c:1683 Session 3889 (sofia/default/32490112449@sip.voip.united-telecom.be) Ended
  1011. 2018-12-13 14:23:20.014999 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@sip.voip.united-telecom.be [CS_DESTROY]
  1012. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_DESTROY (Cur 0 Tot 3920)
  1013. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY
  1014. 2018-12-13 14:23:20.014999 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA DESTROY
  1015. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@sip.voip.united-telecom.be Standard DESTROY
  1016. 2018-12-13 14:23:20.014999 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY going to sleep
  1017. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2974 switch_ivr_schedule_hangup (475f845d-4d9c-4541-888f-1ea0f971bbc0)
  1018. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2975 switch_ivr_schedule_hangup (5b92f1e5-1a48-41bd-8086-35caac3c5d53)
  1019. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2976 switch_ivr_schedule_hangup (fbaa46e5-2dfa-47e2-988d-483f9b22fa15)
  1020. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2977 switch_ivr_schedule_hangup (c70dfed2-39ef-4b9e-b9ae-ec8ee9d7fdd9)
  1021. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2978 switch_ivr_schedule_hangup (508ce523-1591-49cc-a1ac-954493cf6ab8)
  1022. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2979 switch_ivr_schedule_hangup (42e42ff0-794c-4846-afb6-87bfaa3b614c)
  1023. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2980 switch_ivr_schedule_hangup (2f1ec0a5-8052-4fd7-bf68-0014321c6a04)
  1024. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2981 switch_ivr_schedule_hangup (d6dda435-028b-4643-aa52-11b1b2287126)
  1025. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2982 switch_ivr_schedule_hangup (b979dd9b-6d15-4990-bed7-0a2a2b014aaa)
  1026. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2983 switch_ivr_schedule_hangup (51f7f579-7c9f-4270-9a6c-bba5570f4b36)
  1027. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2984 switch_ivr_schedule_hangup (2db21c1c-caa0-4014-bcb6-4c31d3c0ddbe)
  1028. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2985 switch_ivr_schedule_hangup (b9cce595-3fee-47d3-914e-30117275effa)
  1029. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2986 switch_ivr_schedule_hangup (4fab2a40-1fcd-49a4-8be5-40f46c1d6b80)
  1030. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2987 switch_ivr_schedule_hangup (86cc5a68-8bf0-413b-9e8b-96a6ae902602)
  1031. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2988 switch_ivr_schedule_hangup (5bc349c5-27bc-4241-9f49-c98e148a8712)
  1032. 2018-12-13 14:23:20.355007 [DEBUG] switch_scheduler.c:144 Deleting task 2989 switch_ivr_schedule_hangup (1155c53d-b450-4c82-ac7a-8f5a0b2ee02d)
  1033. 2018-12-13 14:23:20.435028 [CRIT] switch_time.c:1235 Over Session Rate of 30!