FreeSWITCH Pastebin

SIP -> DID -> IVR -> Ring group.

From Sahil , 3 Years ago, written in FreeSWITCH.
- go back
URL https://pastebin.freeswitch.org/view/2aaded1c
Embed
Viewing differences between and SIP -> DID -> IVR -> Ring group.
  1. span style="color: goldenrod; font-weight: bold;"> 2021-02-05 09:52:40.537764 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@hq.ubx.univasa.com) Running State Change CS_NEW (Cur 1 Tot 14988)
  2. 2021-02-05 09:52:40.537764 [DEBUG] sofia.c:9873 sofia/default/100@hq.ubx.univasa.com receiving invite from 117.247.91.162:42212 version: 1.6.20  64bit
  3. 2021-02-05 09:52:40.537764 [DEBUG] sofia.c:10044 IP 117.247.91.162 Rejected by acl "default". Falling back to Digest auth.
  4. 2021-02-05 09:52:40.537764 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [018887088@hq.ubx.univasa.com] from ip 117.247.91.162
  5. 2021-02-05 09:52:40.537764 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@hq.ubx.univasa.com) State NEW
  6. 2021-02-05 09:52:40.537764 [DEBUG] sofia.c:2334 detaching session e2aa51da-6797-11eb-b755-5b927fa9d4df
  7. 2021-02-05 09:52:41.317752 [DEBUG] sofia.c:2442 Re-attaching to session e2aa51da-6797-11eb-b755-5b927fa9d4df
  8. 2021-02-05 09:52:41.317752 [DEBUG] sofia.c:9873 sofia/default/100@hq.ubx.univasa.com receiving invite from 117.247.91.162:42212 version: 1.6.20  64bit
  9. 2021-02-05 09:52:41.317752 [DEBUG] sofia.c:10044 IP 117.247.91.162 Rejected by acl "default". Falling back to Digest auth.
  10. 2021-02-05 09:52:41.337754 [DEBUG] sofia.c:11168 Setting NAT mode based on via received
  11. 2021-02-05 09:52:41.337754 [DEBUG] sofia.c:7084 Channel sofia/default/100@hq.ubx.univasa.com entering state [received][100]
  12. 2021-02-05 09:52:41.337754 [DEBUG] sofia.c:7094 Remote SDP:
  13. v=0
  14. o=Z 0 0 IN IP4 117.247.91.162
  15. s=Z
  16. c=IN IP4 117.247.91.162
  17. t=0 0
  18. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  19. a=rtpmap:110 speex/8000
  20. a=rtpmap:98 iLBC/8000
  21. a=fmtp:98 mode=20
  22. a=rtpmap:101 telephone-event/8000
  23. a=fmtp:101 0-15
  24.  
  25. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  26. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  27. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  28. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  29. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  30. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:110:8000:20:0:1]/[G729:18:8000:20:8000:1]
  31. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  32. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  33. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  34. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  35. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  36. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  37. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  38. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  39. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  40. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  41. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [iLBC:98:8000:30:0:1]/[G729:18:8000:20:8000:1]
  42. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  43. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/100@hq.ubx.univasa.com PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  44. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_codec.c:111 sofia/default/100@hq.ubx.univasa.com Original read codec set to PCMA:8
  45. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  46. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_media.c:4767 sofia/default/100@hq.ubx.univasa.com Set 2833 dtmf send payload to 101 recv payload to 101
  47. 2021-02-05 09:52:41.337754 [DEBUG] sofia.c:7507 (sofia/default/100@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  48. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 1 Tot 14988)
  49. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_state_machine.c:627 (sofia/default/100@hq.ubx.univasa.com) State INIT
  50. 2021-02-05 09:52:41.337754 [DEBUG] mod_sofia.c:90 sofia/default/100@hq.ubx.univasa.com SOFIA INIT
  51. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_state_machine.c:40 sofia/default/100@hq.ubx.univasa.com Standard INIT
  52. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_state_machine.c:48 (sofia/default/100@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  53. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_state_machine.c:627 (sofia/default/100@hq.ubx.univasa.com) State INIT going to sleep
  54. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 1 Tot 14988)
  55. 2021-02-05 09:52:41.337754 [DEBUG] switch_channel.c:2249 (sofia/default/100@hq.ubx.univasa.com) Callstate Change DOWN -> RINGING
  56. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_state_machine.c:643 (sofia/default/100@hq.ubx.univasa.com) State ROUTING
  57. 2021-02-05 09:52:41.337754 [DEBUG] mod_sofia.c:143 sofia/default/100@hq.ubx.univasa.com SOFIA ROUTING
  58. 2021-02-05 09:52:41.337754 [DEBUG] switch_core_state_machine.c:236 sofia/default/100@hq.ubx.univasa.com Standard ROUTING
  59. 2021-02-05 09:52:41.337754 [INFO] mod_dialplan_xml.c:637 Processing 100 <100>->018887088 in context default
  60. 2021-02-05 09:52:41.357836 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0a040fad10 Connected.
  61. 2021-02-05 09:52:41.357836 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  62. 2021-02-05 09:52:41.357836 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  63. 2021-02-05 09:52:41.357836 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] blind_flag: 0
  64. 2021-02-05 09:52:41.357836 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 018887088
  65. 2021-02-05 09:52:41.357836 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '018887088' limit 1
  66. 2021-02-05 09:52:41.357836 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '018887088' AND status=0 limit 1
  67. 2021-02-05 09:52:41.357836 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = 'hq.ubx.univasa.com' limit 1
  68. 2021-02-05 09:52:41.357836 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Functions] [GET_queue_INFO] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '018887088' and account_id = '5' and status=0 LIMIT 1
  69. 2021-02-05 09:52:41.357836 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : 100 / 100
  70. 2021-02-05 09:52:41.357836 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887088" LIMIT 1
  71. 2021-02-05 09:52:41.357836 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : inbound
  72. 2021-02-05 09:52:41.357836 [INFO] switch_cpp.cpp:1365 [ASTPP] [Accountcode : 5169063157]
  73. 2021-02-05 09:52:41.357836 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "5169063157" AND deleted = 0 limit 1
  74. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="018887088" AND A.accountid = '5' limit 1
  75. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 018887088
  76. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887088" LIMIT 1
  77. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] New Call direction : inbound
  78. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="018887088" AND (accountid = 0 OR status = 1) LIMIT 1
  79. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
  80. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :5
  81. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^018887088.*' OR patterns = '^01888708.*' OR patterns = '^0188870.*' OR patterns = '^018887.*' OR patterns = '^01888.*' OR patterns = '^0188.*' OR patterns = '^018.*' OR patterns = '^01.*' OR patterns = '^0.*' OR patterns ='--') AND accountid = 5 ORDER BY LENGTH(PKGPTR.patterns) DESC
  82. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '018887088' OR number = '01888708' OR number = '0188870' OR number = '018887' OR number = '01888' OR number = '0188' OR number = '018' OR number = '01' OR number = '0' OR number ='--') OR (number = '018887088*' OR number = '01888708*' OR number = '0188870*' OR number = '018887*' OR number = '01888*' OR number = '0188*' OR number = '018*' OR number = '01*' OR number = '0*' OR number ='--') OR (number = '100' OR number = '10' OR number = '1' OR number ='--') OR (number = '100*' OR number = '10*' OR number = '1*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  83. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Call Barring Status : -1
  84. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '018887088' OR routing_prefix = '01888708' OR routing_prefix = '0188870' OR routing_prefix = '018887' OR routing_prefix = '01888' OR routing_prefix = '0188' OR routing_prefix = '018' OR routing_prefix = '01' OR routing_prefix = '0' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  85. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
  86. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 5
  87. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 5169063157
  88. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 0
  89. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  90. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 1
  91. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] CID Pool : 0
  92. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
  93. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  94. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] call_direction :inbound
  95. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887088" LIMIT 1
  96. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] call_direction:::::: inbound
  97. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
  98. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 1
  99. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
  100. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0
  101. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0.00000
  102. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] comment : 018887088
  103. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Country Id : 141
  104. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 5
  105. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
  106. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Your0 balance Accountid 5 !!!
  107. 2021-02-05 09:52:41.378449 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  108. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 100 minutes
  109. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] INB_FREE:TRUE
  110. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] free_inbound:1
  111. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 5 AND status=0 LIMIT 1
  112. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "5" AND deleted = 0 limit 1
  113. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML:5
  114. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Userinfo XML:5
  115. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML : 5
  116. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 5 limit 1
  117. 2021-02-05 09:52:41.378449 [WARNING] switch_cpp.cpp:1365 [ASTPP] [user_domain] user_domainhq.ubx.univasa.com
  118. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] maxlength::::::::: 100
  119. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 5 limit 1
  120. 2021-02-05 09:52:41.378449 [WARNING] switch_cpp.cpp:1365 [ASTPP] [user_domain] user_domainhq.ubx.univasa.com
  121. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_10
  122. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] custom_function_name::::::::::1343:::::::::::::::1
  123. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [PBX] call_type : 10
  124. 2021-02-05 09:52:41.378449 [INFO] switch_cpp.cpp:1365 [ASTPP] [PBX] call_type : IVR
  125. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Functions] [GET_IVR_INFO] Query :SELECT * from pbx_ivr_specification WHERE id = 1 and status=0 LIMIT 1
  126. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '' LIMIT 1
  127. 2021-02-05 09:52:41.378449 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  128. <document type="freeswitch/xml">
  129. <section name="dialplan" description="ASTPP Dialplan">
  130. <context name="default">
  131. <extension name="018887088">
  132. <condition field="destination_number" expression="018887088">
  133. <action application="set" data="effective_destination_number=018887088"/>
  134. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  135. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  136. <action application="set" data="callstart=2021-02-05 09:52:41"/>
  137. <action application="set" data="hangup_after_bridge=true"/>
  138. <action application="set" data="continue_on_fail=TRUE"/>
  139. <action application="set" data="account_id=5"/>
  140. <action application="set" data="parent_id=0"/>
  141. <action application="set" data="entity_id=0"/>
  142. <action application="set" data="call_processed=internal"/>
  143. <action application="set" data="call_direction=inbound"/>
  144. <action application="set" data="accountname=default"/>
  145. <action application="set" data="origination_rates_did=ID:1|CODE:^018887088.*|DESTINATION:018887088|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:5"/>
  146. <action application="set" data="origination_rates=0"/>
  147. <action application="set" data="original_caller_id_name=100"/>
  148. <action application="set" data="original_caller_id_number=100"/>
  149. <action application="limit" data="db 5169063157 user_5169063157 10 !SWITCH_CONGESTION"/>
  150. <action application="limit" data="hash CPS_5169063157 CPS_user_5169063157 100/1 !SWITCH_CONGESTION"/>
  151. <action application="limit" data="db 5 user_5 10 !SWITCH_CONGESTION"/>
  152. <action application="set" data="effective_caller_id_name=100"/>
  153. <action application="set" data="effective_caller_id_number=100"/>
  154. <action application="set" data="receiver_accid=5"/>
  155. <action application="export" data="domain_name=hq.ubx.univasa.com"/>
  156. <action application="export" data="sip_to_host=hq.ubx.univasa.com"/>
  157. <action application="limit" data="db 018887088 did_018887088 10 !SWITCH_CONGESTION"/>
  158. <action application="export" data="presence_data=x|||Ebunoluwa(5169063157)|||^018887088.* // 018887088 // 0.00000||||||DID|||117.247.91.162"/>
  159. <action application="export" data="call_type=10"/>
  160. <action application="set" data="did_calltype=10"/>
  161. <action application="set" data="did_extensions=1"/>
  162. <action application="set" data="ringback="/>
  163. <action application="set" data="module_name=pbx"/>
  164. <action application="set" data="sip_h_P-call_type=custom_forward"/>
  165. <action application="set" data="sip_h_P-Accountcode=5"/>
  166. <action application="set" data="domain_name=hq.ubx.univasa.com"/>
  167.     <action application="answer"/>
  168. <action application="ivr" data="HQ CC IVR0"/>
  169. <action application="lua" data="astpp/lib/pbx/astpp-failover_ivr_destination.lua"/>
  170. </condition>
  171. </extension>
  172. </context>
  173. </section>
  174. </document>
  175. 2021-02-05 09:52:41.378449 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0a040fad10 released.
  176. Dialplan: sofia/default/100@hq.ubx.univasa.com parsing [default->018887088] continue=false
  177. Dialplan: sofia/default/100@hq.ubx.univasa.com Regex (PASS) [018887088] destination_number(018887088) =~ /018887088/ break=on-false
  178. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(effective_destination_number=018887088)
  179. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(bridge_pre_execute_bleg_app=sched_hangup)
  180. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  181. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(callstart=2021-02-05 09:52:41)
  182. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(hangup_after_bridge=true)
  183. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(continue_on_fail=TRUE)
  184. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(account_id=5)
  185. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(parent_id=0)
  186. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(entity_id=0)
  187. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(call_processed=internal)
  188. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(call_direction=inbound)
  189. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(accountname=default)
  190. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(origination_rates_did=ID:1|CODE:^018887088.*|DESTINATION:018887088|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:5)
  191. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(origination_rates=0)
  192. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(original_caller_id_name=100)
  193. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(original_caller_id_number=100)
  194. Dialplan: sofia/default/100@hq.ubx.univasa.com Action limit(db 5169063157 user_5169063157 10 !SWITCH_CONGESTION)
  195. Dialplan: sofia/default/100@hq.ubx.univasa.com Action limit(hash CPS_5169063157 CPS_user_5169063157 100/1 !SWITCH_CONGESTION)
  196. Dialplan: sofia/default/100@hq.ubx.univasa.com Action limit(db 5 user_5 10 !SWITCH_CONGESTION)
  197. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(effective_caller_id_name=100)
  198. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(effective_caller_id_number=100)
  199. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(receiver_accid=5)
  200. Dialplan: sofia/default/100@hq.ubx.univasa.com Action export(domain_name=hq.ubx.univasa.com)
  201. Dialplan: sofia/default/100@hq.ubx.univasa.com Action export(sip_to_host=hq.ubx.univasa.com)
  202. Dialplan: sofia/default/100@hq.ubx.univasa.com Action limit(db 018887088 did_018887088 10 !SWITCH_CONGESTION)
  203. Dialplan: sofia/default/100@hq.ubx.univasa.com Action export(presence_data=x|||Ebunoluwa(5169063157)|||^018887088.* // 018887088 // 0.00000||||||DID|||117.247.91.162)
  204. Dialplan: sofia/default/100@hq.ubx.univasa.com Action export(call_type=10)
  205. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(did_calltype=10)
  206. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(did_extensions=1)
  207. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(ringback=)
  208. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(module_name=pbx)
  209. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(sip_h_P-call_type=custom_forward)
  210. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(sip_h_P-Accountcode=5)
  211. Dialplan: sofia/default/100@hq.ubx.univasa.com Action set(domain_name=hq.ubx.univasa.com)
  212. Dialplan: sofia/default/100@hq.ubx.univasa.com Action answer()
  213. Dialplan: sofia/default/100@hq.ubx.univasa.com Action ivr(HQ CC IVR0)
  214. Dialplan: sofia/default/100@hq.ubx.univasa.com Action lua(astpp/lib/pbx/astpp-failover_ivr_destination.lua)
  215. 2021-02-05 09:52:41.378449 [DEBUG] switch_core_state_machine.c:286 (sofia/default/100@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_EXECUTE
  216. 2021-02-05 09:52:41.378449 [DEBUG] switch_core_state_machine.c:643 (sofia/default/100@hq.ubx.univasa.com) State ROUTING going to sleep
  217. 2021-02-05 09:52:41.378449 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@hq.ubx.univasa.com) Running State Change CS_EXECUTE (Cur 1 Tot 14988)
  218. 2021-02-05 09:52:41.378449 [DEBUG] switch_core_state_machine.c:650 (sofia/default/100@hq.ubx.univasa.com) State EXECUTE
  219. 2021-02-05 09:52:41.378449 [DEBUG] mod_sofia.c:198 sofia/default/100@hq.ubx.univasa.com SOFIA EXECUTE
  220. 2021-02-05 09:52:41.378449 [DEBUG] switch_core_state_machine.c:328 sofia/default/100@hq.ubx.univasa.com Standard EXECUTE
  221. EXECUTE sofia/default/100@hq.ubx.univasa.com set(effective_destination_number=018887088)
  222. 2021-02-05 09:52:41.378449 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [effective_destination_number]=[018887088]
  223. EXECUTE sofia/default/100@hq.ubx.univasa.com set(bridge_pre_execute_bleg_app=sched_hangup)
  224. 2021-02-05 09:52:41.378449 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [bridge_pre_execute_bleg_app]=[sched_hangup]
  225. EXECUTE sofia/default/100@hq.ubx.univasa.com set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  226. 2021-02-05 09:52:41.378449 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  227. EXECUTE sofia/default/100@hq.ubx.univasa.com set(callstart=2021-02-05 09:52:41)
  228. 2021-02-05 09:52:41.378449 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [callstart]=[2021-02-05 09:52:41]
  229. EXECUTE sofia/default/100@hq.ubx.univasa.com set(hangup_after_bridge=true)
  230. 2021-02-05 09:52:41.378449 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [hangup_after_bridge]=[true]
  231. EXECUTE sofia/default/100@hq.ubx.univasa.com set(continue_on_fail=TRUE)
  232. 2021-02-05 09:52:41.378449 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [continue_on_fail]=[TRUE]
  233. EXECUTE sofia/default/100@hq.ubx.univasa.com set(account_id=5)
  234. 2021-02-05 09:52:41.378449 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [account_id]=[5]
  235. EXECUTE sofia/default/100@hq.ubx.univasa.com set(parent_id=0)
  236. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [parent_id]=[0]
  237. EXECUTE sofia/default/100@hq.ubx.univasa.com set(entity_id=0)
  238. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [entity_id]=[0]
  239. EXECUTE sofia/default/100@hq.ubx.univasa.com set(call_processed=internal)
  240. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [call_processed]=[internal]
  241. EXECUTE sofia/default/100@hq.ubx.univasa.com set(call_direction=inbound)
  242. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [call_direction]=[inbound]
  243. EXECUTE sofia/default/100@hq.ubx.univasa.com set(accountname=default)
  244. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [accountname]=[default]
  245. EXECUTE sofia/default/100@hq.ubx.univasa.com set(origination_rates_did=ID:1|CODE:^018887088.*|DESTINATION:018887088|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:5)
  246. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [origination_rates_did]=[ID:1|CODE:^018887088.*|DESTINATION:018887088|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:5]
  247. EXECUTE sofia/default/100@hq.ubx.univasa.com set(origination_rates=0)
  248. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [origination_rates]=[0]
  249. EXECUTE sofia/default/100@hq.ubx.univasa.com set(original_caller_id_name=100)
  250. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [original_caller_id_name]=[100]
  251. EXECUTE sofia/default/100@hq.ubx.univasa.com set(original_caller_id_number=100)
  252. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [original_caller_id_number]=[100]
  253. EXECUTE sofia/default/100@hq.ubx.univasa.com limit(db 5169063157 user_5169063157 10 !SWITCH_CONGESTION)
  254. 2021-02-05 09:52:41.397760 [DEBUG] switch_limit.c:126 incr called: 5169063157_user_5169063157 max:10, interval:0
  255. 2021-02-05 09:52:41.397760 [DEBUG] mod_db.c:194 Usage for 5169063157_user_5169063157 is now 1/10
  256. EXECUTE sofia/default/100@hq.ubx.univasa.com limit(hash CPS_5169063157 CPS_user_5169063157 100/1 !SWITCH_CONGESTION)
  257. 2021-02-05 09:52:41.397760 [DEBUG] switch_limit.c:126 incr called: CPS_5169063157_CPS_user_5169063157 max:100, interval:1
  258. 2021-02-05 09:52:41.397760 [DEBUG] mod_hash.c:198 Usage for CPS_5169063157_CPS_user_5169063157 is now 1/100 for the last 1 seconds
  259. EXECUTE sofia/default/100@hq.ubx.univasa.com limit(db 5 user_5 10 !SWITCH_CONGESTION)
  260. 2021-02-05 09:52:41.397760 [DEBUG] switch_limit.c:126 incr called: 5_user_5 max:10, interval:0
  261. 2021-02-05 09:52:41.397760 [DEBUG] mod_db.c:194 Usage for 5_user_5 is now 1/10
  262. EXECUTE sofia/default/100@hq.ubx.univasa.com set(effective_caller_id_name=100)
  263. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [effective_caller_id_name]=[100]
  264. EXECUTE sofia/default/100@hq.ubx.univasa.com set(effective_caller_id_number=100)
  265. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [effective_caller_id_number]=[100]
  266. EXECUTE sofia/default/100@hq.ubx.univasa.com set(receiver_accid=5)
  267. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [receiver_accid]=[5]
  268. EXECUTE sofia/default/100@hq.ubx.univasa.com export(domain_name=hq.ubx.univasa.com)
  269. 2021-02-05 09:52:41.397760 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[hq.ubx.univasa.com]
  270. EXECUTE sofia/default/100@hq.ubx.univasa.com export(sip_to_host=hq.ubx.univasa.com)
  271. 2021-02-05 09:52:41.397760 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_to_host]=[hq.ubx.univasa.com]
  272. EXECUTE sofia/default/100@hq.ubx.univasa.com limit(db 018887088 did_018887088 10 !SWITCH_CONGESTION)
  273. 2021-02-05 09:52:41.397760 [DEBUG] switch_limit.c:126 incr called: 018887088_did_018887088 max:10, interval:0
  274. 2021-02-05 09:52:41.397760 [DEBUG] mod_db.c:194 Usage for 018887088_did_018887088 is now 1/10
  275. EXECUTE sofia/default/100@hq.ubx.univasa.com export(presence_data=x|||Ebunoluwa(5169063157)|||^018887088.* // 018887088 // 0.00000||||||DID|||117.247.91.162)
  276. 2021-02-05 09:52:41.397760 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [presence_data]=[x|||Ebunoluwa(5169063157)|||^018887088.* // 018887088 // 0.00000||||||DID|||117.247.91.162]
  277. EXECUTE sofia/default/100@hq.ubx.univasa.com export(call_type=10)
  278. 2021-02-05 09:52:41.397760 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_type]=[10]
  279. EXECUTE sofia/default/100@hq.ubx.univasa.com set(did_calltype=10)
  280. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [did_calltype]=[10]
  281. EXECUTE sofia/default/100@hq.ubx.univasa.com set(did_extensions=1)
  282. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [did_extensions]=[1]
  283. EXECUTE sofia/default/100@hq.ubx.univasa.com set(ringback=)
  284. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [ringback]=[UNDEF]
  285. EXECUTE sofia/default/100@hq.ubx.univasa.com set(module_name=pbx)
  286. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [module_name]=[pbx]
  287. EXECUTE sofia/default/100@hq.ubx.univasa.com set(sip_h_P-call_type=custom_forward)
  288. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [sip_h_P-call_type]=[custom_forward]
  289. EXECUTE sofia/default/100@hq.ubx.univasa.com set(sip_h_P-Accountcode=5)
  290. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [sip_h_P-Accountcode]=[5]
  291. EXECUTE sofia/default/100@hq.ubx.univasa.com set(domain_name=hq.ubx.univasa.com)
  292. 2021-02-05 09:52:41.397760 [DEBUG] mod_dptools.c:1548 SET sofia/default/100@hq.ubx.univasa.com [domain_name]=[hq.ubx.univasa.com]
  293. EXECUTE sofia/default/100@hq.ubx.univasa.com answer()
  294. 2021-02-05 09:52:41.397760 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/100@hq.ubx.univasa.com] 172.31.70.255 port 32512 -> 117.247.91.162 port 8000 codec: 8 ms: 20
  295. 2021-02-05 09:52:41.397760 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  296. 2021-02-05 09:52:41.397760 [DEBUG] switch_core_media.c:7180 sofia/default/100@hq.ubx.univasa.com Set 2833 dtmf send payload to 101
  297. 2021-02-05 09:52:41.397760 [DEBUG] switch_core_media.c:7187 sofia/default/100@hq.ubx.univasa.com Set 2833 dtmf receive payload to 101
  298. 2021-02-05 09:52:41.397760 [DEBUG] switch_core_media.c:7210 sofia/default/100@hq.ubx.univasa.com Set rtp dtmf delay to 40
  299. 2021-02-05 09:52:41.397760 [DEBUG] mod_sofia.c:850 Local SDP sofia/default/100@hq.ubx.univasa.com:
  300. v=0
  301. o=FreeSWITCH 1612486249 1612486250 IN IP4 3.85.245.147
  302. s=FreeSWITCH
  303. c=IN IP4 3.85.245.147
  304. t=0 0
  305. m=audio 32512 RTP/AVP 8 101
  306. a=rtpmap:8 PCMA/8000
  307. a=rtpmap:101 telephone-event/8000
  308. a=fmtp:101 0-16
  309. a=ptime:20
  310. a=sendrecv
  311.  
  312. 2021-02-05 09:52:41.397760 [NOTICE] mod_dptools.c:1312 Channel [sofia/default/100@hq.ubx.univasa.com] has been answered
  313. 2021-02-05 09:52:41.397760 [DEBUG] switch_channel.c:3773 (sofia/default/100@hq.ubx.univasa.com) Callstate Change RINGING -> ACTIVE
  314. 2021-02-05 09:52:41.397760 [DEBUG] sofia.c:7084 Channel sofia/default/100@hq.ubx.univasa.com entering state [completed][200]
  315. EXECUTE sofia/default/100@hq.ubx.univasa.com ivr(HQ CC IVR0)
  316. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exit'
  317. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-sub'
  318. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
  319. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
  320. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-back'
  321. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-top'
  322. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:880 building menu 'HQ CC IVR0'
  323. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '1'
  324. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '0'
  325. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '2'
  326. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '/^(\d{2,20})$/'
  327. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_menu.c:483 Executing IVR menu HQ CC IVR0
  328. 2021-02-05 09:52:41.458162 [DEBUG] switch_core_file.c:342 File /var/www/html/astpp/upload/pbx/Welcome-MSG1_030221015657.wav sample rate 24000 doesn't match requested rate 8000
  329. 2021-02-05 09:52:41.458162 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  330. freeswitch@ip-172-31-70-255.ec2.internal>
  331. 2021-02-05 09:52:41.758340 [DEBUG] sofia.c:7084 Channel sofia/default/100@hq.ubx.univasa.com entering state [ready][200]
  332. 2021-02-05 09:52:42.097746 [INFO] switch_rtp.c:7268 Auto Changing audio port from 117.247.91.162:8000 to 117.247.91.162:3423
  333. 2021-02-05 09:52:45.238016 [DEBUG] switch_rtp.c:7534 RTP RECV DTMF 1:960
  334. 2021-02-05 09:52:45.238016 [INFO] switch_channel.c:515 RECV DTMF 1:960
  335. 2021-02-05 09:52:45.238016 [DEBUG] switch_ivr_play_say.c:1942 done playing file /var/www/html/astpp/upload/pbx/Welcome-MSG1_030221015657.wav
  336. 2021-02-05 09:52:45.238016 [DEBUG] switch_ivr_menu.c:377 waiting for 19/20 digits t/o 2000
  337. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_menu.c:424 digits '1'
  338. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_menu.c:577 IVR action on menu 'HQ CC IVR0' matched '1' param 'bridge {absolute_codec_string=^^:PCMA:PCMU:G729,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=5}[call_timeout=5,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=110,leg_timeout=5,leg_delay_start=0]sofia/${sofia_profile_name}/110@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=120,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/120@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=130,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/130@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=140,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/140@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=150,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/150@${domain_name},'
  339. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
  340. EXECUTE sofia/default/100@hq.ubx.univasa.com bridge({absolute_codec_string=^^:PCMA:PCMU:G729,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=5}[call_timeout=5,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=110,leg_timeout=5,leg_delay_start=0]sofia/default/110@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=120,leg_timeout=0,leg_delay_start=0]sofia/default/120@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=130,leg_timeout=0,leg_delay_start=0]sofia/default/130@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=140,leg_timeout=0,leg_delay_start=0]sofia/default/140@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=150,leg_timeout=0,leg_delay_start=0]sofia/default/150@hq.ubx.univasa.com,)
  341. 2021-02-05 09:52:47.257758 [DEBUG] switch_channel.c:1823 (sofia/default/100@hq.ubx.univasa.com) Callstate Change ACTIVE -> RING_WAIT
  342. 2021-02-05 09:52:47.257758 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [domain_name]=[hq.ubx.univasa.com] to event
  343. 2021-02-05 09:52:47.257758 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [sip_to_host]=[hq.ubx.univasa.com] to event
  344. 2021-02-05 09:52:47.257758 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [presence_data]=[x|||Ebunoluwa(5169063157)|||^018887088.* // 018887088 // 0.00000||||||DID|||117.247.91.162] to event
  345. 2021-02-05 09:52:47.257758 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [call_type]=[10] to event
  346. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  347. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  348. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/110@hq.ubx.univasa.com [e6abc0fc-6797-11eb-b782-5b927fa9d4df]
  349. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:4819 (sofia/default/110@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  350. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:2963 sofia/default/110@hq.ubx.univasa.com Setting leg timeout to 5
  351. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  352. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 2 Tot 14989)
  353. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/120@hq.ubx.univasa.com [e6abd25e-6797-11eb-b788-5b927fa9d4df]
  354. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:4819 (sofia/default/120@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  355. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/110@hq.ubx.univasa.com) State INIT
  356. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:90 sofia/default/110@hq.ubx.univasa.com SOFIA INIT
  357. 2021-02-05 09:52:47.257758 [DEBUG] sofia_glue.c:1295 sofia/default/110@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  358. Local SDP:
  359. v=0
  360. o=FreeSWITCH 1612487519 1612487520 IN IP4 3.85.245.147
  361. s=FreeSWITCH
  362. c=IN IP4 3.85.245.147
  363. t=0 0
  364. m=audio 31248 RTP/AVP 8 0 18 101
  365. a=rtpmap:8 PCMA/8000
  366. a=rtpmap:0 PCMU/8000
  367. a=rtpmap:18 G729/8000
  368. a=rtpmap:101 telephone-event/8000
  369. a=fmtp:101 0-16
  370. a=ptime:20
  371. a=sendrecv
  372.  
  373. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:40 sofia/default/110@hq.ubx.univasa.com Standard INIT
  374. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:48 (sofia/default/110@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  375. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/110@hq.ubx.univasa.com) State INIT going to sleep
  376. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  377. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 3 Tot 14990)
  378. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/130@hq.ubx.univasa.com [e6abe4c4-6797-11eb-b78e-5b927fa9d4df]
  379. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/110@hq.ubx.univasa.com) State ROUTING
  380. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:143 sofia/default/110@hq.ubx.univasa.com SOFIA ROUTING
  381. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:67 (sofia/default/110@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  382. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:4819 (sofia/default/130@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  383. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/110@hq.ubx.univasa.com) State ROUTING going to sleep
  384. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 14991)
  385. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/110@hq.ubx.univasa.com) State CONSUME_MEDIA
  386. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/110@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  387. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  388. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 4 Tot 14991)
  389. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/140@hq.ubx.univasa.com [e6abfc16-6797-11eb-b794-5b927fa9d4df]
  390. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:4819 (sofia/default/140@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  391. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/120@hq.ubx.univasa.com) State INIT
  392. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:90 sofia/default/120@hq.ubx.univasa.com SOFIA INIT
  393. 2021-02-05 09:52:47.257758 [DEBUG] sofia_glue.c:1295 sofia/default/120@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  394. Local SDP:
  395. v=0
  396. o=FreeSWITCH 1612492471 1612492472 IN IP4 3.85.245.147
  397. s=FreeSWITCH
  398. c=IN IP4 3.85.245.147
  399. t=0 0
  400. m=audio 26296 RTP/AVP 8 0 18 101
  401. a=rtpmap:8 PCMA/8000
  402. a=rtpmap:0 PCMU/8000
  403. a=rtpmap:18 G729/8000
  404. a=rtpmap:101 telephone-event/8000
  405. a=fmtp:101 0-16
  406. a=ptime:20
  407. a=sendrecv
  408.  
  409. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:40 sofia/default/120@hq.ubx.univasa.com Standard INIT
  410. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:48 (sofia/default/120@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  411. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/120@hq.ubx.univasa.com) State INIT going to sleep
  412. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  413. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 5 Tot 14992)
  414. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/120@hq.ubx.univasa.com) State ROUTING
  415. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:143 sofia/default/120@hq.ubx.univasa.com SOFIA ROUTING
  416. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:67 (sofia/default/120@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  417. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/120@hq.ubx.univasa.com) State ROUTING going to sleep
  418. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/150@hq.ubx.univasa.com [e6ac0f62-6797-11eb-b79a-5b927fa9d4df]
  419. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 14993)
  420. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:4819 (sofia/default/150@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  421. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/120@hq.ubx.univasa.com) State CONSUME_MEDIA
  422. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/120@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  423. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 6 Tot 14993)
  424. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/130@hq.ubx.univasa.com) State INIT
  425. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:90 sofia/default/130@hq.ubx.univasa.com SOFIA INIT
  426. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 6 Tot 14993)
  427. 2021-02-05 09:52:47.257758 [DEBUG] sofia_glue.c:1295 sofia/default/130@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  428. Local SDP:
  429. v=0
  430. o=FreeSWITCH 1612493041 1612493042 IN IP4 3.85.245.147
  431. s=FreeSWITCH
  432. c=IN IP4 3.85.245.147
  433. t=0 0
  434. m=audio 25726 RTP/AVP 8 0 18 101
  435. a=rtpmap:8 PCMA/8000
  436. a=rtpmap:0 PCMU/8000
  437. a=rtpmap:18 G729/8000
  438. a=rtpmap:101 telephone-event/8000
  439. a=fmtp:101 0-16
  440. a=ptime:20
  441. a=sendrecv
  442.  
  443. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:40 sofia/default/130@hq.ubx.univasa.com Standard INIT
  444. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:48 (sofia/default/130@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  445. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/130@hq.ubx.univasa.com) State INIT going to sleep
  446. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/140@hq.ubx.univasa.com) State INIT
  447. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:90 sofia/default/140@hq.ubx.univasa.com SOFIA INIT
  448. 2021-02-05 09:52:47.257758 [DEBUG] sofia_glue.c:1295 sofia/default/140@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  449. Local SDP:
  450. v=0
  451. o=FreeSWITCH 1612499067 1612499068 IN IP4 3.85.245.147
  452. s=FreeSWITCH
  453. c=IN IP4 3.85.245.147
  454. t=0 0
  455. m=audio 19700 RTP/AVP 8 0 18 101
  456. a=rtpmap:8 PCMA/8000
  457. a=rtpmap:0 PCMU/8000
  458. a=rtpmap:18 G729/8000
  459. a=rtpmap:101 telephone-event/8000
  460. a=fmtp:101 0-16
  461. a=ptime:20
  462. a=sendrecv
  463.  
  464. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:40 sofia/default/140@hq.ubx.univasa.com Standard INIT
  465. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:48 (sofia/default/140@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  466. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/140@hq.ubx.univasa.com) State INIT going to sleep
  467. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 6 Tot 14993)
  468. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/130@hq.ubx.univasa.com) State ROUTING
  469. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:143 sofia/default/130@hq.ubx.univasa.com SOFIA ROUTING
  470. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:67 (sofia/default/130@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  471. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/130@hq.ubx.univasa.com) State ROUTING going to sleep
  472. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 14993)
  473. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 6 Tot 14993)
  474. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/130@hq.ubx.univasa.com) State CONSUME_MEDIA
  475. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/130@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  476. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/140@hq.ubx.univasa.com) State ROUTING
  477. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:143 sofia/default/140@hq.ubx.univasa.com SOFIA ROUTING
  478. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:67 (sofia/default/140@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  479. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/140@hq.ubx.univasa.com) State ROUTING going to sleep
  480. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 14993)
  481. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/140@hq.ubx.univasa.com) State CONSUME_MEDIA
  482. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/140@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  483. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 6 Tot 14993)
  484. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/150@hq.ubx.univasa.com) State INIT
  485. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:90 sofia/default/150@hq.ubx.univasa.com SOFIA INIT
  486. 2021-02-05 09:52:47.257758 [DEBUG] sofia_glue.c:1295 sofia/default/150@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  487. Local SDP:
  488. v=0
  489. o=FreeSWITCH 1612497435 1612497436 IN IP4 3.85.245.147
  490. s=FreeSWITCH
  491. c=IN IP4 3.85.245.147
  492. t=0 0
  493. m=audio 21332 RTP/AVP 8 0 18 101
  494. a=rtpmap:8 PCMA/8000
  495. a=rtpmap:0 PCMU/8000
  496. a=rtpmap:18 G729/8000
  497. a=rtpmap:101 telephone-event/8000
  498. a=fmtp:101 0-16
  499. a=ptime:20
  500. a=sendrecv
  501.  
  502. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:40 sofia/default/150@hq.ubx.univasa.com Standard INIT
  503. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:48 (sofia/default/150@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  504. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:627 (sofia/default/150@hq.ubx.univasa.com) State INIT going to sleep
  505. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 6 Tot 14993)
  506. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/150@hq.ubx.univasa.com) State ROUTING
  507. 2021-02-05 09:52:47.257758 [DEBUG] mod_sofia.c:143 sofia/default/150@hq.ubx.univasa.com SOFIA ROUTING
  508. 2021-02-05 09:52:47.257758 [DEBUG] switch_ivr_originate.c:67 (sofia/default/150@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  509. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:643 (sofia/default/150@hq.ubx.univasa.com) State ROUTING going to sleep
  510. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 14993)
  511. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/150@hq.ubx.univasa.com) State CONSUME_MEDIA
  512. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:662 (sofia/default/150@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  513. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [e6aca8dc-6797-11eb-b79e-5b927fa9d4df]
  514. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:7084 Channel sofia/default/110@hq.ubx.univasa.com entering state [calling][0]
  515. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:7084 Channel sofia/default/120@hq.ubx.univasa.com entering state [calling][0]
  516. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:7084 Channel sofia/default/130@hq.ubx.univasa.com entering state [calling][0]
  517. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:7084 Channel sofia/default/140@hq.ubx.univasa.com entering state [calling][0]
  518. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:7084 Channel sofia/default/150@hq.ubx.univasa.com entering state [calling][0]
  519. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 7 Tot 14994)
  520. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  521. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  522. 2021-02-05 09:52:47.257758 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [110@hq.ubx.univasa.com] from ip 3.85.245.147
  523. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  524. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [e6ad2640-6797-11eb-b7a0-5b927fa9d4df]
  525. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [e6ad3c8e-6797-11eb-b7a1-5b927fa9d4df]
  526. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 9 Tot 14996)
  527. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [e6ad3f7c-6797-11eb-b7a2-5b927fa9d4df]
  528. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  529. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:2334 detaching session e6aca8dc-6797-11eb-b79e-5b927fa9d4df
  530. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  531. 2021-02-05 09:52:47.257758 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [e6ad422e-6797-11eb-b7a3-5b927fa9d4df]
  532. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 11 Tot 14998)
  533. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  534. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  535. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 11 Tot 14998)
  536. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  537. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  538. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 11 Tot 14998)
  539. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  540. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  541. 2021-02-05 09:52:47.257758 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [120@hq.ubx.univasa.com] from ip 3.85.245.147
  542. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  543. 2021-02-05 09:52:47.257758 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [150@hq.ubx.univasa.com] from ip 3.85.245.147
  544. 2021-02-05 09:52:47.257758 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  545. 2021-02-05 09:52:47.257758 [DEBUG] sofia.c:2334 detaching session e6ad2640-6797-11eb-b7a0-5b927fa9d4df
  546. 2021-02-05 09:52:47.278014 [DEBUG] sofia.c:2334 detaching session e6ad422e-6797-11eb-b7a3-5b927fa9d4df
  547. 2021-02-05 09:52:47.278014 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [130@hq.ubx.univasa.com] from ip 3.85.245.147
  548. 2021-02-05 09:52:47.278014 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  549. 2021-02-05 09:52:47.278014 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [140@hq.ubx.univasa.com] from ip 3.85.245.147
  550. 2021-02-05 09:52:47.278014 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  551. 2021-02-05 09:52:47.278014 [DEBUG] sofia.c:2334 detaching session e6ad3c8e-6797-11eb-b7a1-5b927fa9d4df
  552. 2021-02-05 09:52:47.278014 [DEBUG] sofia.c:2334 detaching session e6ad3f7c-6797-11eb-b7a2-5b927fa9d4df
  553. 2021-02-05 09:52:47.298034 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  554. 2021-02-05 09:52:47.298034 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/130@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  555. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 14998)
  556. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:850 (sofia/default/130@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  557. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/130@hq.ubx.univasa.com) State HANGUP
  558. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:438 Channel sofia/default/130@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  559. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/130@hq.ubx.univasa.com
  560. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:60 sofia/default/130@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  561. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/130@hq.ubx.univasa.com) State HANGUP going to sleep
  562. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:619 (sofia/default/130@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  563. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 14998)
  564. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:938 (sofia/default/130@hq.ubx.univasa.com) State REPORTING
  565. 2021-02-05 09:52:47.298034 [INFO] mod_json_cdr.c:271 Process [e6abe4c4-6797-11eb-b78e-5b927fa9d4df.cdr.json]
  566. 2021-02-05 09:52:47.298034 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  567. 2021-02-05 09:52:47.298034 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/140@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  568. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 14998)
  569. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:850 (sofia/default/140@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  570. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/140@hq.ubx.univasa.com) State HANGUP
  571. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:438 Channel sofia/default/140@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  572. 2021-02-05 09:52:47.298034 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  573. 2021-02-05 09:52:47.298034 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/150@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  574. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/140@hq.ubx.univasa.com
  575. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:60 sofia/default/140@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  576. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/140@hq.ubx.univasa.com) State HANGUP going to sleep
  577. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:619 (sofia/default/140@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  578. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 14998)
  579. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:938 (sofia/default/140@hq.ubx.univasa.com) State REPORTING
  580. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 14998)
  581. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:850 (sofia/default/150@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  582. 2021-02-05 09:52:47.298034 [INFO] mod_json_cdr.c:271 Process [e6abfc16-6797-11eb-b794-5b927fa9d4df.cdr.json]
  583. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/150@hq.ubx.univasa.com) State HANGUP
  584. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:438 Channel sofia/default/150@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  585. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/150@hq.ubx.univasa.com
  586. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:60 sofia/default/150@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  587. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/150@hq.ubx.univasa.com) State HANGUP going to sleep
  588. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:619 (sofia/default/150@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  589. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 14998)
  590. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:938 (sofia/default/150@hq.ubx.univasa.com) State REPORTING
  591. 2021-02-05 09:52:47.298034 [INFO] mod_json_cdr.c:271 Process [e6ac0f62-6797-11eb-b79a-5b927fa9d4df.cdr.json]
  592. 2021-02-05 09:52:47.298034 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  593. 2021-02-05 09:52:47.298034 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/110@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  594. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 14998)
  595. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:850 (sofia/default/110@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  596. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/110@hq.ubx.univasa.com) State HANGUP
  597. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:438 Channel sofia/default/110@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  598. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/110@hq.ubx.univasa.com
  599. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:60 sofia/default/110@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  600. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/110@hq.ubx.univasa.com) State HANGUP going to sleep
  601. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:619 (sofia/default/110@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  602. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 14998)
  603. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:938 (sofia/default/110@hq.ubx.univasa.com) State REPORTING
  604. 2021-02-05 09:52:47.298034 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  605. 2021-02-05 09:52:47.298034 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/120@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  606. 2021-02-05 09:52:47.298034 [INFO] mod_json_cdr.c:271 Process [e6abc0fc-6797-11eb-b782-5b927fa9d4df.cdr.json]
  607. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 14998)
  608. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:850 (sofia/default/120@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  609. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/120@hq.ubx.univasa.com) State HANGUP
  610. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:438 Channel sofia/default/120@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  611. 2021-02-05 09:52:47.298034 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/120@hq.ubx.univasa.com
  612. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:60 sofia/default/120@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  613. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:852 (sofia/default/120@hq.ubx.univasa.com) State HANGUP going to sleep
  614. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:619 (sofia/default/120@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  615. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 14998)
  616. 2021-02-05 09:52:47.298034 [DEBUG] switch_core_state_machine.c:938 (sofia/default/120@hq.ubx.univasa.com) State REPORTING
  617. 2021-02-05 09:52:47.298034 [INFO] mod_json_cdr.c:271 Process [e6abd25e-6797-11eb-b788-5b927fa9d4df.cdr.json]
  618. 2021-02-05 09:52:47.321494 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING]
  619. 2021-02-05 09:52:47.321494 [DEBUG] switch_channel.c:2047 (sofia/default/100@hq.ubx.univasa.com) Callstate Change RING_WAIT -> ACTIVE
  620. 2021-02-05 09:52:47.321494 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: MANDATORY_IE_MISSING
  621. 2021-02-05 09:52:47.321494 [DEBUG] switch_ivr_menu.c:559 action regex [1] [/^(\d{2,20})$/] [0]
  622. 2021-02-05 09:52:47.321494 [DEBUG] switch_core_file.c:342 File /var/www/html/astpp/upload/pbx/Welcome-MSG1_030221015657.wav sample rate 24000 doesn't match requested rate 8000
  623. 2021-02-05 09:52:47.321494 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  624. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:174 sofia/default/140@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  625. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:938 (sofia/default/140@hq.ubx.univasa.com) State REPORTING going to sleep
  626. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:610 (sofia/default/140@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  627. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_session.c:1665 Session 14992 (sofia/default/140@hq.ubx.univasa.com) Locked, Waiting on external entities
  628. 2021-02-05 09:52:47.337950 [NOTICE] switch_core_session.c:1683 Session 14992 (sofia/default/140@hq.ubx.univasa.com) Ended
  629. 2021-02-05 09:52:47.337950 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/140@hq.ubx.univasa.com [CS_DESTROY]
  630. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:741 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 10 Tot 14998)
  631. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:751 (sofia/default/140@hq.ubx.univasa.com) State DESTROY
  632. 2021-02-05 09:52:47.337950 [DEBUG] mod_sofia.c:343 sofia/default/140@hq.ubx.univasa.com SOFIA DESTROY
  633. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:181 sofia/default/140@hq.ubx.univasa.com Standard DESTROY
  634. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:751 (sofia/default/140@hq.ubx.univasa.com) State DESTROY going to sleep
  635. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:174 sofia/default/150@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  636. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:938 (sofia/default/150@hq.ubx.univasa.com) State REPORTING going to sleep
  637. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:610 (sofia/default/150@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  638. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_session.c:1665 Session 14993 (sofia/default/150@hq.ubx.univasa.com) Locked, Waiting on external entities
  639. 2021-02-05 09:52:47.337950 [NOTICE] switch_core_session.c:1683 Session 14993 (sofia/default/150@hq.ubx.univasa.com) Ended
  640. 2021-02-05 09:52:47.337950 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/150@hq.ubx.univasa.com [CS_DESTROY]
  641. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:741 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 9 Tot 14998)
  642. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:751 (sofia/default/150@hq.ubx.univasa.com) State DESTROY
  643. 2021-02-05 09:52:47.337950 [DEBUG] mod_sofia.c:343 sofia/default/150@hq.ubx.univasa.com SOFIA DESTROY
  644. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:181 sofia/default/150@hq.ubx.univasa.com Standard DESTROY
  645. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:751 (sofia/default/150@hq.ubx.univasa.com) State DESTROY going to sleep
  646. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:174 sofia/default/130@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  647. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:938 (sofia/default/130@hq.ubx.univasa.com) State REPORTING going to sleep
  648. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:610 (sofia/default/130@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  649. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_session.c:1665 Session 14991 (sofia/default/130@hq.ubx.univasa.com) Locked, Waiting on external entities
  650. 2021-02-05 09:52:47.337950 [NOTICE] switch_core_session.c:1683 Session 14991 (sofia/default/130@hq.ubx.univasa.com) Ended
  651. 2021-02-05 09:52:47.337950 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/130@hq.ubx.univasa.com [CS_DESTROY]
  652. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:741 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 8 Tot 14998)
  653. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:751 (sofia/default/130@hq.ubx.univasa.com) State DESTROY
  654. 2021-02-05 09:52:47.337950 [DEBUG] mod_sofia.c:343 sofia/default/130@hq.ubx.univasa.com SOFIA DESTROY
  655. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:181 sofia/default/130@hq.ubx.univasa.com Standard DESTROY
  656. 2021-02-05 09:52:47.337950 [DEBUG] switch_core_state_machine.c:751 (sofia/default/130@hq.ubx.univasa.com) State DESTROY going to sleep
  657. 2021-02-05 09:52:47.379041 [DEBUG] switch_core_state_machine.c:174 sofia/default/110@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  658. 2021-02-05 09:52:47.379041 [DEBUG] switch_core_state_machine.c:938 (sofia/default/110@hq.ubx.univasa.com) State REPORTING going to sleep
  659. 2021-02-05 09:52:47.379041 [DEBUG] switch_core_state_machine.c:610 (sofia/default/110@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  660. 2021-02-05 09:52:47.379041 [DEBUG] switch_core_session.c:1665 Session 14989 (sofia/default/110@hq.ubx.univasa.com) Locked, Waiting on external entities
  661. 2021-02-05 09:52:47.379041 [NOTICE] switch_core_session.c:1683 Session 14989 (sofia/default/110@hq.ubx.univasa.com) Ended
  662. 2021-02-05 09:52:47.379041 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/110@hq.ubx.univasa.com [CS_DESTROY]
  663. 2021-02-05 09:52:47.379041 [DEBUG] switch_core_state_machine.c:741 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 7 Tot 14998)
  664. 2021-02-05 09:52:47.379041 [DEBUG] switch_core_state_machine.c:751 (sofia/default/110@hq.ubx.univasa.com) State DESTROY
  665. 2021-02-05 09:52:47.379041 [DEBUG] mod_sofia.c:343 sofia/default/110@hq.ubx.univasa.com SOFIA DESTROY
  666. 2021-02-05 09:52:47.379041 [DEBUG] switch_core_state_machine.c:181 sofia/default/110@hq.ubx.univasa.com Standard DESTROY
  667. 2021-02-05 09:52:47.379041 [DEBUG] switch_core_state_machine.c:751 (sofia/default/110@hq.ubx.univasa.com) State DESTROY going to sleep
  668. 2021-02-05 09:52:47.397799 [DEBUG] switch_core_state_machine.c:174 sofia/default/120@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  669. 2021-02-05 09:52:47.397799 [DEBUG] switch_core_state_machine.c:938 (sofia/default/120@hq.ubx.univasa.com) State REPORTING going to sleep
  670. 2021-02-05 09:52:47.397799 [DEBUG] switch_core_state_machine.c:610 (sofia/default/120@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  671. 2021-02-05 09:52:47.397799 [DEBUG] switch_core_session.c:1665 Session 14990 (sofia/default/120@hq.ubx.univasa.com) Locked, Waiting on external entities
  672. 2021-02-05 09:52:47.397799 [NOTICE] switch_core_session.c:1683 Session 14990 (sofia/default/120@hq.ubx.univasa.com) Ended
  673. 2021-02-05 09:52:47.397799 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/120@hq.ubx.univasa.com [CS_DESTROY]
  674. 2021-02-05 09:52:47.397799 [DEBUG] switch_core_state_machine.c:741 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 6 Tot 14998)
  675. 2021-02-05 09:52:47.397799 [DEBUG] switch_core_state_machine.c:751 (sofia/default/120@hq.ubx.univasa.com) State DESTROY
  676. 2021-02-05 09:52:47.397799 [DEBUG] mod_sofia.c:343 sofia/default/120@hq.ubx.univasa.com SOFIA DESTROY
  677. 2021-02-05 09:52:47.397799 [DEBUG] switch_core_state_machine.c:181 sofia/default/120@hq.ubx.univasa.com Standard DESTROY
  678. 2021-02-05 09:52:47.397799 [DEBUG] switch_core_state_machine.c:751 (sofia/default/120@hq.ubx.univasa.com) State DESTROY going to sleep
  679. 2021-02-05 09:52:54.797746 [DEBUG] switch_rtp.c:7534 RTP RECV DTMF 2:960
  680. 2021-02-05 09:52:54.797746 [INFO] switch_channel.c:515 RECV DTMF 2:960
  681. 2021-02-05 09:52:54.797746 [DEBUG] switch_ivr_play_say.c:1942 done playing file /var/www/html/astpp/upload/pbx/Welcome-MSG1_030221015657.wav
  682. 2021-02-05 09:52:54.797746 [DEBUG] switch_ivr_menu.c:377 waiting for 19/20 digits t/o 2000
  683. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_menu.c:424 digits '2'
  684. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_menu.c:577 IVR action on menu 'HQ CC IVR0' matched '2' param 'bridge {absolute_codec_string=^^:PCMA:PCMU:G729,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=5}[call_timeout=5,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=110,leg_timeout=5,leg_delay_start=0]sofia/${sofia_profile_name}/110@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=120,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/120@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=130,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/130@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=140,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/140@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=150,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/150@${domain_name},'
  685. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
  686. EXECUTE sofia/default/100@hq.ubx.univasa.com bridge({absolute_codec_string=^^:PCMA:PCMU:G729,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=5}[call_timeout=5,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=110,leg_timeout=5,leg_delay_start=0]sofia/default/110@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=120,leg_timeout=0,leg_delay_start=0]sofia/default/120@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=130,leg_timeout=0,leg_delay_start=0]sofia/default/130@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=140,leg_timeout=0,leg_delay_start=0]sofia/default/140@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=150,leg_timeout=0,leg_delay_start=0]sofia/default/150@hq.ubx.univasa.com,)
  687. 2021-02-05 09:52:56.797765 [DEBUG] switch_channel.c:1823 (sofia/default/100@hq.ubx.univasa.com) Callstate Change ACTIVE -> RING_WAIT
  688. 2021-02-05 09:52:56.797765 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [domain_name]=[hq.ubx.univasa.com] to event
  689. 2021-02-05 09:52:56.797765 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [sip_to_host]=[hq.ubx.univasa.com] to event
  690. 2021-02-05 09:52:56.797765 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [presence_data]=[x|||Ebunoluwa(5169063157)|||^018887088.* // 018887088 // 0.00000||||||DID|||117.247.91.162] to event
  691. 2021-02-05 09:52:56.797765 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [call_type]=[10] to event
  692. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  693. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  694. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/110@hq.ubx.univasa.com [ec5b986a-6797-11eb-b7b1-5b927fa9d4df]
  695. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:4819 (sofia/default/110@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  696. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:2963 sofia/default/110@hq.ubx.univasa.com Setting leg timeout to 5
  697. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  698. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 7 Tot 14999)
  699. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/110@hq.ubx.univasa.com) State INIT
  700. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/120@hq.ubx.univasa.com [ec5bab84-6797-11eb-b7b7-5b927fa9d4df]
  701. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:90 sofia/default/110@hq.ubx.univasa.com SOFIA INIT
  702. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:4819 (sofia/default/120@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  703. 2021-02-05 09:52:56.797765 [DEBUG] sofia_glue.c:1295 sofia/default/110@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  704. Local SDP:
  705. v=0
  706. o=FreeSWITCH 1612491956 1612491957 IN IP4 3.85.245.147
  707. s=FreeSWITCH
  708. c=IN IP4 3.85.245.147
  709. t=0 0
  710. m=audio 26820 RTP/AVP 8 0 18 101
  711. a=rtpmap:8 PCMA/8000
  712. a=rtpmap:0 PCMU/8000
  713. a=rtpmap:18 G729/8000
  714. a=rtpmap:101 telephone-event/8000
  715. a=fmtp:101 0-16
  716. a=ptime:20
  717. a=sendrecv
  718.  
  719. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:40 sofia/default/110@hq.ubx.univasa.com Standard INIT
  720. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:48 (sofia/default/110@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  721. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/110@hq.ubx.univasa.com) State INIT going to sleep
  722. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 8 Tot 15000)
  723. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  724. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/110@hq.ubx.univasa.com) State ROUTING
  725. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:143 sofia/default/110@hq.ubx.univasa.com SOFIA ROUTING
  726. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:67 (sofia/default/110@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  727. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/110@hq.ubx.univasa.com) State ROUTING going to sleep
  728. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 8 Tot 15000)
  729. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/110@hq.ubx.univasa.com) State CONSUME_MEDIA
  730. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/110@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  731. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/130@hq.ubx.univasa.com [ec5bcbbe-6797-11eb-b7bd-5b927fa9d4df]
  732. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:4819 (sofia/default/130@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  733. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  734. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 9 Tot 15001)
  735. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/120@hq.ubx.univasa.com) State INIT
  736. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/140@hq.ubx.univasa.com [ec5bdf0a-6797-11eb-b7c3-5b927fa9d4df]
  737. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:90 sofia/default/120@hq.ubx.univasa.com SOFIA INIT
  738. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:4819 (sofia/default/140@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  739. 2021-02-05 09:52:56.797765 [DEBUG] sofia_glue.c:1295 sofia/default/120@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  740. Local SDP:
  741. v=0
  742. o=FreeSWITCH 1612496826 1612496827 IN IP4 3.85.245.147
  743. s=FreeSWITCH
  744. c=IN IP4 3.85.245.147
  745. t=0 0
  746. m=audio 21950 RTP/AVP 8 0 18 101
  747. a=rtpmap:8 PCMA/8000
  748. a=rtpmap:0 PCMU/8000
  749. a=rtpmap:18 G729/8000
  750. a=rtpmap:101 telephone-event/8000
  751. a=fmtp:101 0-16
  752. a=ptime:20
  753. a=sendrecv
  754.  
  755. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:40 sofia/default/120@hq.ubx.univasa.com Standard INIT
  756. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:48 (sofia/default/120@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  757. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/120@hq.ubx.univasa.com) State INIT going to sleep
  758. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 10 Tot 15002)
  759. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  760. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/120@hq.ubx.univasa.com) State ROUTING
  761. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:143 sofia/default/120@hq.ubx.univasa.com SOFIA ROUTING
  762. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:67 (sofia/default/120@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  763. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/120@hq.ubx.univasa.com) State ROUTING going to sleep
  764. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 10 Tot 15002)
  765. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/120@hq.ubx.univasa.com) State CONSUME_MEDIA
  766. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/120@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  767. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/150@hq.ubx.univasa.com [ec5bff4e-6797-11eb-b7c9-5b927fa9d4df]
  768. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:4819 (sofia/default/150@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  769. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 11 Tot 15003)
  770. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/130@hq.ubx.univasa.com) State INIT
  771. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:90 sofia/default/130@hq.ubx.univasa.com SOFIA INIT
  772. 2021-02-05 09:52:56.797765 [DEBUG] sofia_glue.c:1295 sofia/default/130@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  773. Local SDP:
  774. v=0
  775. o=FreeSWITCH 1612499848 1612499849 IN IP4 3.85.245.147
  776. s=FreeSWITCH
  777. c=IN IP4 3.85.245.147
  778. t=0 0
  779. m=audio 18928 RTP/AVP 8 0 18 101
  780. a=rtpmap:8 PCMA/8000
  781. a=rtpmap:0 PCMU/8000
  782. a=rtpmap:18 G729/8000
  783. a=rtpmap:101 telephone-event/8000
  784. a=fmtp:101 0-16
  785. a=ptime:20
  786. a=sendrecv
  787.  
  788. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:40 sofia/default/130@hq.ubx.univasa.com Standard INIT
  789. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:48 (sofia/default/130@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  790. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/130@hq.ubx.univasa.com) State INIT going to sleep
  791. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 11 Tot 15003)
  792. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/130@hq.ubx.univasa.com) State ROUTING
  793. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:143 sofia/default/130@hq.ubx.univasa.com SOFIA ROUTING
  794. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:67 (sofia/default/130@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  795. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/130@hq.ubx.univasa.com) State ROUTING going to sleep
  796. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 11 Tot 15003)
  797. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/130@hq.ubx.univasa.com) State CONSUME_MEDIA
  798. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/130@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  799. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:7084 Channel sofia/default/110@hq.ubx.univasa.com entering state [calling][0]
  800. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 11 Tot 15003)
  801. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/140@hq.ubx.univasa.com) State INIT
  802. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:90 sofia/default/140@hq.ubx.univasa.com SOFIA INIT
  803. 2021-02-05 09:52:56.797765 [DEBUG] sofia_glue.c:1295 sofia/default/140@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  804. Local SDP:
  805. v=0
  806. o=FreeSWITCH 1612500246 1612500247 IN IP4 3.85.245.147
  807. s=FreeSWITCH
  808. c=IN IP4 3.85.245.147
  809. t=0 0
  810. m=audio 18530 RTP/AVP 8 0 18 101
  811. a=rtpmap:8 PCMA/8000
  812. a=rtpmap:0 PCMU/8000
  813. a=rtpmap:18 G729/8000
  814. a=rtpmap:101 telephone-event/8000
  815. a=fmtp:101 0-16
  816. a=ptime:20
  817. a=sendrecv
  818.  
  819. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:40 sofia/default/140@hq.ubx.univasa.com Standard INIT
  820. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:48 (sofia/default/140@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  821. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/140@hq.ubx.univasa.com) State INIT going to sleep
  822. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 11 Tot 15003)
  823. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/140@hq.ubx.univasa.com) State ROUTING
  824. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:143 sofia/default/140@hq.ubx.univasa.com SOFIA ROUTING
  825. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:67 (sofia/default/140@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  826. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/140@hq.ubx.univasa.com) State ROUTING going to sleep
  827. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 11 Tot 15003)
  828. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/140@hq.ubx.univasa.com) State CONSUME_MEDIA
  829. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/140@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  830. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [ec5cb632-6797-11eb-b7cd-5b927fa9d4df]
  831. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [ec5cba74-6797-11eb-b7ce-5b927fa9d4df]
  832. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [ec5cbdd0-6797-11eb-b7cf-5b927fa9d4df]
  833. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [ec5cc08c-6797-11eb-b7d0-5b927fa9d4df]
  834. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:7084 Channel sofia/default/140@hq.ubx.univasa.com entering state [calling][0]
  835. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 15 Tot 15007)
  836. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/150@hq.ubx.univasa.com) State INIT
  837. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:90 sofia/default/150@hq.ubx.univasa.com SOFIA INIT
  838. 2021-02-05 09:52:56.797765 [DEBUG] sofia_glue.c:1295 sofia/default/150@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  839. Local SDP:
  840. v=0
  841. o=FreeSWITCH 1612487670 1612487671 IN IP4 3.85.245.147
  842. s=FreeSWITCH
  843. c=IN IP4 3.85.245.147
  844. t=0 0
  845. m=audio 31106 RTP/AVP 8 0 18 101
  846. a=rtpmap:8 PCMA/8000
  847. a=rtpmap:0 PCMU/8000
  848. a=rtpmap:18 G729/8000
  849. a=rtpmap:101 telephone-event/8000
  850. a=fmtp:101 0-16
  851. a=ptime:20
  852. a=sendrecv
  853.  
  854. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:40 sofia/default/150@hq.ubx.univasa.com Standard INIT
  855. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:48 (sofia/default/150@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  856. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:627 (sofia/default/150@hq.ubx.univasa.com) State INIT going to sleep
  857. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 15 Tot 15007)
  858. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/150@hq.ubx.univasa.com) State ROUTING
  859. 2021-02-05 09:52:56.797765 [DEBUG] mod_sofia.c:143 sofia/default/150@hq.ubx.univasa.com SOFIA ROUTING
  860. 2021-02-05 09:52:56.797765 [DEBUG] switch_ivr_originate.c:67 (sofia/default/150@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  861. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:643 (sofia/default/150@hq.ubx.univasa.com) State ROUTING going to sleep
  862. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 15 Tot 15007)
  863. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/150@hq.ubx.univasa.com) State CONSUME_MEDIA
  864. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:662 (sofia/default/150@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  865. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 15 Tot 15007)
  866. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  867. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  868. 2021-02-05 09:52:56.797765 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [ec5d1d3e-6797-11eb-b7d2-5b927fa9d4df]
  869. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 16 Tot 15008)
  870. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  871. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  872. 2021-02-05 09:52:56.797765 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [110@hq.ubx.univasa.com] from ip 3.85.245.147
  873. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  874. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:2334 detaching session ec5cb632-6797-11eb-b7cd-5b927fa9d4df
  875. 2021-02-05 09:52:56.797765 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [120@hq.ubx.univasa.com] from ip 3.85.245.147
  876. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  877. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:2334 detaching session ec5cba74-6797-11eb-b7ce-5b927fa9d4df
  878. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:7084 Channel sofia/default/150@hq.ubx.univasa.com entering state [calling][0]
  879. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 16 Tot 15008)
  880. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  881. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  882. 2021-02-05 09:52:56.797765 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [130@hq.ubx.univasa.com] from ip 3.85.245.147
  883. 2021-02-05 09:52:56.797765 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  884. 2021-02-05 09:52:56.797765 [DEBUG] sofia.c:2334 detaching session ec5cbdd0-6797-11eb-b7cf-5b927fa9d4df
  885. 2021-02-05 09:52:56.822125 [DEBUG] sofia.c:7084 Channel sofia/default/130@hq.ubx.univasa.com entering state [calling][0]
  886. 2021-02-05 09:52:56.822125 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 16 Tot 15008)
  887. 2021-02-05 09:52:56.822125 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  888. 2021-02-05 09:52:56.822125 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  889. 2021-02-05 09:52:56.822125 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [140@hq.ubx.univasa.com] from ip 3.85.245.147
  890. 2021-02-05 09:52:56.822125 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  891. 2021-02-05 09:52:56.822125 [DEBUG] sofia.c:2334 detaching session ec5cc08c-6797-11eb-b7d0-5b927fa9d4df
  892. 2021-02-05 09:52:56.822125 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 16 Tot 15008)
  893. 2021-02-05 09:52:56.822125 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  894. 2021-02-05 09:52:56.822125 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  895. 2021-02-05 09:52:56.822125 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [150@hq.ubx.univasa.com] from ip 3.85.245.147
  896. 2021-02-05 09:52:56.822125 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  897. 2021-02-05 09:52:56.822125 [DEBUG] sofia.c:7084 Channel sofia/default/120@hq.ubx.univasa.com entering state [calling][0]
  898. 2021-02-05 09:52:56.822125 [DEBUG] sofia.c:2334 detaching session ec5d1d3e-6797-11eb-b7d2-5b927fa9d4df
  899. 2021-02-05 09:52:56.838681 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  900. 2021-02-05 09:52:56.838681 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/140@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  901. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 16 Tot 15008)
  902. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:850 (sofia/default/140@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  903. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:852 (sofia/default/140@hq.ubx.univasa.com) State HANGUP
  904. 2021-02-05 09:52:56.838681 [DEBUG] mod_sofia.c:438 Channel sofia/default/140@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  905. 2021-02-05 09:52:56.838681 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/140@hq.ubx.univasa.com
  906. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:60 sofia/default/140@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  907. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:852 (sofia/default/140@hq.ubx.univasa.com) State HANGUP going to sleep
  908. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:619 (sofia/default/140@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  909. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 16 Tot 15008)
  910. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:938 (sofia/default/140@hq.ubx.univasa.com) State REPORTING
  911. 2021-02-05 09:52:56.838681 [INFO] mod_json_cdr.c:271 Process [ec5bdf0a-6797-11eb-b7c3-5b927fa9d4df.cdr.json]
  912. 2021-02-05 09:52:56.838681 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  913. 2021-02-05 09:52:56.838681 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/110@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  914. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 16 Tot 15008)
  915. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:850 (sofia/default/110@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  916. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:852 (sofia/default/110@hq.ubx.univasa.com) State HANGUP
  917. 2021-02-05 09:52:56.838681 [DEBUG] mod_sofia.c:438 Channel sofia/default/110@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  918. 2021-02-05 09:52:56.838681 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/110@hq.ubx.univasa.com
  919. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:60 sofia/default/110@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  920. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:852 (sofia/default/110@hq.ubx.univasa.com) State HANGUP going to sleep
  921. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:619 (sofia/default/110@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  922. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 16 Tot 15008)
  923. 2021-02-05 09:52:56.838681 [DEBUG] switch_core_state_machine.c:938 (sofia/default/110@hq.ubx.univasa.com) State REPORTING
  924. 2021-02-05 09:52:56.838681 [INFO] mod_json_cdr.c:271 Process [ec5b986a-6797-11eb-b7b1-5b927fa9d4df.cdr.json]
  925. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:174 sofia/default/140@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  926. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:938 (sofia/default/140@hq.ubx.univasa.com) State REPORTING going to sleep
  927. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:610 (sofia/default/140@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  928. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_session.c:1665 Session 15002 (sofia/default/140@hq.ubx.univasa.com) Locked, Waiting on external entities
  929. 2021-02-05 09:52:56.857951 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  930. 2021-02-05 09:52:56.857951 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/130@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  931. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 16 Tot 15008)
  932. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:850 (sofia/default/130@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  933. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:852 (sofia/default/130@hq.ubx.univasa.com) State HANGUP
  934. 2021-02-05 09:52:56.857951 [DEBUG] mod_sofia.c:438 Channel sofia/default/130@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  935. 2021-02-05 09:52:56.857951 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/130@hq.ubx.univasa.com
  936. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:60 sofia/default/130@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  937. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:852 (sofia/default/130@hq.ubx.univasa.com) State HANGUP going to sleep
  938. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:619 (sofia/default/130@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  939. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 16 Tot 15008)
  940. 2021-02-05 09:52:56.857951 [DEBUG] switch_core_state_machine.c:938 (sofia/default/130@hq.ubx.univasa.com) State REPORTING
  941. 2021-02-05 09:52:56.857951 [INFO] mod_json_cdr.c:271 Process [ec5bcbbe-6797-11eb-b7bd-5b927fa9d4df.cdr.json]
  942. 2021-02-05 09:52:56.900859 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  943. 2021-02-05 09:52:56.900859 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/120@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  944. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 16 Tot 15008)
  945. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:850 (sofia/default/120@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  946. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:852 (sofia/default/120@hq.ubx.univasa.com) State HANGUP
  947. 2021-02-05 09:52:56.900859 [DEBUG] mod_sofia.c:438 Channel sofia/default/120@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  948. 2021-02-05 09:52:56.900859 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  949. 2021-02-05 09:52:56.900859 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/150@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  950. 2021-02-05 09:52:56.900859 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/120@hq.ubx.univasa.com
  951. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:60 sofia/default/120@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  952. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:852 (sofia/default/120@hq.ubx.univasa.com) State HANGUP going to sleep
  953. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:619 (sofia/default/120@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  954. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 16 Tot 15008)
  955. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:938 (sofia/default/120@hq.ubx.univasa.com) State REPORTING
  956. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 16 Tot 15008)
  957. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:850 (sofia/default/150@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  958. 2021-02-05 09:52:56.900859 [INFO] mod_json_cdr.c:271 Process [ec5bab84-6797-11eb-b7b7-5b927fa9d4df.cdr.json]
  959. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:852 (sofia/default/150@hq.ubx.univasa.com) State HANGUP
  960. 2021-02-05 09:52:56.900859 [DEBUG] mod_sofia.c:438 Channel sofia/default/150@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  961. 2021-02-05 09:52:56.900859 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/150@hq.ubx.univasa.com
  962. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:60 sofia/default/150@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  963. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:852 (sofia/default/150@hq.ubx.univasa.com) State HANGUP going to sleep
  964. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:619 (sofia/default/150@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  965. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 16 Tot 15008)
  966. 2021-02-05 09:52:56.900859 [DEBUG] switch_core_state_machine.c:938 (sofia/default/150@hq.ubx.univasa.com) State REPORTING
  967. 2021-02-05 09:52:56.900859 [INFO] mod_json_cdr.c:271 Process [ec5bff4e-6797-11eb-b7c9-5b927fa9d4df.cdr.json]
  968. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:174 sofia/default/130@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  969. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:938 (sofia/default/130@hq.ubx.univasa.com) State REPORTING going to sleep
  970. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:610 (sofia/default/130@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  971. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_session.c:1665 Session 15001 (sofia/default/130@hq.ubx.univasa.com) Locked, Waiting on external entities
  972. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:174 sofia/default/110@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  973. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:938 (sofia/default/110@hq.ubx.univasa.com) State REPORTING going to sleep
  974. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:610 (sofia/default/110@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  975. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_session.c:1665 Session 14999 (sofia/default/110@hq.ubx.univasa.com) Locked, Waiting on external entities
  976. 2021-02-05 09:52:56.917794 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING]
  977. 2021-02-05 09:52:56.917794 [DEBUG] switch_channel.c:2047 (sofia/default/100@hq.ubx.univasa.com) Callstate Change RING_WAIT -> ACTIVE
  978. 2021-02-05 09:52:56.917794 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: MANDATORY_IE_MISSING
  979. 2021-02-05 09:52:56.917794 [DEBUG] switch_ivr_menu.c:559 action regex [2] [/^(\d{2,20})$/] [0]
  980. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_file.c:342 File /var/www/html/astpp/upload/pbx/Welcome-MSG1_030221015657.wav sample rate 24000 doesn't match requested rate 8000
  981. 2021-02-05 09:52:56.917794 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  982. 2021-02-05 09:52:56.917794 [NOTICE] switch_core_session.c:1683 Session 14999 (sofia/default/110@hq.ubx.univasa.com) Ended
  983. 2021-02-05 09:52:56.917794 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/110@hq.ubx.univasa.com [CS_DESTROY]
  984. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:741 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 15 Tot 15008)
  985. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:751 (sofia/default/110@hq.ubx.univasa.com) State DESTROY
  986. 2021-02-05 09:52:56.917794 [DEBUG] mod_sofia.c:343 sofia/default/110@hq.ubx.univasa.com SOFIA DESTROY
  987. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:181 sofia/default/110@hq.ubx.univasa.com Standard DESTROY
  988. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:751 (sofia/default/110@hq.ubx.univasa.com) State DESTROY going to sleep
  989. 2021-02-05 09:52:56.917794 [NOTICE] switch_core_session.c:1683 Session 15001 (sofia/default/130@hq.ubx.univasa.com) Ended
  990. 2021-02-05 09:52:56.917794 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/130@hq.ubx.univasa.com [CS_DESTROY]
  991. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:741 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 14 Tot 15008)
  992. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:751 (sofia/default/130@hq.ubx.univasa.com) State DESTROY
  993. 2021-02-05 09:52:56.917794 [DEBUG] mod_sofia.c:343 sofia/default/130@hq.ubx.univasa.com SOFIA DESTROY
  994. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:181 sofia/default/130@hq.ubx.univasa.com Standard DESTROY
  995. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:751 (sofia/default/130@hq.ubx.univasa.com) State DESTROY going to sleep
  996. 2021-02-05 09:52:56.917794 [NOTICE] switch_core_session.c:1683 Session 15002 (sofia/default/140@hq.ubx.univasa.com) Ended
  997. 2021-02-05 09:52:56.917794 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/140@hq.ubx.univasa.com [CS_DESTROY]
  998. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:741 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 13 Tot 15008)
  999. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:751 (sofia/default/140@hq.ubx.univasa.com) State DESTROY
  1000. 2021-02-05 09:52:56.917794 [DEBUG] mod_sofia.c:343 sofia/default/140@hq.ubx.univasa.com SOFIA DESTROY
  1001. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:181 sofia/default/140@hq.ubx.univasa.com Standard DESTROY
  1002. 2021-02-05 09:52:56.917794 [DEBUG] switch_core_state_machine.c:751 (sofia/default/140@hq.ubx.univasa.com) State DESTROY going to sleep
  1003. 2021-02-05 09:52:56.937903 [DEBUG] switch_core_state_machine.c:174 sofia/default/120@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  1004. 2021-02-05 09:52:56.937903 [DEBUG] switch_core_state_machine.c:938 (sofia/default/120@hq.ubx.univasa.com) State REPORTING going to sleep
  1005. 2021-02-05 09:52:56.937903 [DEBUG] switch_core_state_machine.c:610 (sofia/default/120@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  1006. 2021-02-05 09:52:56.937903 [DEBUG] switch_core_session.c:1665 Session 15000 (sofia/default/120@hq.ubx.univasa.com) Locked, Waiting on external entities
  1007. 2021-02-05 09:52:56.937903 [NOTICE] switch_core_session.c:1683 Session 15000 (sofia/default/120@hq.ubx.univasa.com) Ended
  1008. 2021-02-05 09:52:56.937903 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/120@hq.ubx.univasa.com [CS_DESTROY]
  1009. 2021-02-05 09:52:56.937903 [DEBUG] switch_core_state_machine.c:741 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 12 Tot 15008)
  1010. 2021-02-05 09:52:56.937903 [DEBUG] switch_core_state_machine.c:751 (sofia/default/120@hq.ubx.univasa.com) State DESTROY
  1011. 2021-02-05 09:52:56.937903 [DEBUG] mod_sofia.c:343 sofia/default/120@hq.ubx.univasa.com SOFIA DESTROY
  1012. 2021-02-05 09:52:56.937903 [DEBUG] switch_core_state_machine.c:181 sofia/default/120@hq.ubx.univasa.com Standard DESTROY
  1013. 2021-02-05 09:52:56.937903 [DEBUG] switch_core_state_machine.c:751 (sofia/default/120@hq.ubx.univasa.com) State DESTROY going to sleep
  1014. 2021-02-05 09:52:56.958068 [DEBUG] switch_core_state_machine.c:174 sofia/default/150@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  1015. 2021-02-05 09:52:56.958068 [DEBUG] switch_core_state_machine.c:938 (sofia/default/150@hq.ubx.univasa.com) State REPORTING going to sleep
  1016. 2021-02-05 09:52:56.958068 [DEBUG] switch_core_state_machine.c:610 (sofia/default/150@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  1017. 2021-02-05 09:52:56.958068 [DEBUG] switch_core_session.c:1665 Session 15003 (sofia/default/150@hq.ubx.univasa.com) Locked, Waiting on external entities
  1018. 2021-02-05 09:52:56.958068 [NOTICE] switch_core_session.c:1683 Session 15003 (sofia/default/150@hq.ubx.univasa.com) Ended
  1019. 2021-02-05 09:52:56.958068 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/150@hq.ubx.univasa.com [CS_DESTROY]
  1020. 2021-02-05 09:52:56.958068 [DEBUG] switch_core_state_machine.c:741 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 11 Tot 15008)
  1021. 2021-02-05 09:52:56.958068 [DEBUG] switch_core_state_machine.c:751 (sofia/default/150@hq.ubx.univasa.com) State DESTROY
  1022. 2021-02-05 09:52:56.958068 [DEBUG] mod_sofia.c:343 sofia/default/150@hq.ubx.univasa.com SOFIA DESTROY
  1023. 2021-02-05 09:52:56.958068 [DEBUG] switch_core_state_machine.c:181 sofia/default/150@hq.ubx.univasa.com Standard DESTROY
  1024. 2021-02-05 09:52:56.958068 [DEBUG] switch_core_state_machine.c:751 (sofia/default/150@hq.ubx.univasa.com) State DESTROY going to sleep
  1025. 2021-02-05 09:52:57.337750 [WARNING] switch_core_state_machine.c:687 e6ad2640-6797-11eb-b7a0-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1026. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1027. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 11 Tot 15008)
  1028. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1029. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1030. 2021-02-05 09:52:57.337750 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1031. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1032. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1033. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1034. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 11 Tot 15008)
  1035. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1036. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1037. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1038. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1039. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_session.c:1665 Session 14995 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1040. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_session.c:1683 Session 14995 (sofia/default/100@3.85.245.147) Ended
  1041. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1042. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 10 Tot 15008)
  1043. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1044. 2021-02-05 09:52:57.337750 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1045. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1046. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1047. 2021-02-05 09:52:57.337750 [WARNING] switch_core_state_machine.c:687 e6ad422e-6797-11eb-b7a3-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1048. 2021-02-05 09:52:57.337750 [WARNING] switch_core_state_machine.c:687 e6ad3f7c-6797-11eb-b7a2-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1049. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1050. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1051. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 10 Tot 15008)
  1052. 2021-02-05 09:52:57.337750 [WARNING] switch_core_state_machine.c:687 e6aca8dc-6797-11eb-b79e-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1053. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1054. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1055. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1056. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 10 Tot 15008)
  1057. 2021-02-05 09:52:57.337750 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1058. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1059. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1060. 2021-02-05 09:52:57.337750 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1061. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1062. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1063. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1064. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 10 Tot 15008)
  1065. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1066. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1067. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1068. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1069. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_session.c:1665 Session 14997 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1070. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_session.c:1683 Session 14997 (sofia/default/100@3.85.245.147) Ended
  1071. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1072. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 9 Tot 15008)
  1073. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1074. 2021-02-05 09:52:57.337750 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1075. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1076. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1077. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 9 Tot 15008)
  1078. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1079. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1080. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1081. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 9 Tot 15008)
  1082. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1083. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1084. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1085. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1086. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1087. 2021-02-05 09:52:57.337750 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1088. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1089. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_session.c:1665 Session 14994 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1090. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_session.c:1683 Session 14994 (sofia/default/100@3.85.245.147) Ended
  1091. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1092. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 8 Tot 15008)
  1093. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1094. 2021-02-05 09:52:57.337750 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1095. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1096. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1097. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1098. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1099. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1100. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 8 Tot 15008)
  1101. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1102. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1103. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1104. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1105. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_session.c:1665 Session 14998 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1106. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_session.c:1683 Session 14998 (sofia/default/100@3.85.245.147) Ended
  1107. 2021-02-05 09:52:57.337750 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1108. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 7 Tot 15008)
  1109. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1110. 2021-02-05 09:52:57.337750 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1111. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1112. 2021-02-05 09:52:57.337750 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1113. 2021-02-05 09:52:57.378054 [WARNING] switch_core_state_machine.c:687 e6ad3c8e-6797-11eb-b7a1-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1114. 2021-02-05 09:52:57.378054 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1115. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 7 Tot 15008)
  1116. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1117. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1118. 2021-02-05 09:52:57.378054 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1119. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1120. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1121. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1122. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 7 Tot 15008)
  1123. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1124. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1125. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1126. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1127. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_session.c:1665 Session 14996 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1128. 2021-02-05 09:52:57.378054 [NOTICE] switch_core_session.c:1683 Session 14996 (sofia/default/100@3.85.245.147) Ended
  1129. 2021-02-05 09:52:57.378054 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1130. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 6 Tot 15008)
  1131. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1132. 2021-02-05 09:52:57.378054 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1133. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1134. 2021-02-05 09:52:57.378054 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1135. 2021-02-05 09:52:58.957752 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [110@hq.ubx.univasa.com] from ip 117.247.91.162
  1136. 2021-02-05 09:53:00.057781 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [727@3.85.245.147] from ip 185.108.106.155
  1137. 2021-02-05 09:53:00.177766 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [7994@3.85.245.147] from ip 212.102.35.202
  1138. 2021-02-05 09:53:00.217754 [WARNING] sofia_reg.c:2906 Can't find user [727@3.85.245.147] from 185.108.106.155
  1139. You must define a domain called '3.85.245.147' in your directory and add a user with the id="727" attribute
  1140. and you must configure your device to use the proper domain in it's authentication credentials.
  1141. 2021-02-05 09:53:00.217754 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [727@3.85.245.147] from ip 185.108.106.155
  1142. 2021-02-05 09:53:00.297756 [WARNING] sofia_reg.c:2906 Can't find user [7994@3.85.245.147] from 212.102.35.202
  1143. You must define a domain called '3.85.245.147' in your directory and add a user with the id="7994" attribute
  1144. and you must configure your device to use the proper domain in it's authentication credentials.
  1145. 2021-02-05 09:53:00.297756 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [7994@3.85.245.147] from ip 212.102.35.202
  1146. 2021-02-05 09:53:06.917755 [WARNING] switch_core_state_machine.c:687 ec5d1d3e-6797-11eb-b7d2-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1147. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1148. 2021-02-05 09:53:06.917755 [WARNING] switch_core_state_machine.c:687 ec5cbdd0-6797-11eb-b7cf-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1149. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1150. 2021-02-05 09:53:06.917755 [WARNING] switch_core_state_machine.c:687 ec5cb632-6797-11eb-b7cd-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1151. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1152. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 6 Tot 15008)
  1153. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 6 Tot 15008)
  1154. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1155. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1156. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1157. 2021-02-05 09:53:06.917755 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1158. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1159. 2021-02-05 09:53:06.917755 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1160. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 6 Tot 15008)
  1161. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1162. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1163. 2021-02-05 09:53:06.917755 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1164. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1165. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1166. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1167. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 6 Tot 15008)
  1168. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1169. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1170. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1171. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1172. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_session.c:1665 Session 15008 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1173. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_session.c:1683 Session 15008 (sofia/default/100@3.85.245.147) Ended
  1174. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1175. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 5 Tot 15008)
  1176. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1177. 2021-02-05 09:53:06.917755 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1178. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1179. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1180. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1181. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1182. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1183. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 5 Tot 15008)
  1184. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1185. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1186. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1187. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1188. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_session.c:1665 Session 15004 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1189. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_session.c:1683 Session 15004 (sofia/default/100@3.85.245.147) Ended
  1190. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1191. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 4 Tot 15008)
  1192. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1193. 2021-02-05 09:53:06.917755 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1194. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1195. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1196. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1197. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1198. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1199. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 4 Tot 15008)
  1200. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1201. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1202. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1203. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1204. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_session.c:1665 Session 15006 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1205. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_session.c:1683 Session 15006 (sofia/default/100@3.85.245.147) Ended
  1206. 2021-02-05 09:53:06.917755 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1207. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 3 Tot 15008)
  1208. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1209. 2021-02-05 09:53:06.917755 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1210. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1211. 2021-02-05 09:53:06.917755 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1212. 2021-02-05 09:53:06.938005 [WARNING] switch_core_state_machine.c:687 ec5cba74-6797-11eb-b7ce-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1213. 2021-02-05 09:53:06.938005 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1214. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 3 Tot 15008)
  1215. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1216. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1217. 2021-02-05 09:53:06.938005 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1218. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1219. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1220. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1221. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 3 Tot 15008)
  1222. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1223. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1224. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1225. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1226. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_session.c:1665 Session 15005 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1227. 2021-02-05 09:53:06.938005 [NOTICE] switch_core_session.c:1683 Session 15005 (sofia/default/100@3.85.245.147) Ended
  1228. 2021-02-05 09:53:06.938005 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1229. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 2 Tot 15008)
  1230. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1231. 2021-02-05 09:53:06.938005 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1232. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1233. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1234. 2021-02-05 09:53:06.938005 [WARNING] switch_core_state_machine.c:687 ec5cc08c-6797-11eb-b7d0-5b927fa9d4df sofia/default/100@3.85.245.147 Abandoned
  1235. 2021-02-05 09:53:06.938005 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/default/100@3.85.245.147 [CS_NEW] [WRONG_CALL_STATE]
  1236. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_HANGUP (Cur 2 Tot 15008)
  1237. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@3.85.245.147) Callstate Change DOWN -> HANGUP
  1238. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP
  1239. 2021-02-05 09:53:06.938005 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@3.85.245.147 hanging up, cause: WRONG_CALL_STATE
  1240. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@3.85.245.147 Standard HANGUP, cause: WRONG_CALL_STATE
  1241. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@3.85.245.147) State HANGUP going to sleep
  1242. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@3.85.245.147) State Change CS_HANGUP -> CS_REPORTING
  1243. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_REPORTING (Cur 2 Tot 15008)
  1244. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING
  1245. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@3.85.245.147 Standard REPORTING, cause: WRONG_CALL_STATE
  1246. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@3.85.245.147) State REPORTING going to sleep
  1247. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@3.85.245.147) State Change CS_REPORTING -> CS_DESTROY
  1248. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_session.c:1665 Session 15007 (sofia/default/100@3.85.245.147) Locked, Waiting on external entities
  1249. 2021-02-05 09:53:06.938005 [NOTICE] switch_core_session.c:1683 Session 15007 (sofia/default/100@3.85.245.147) Ended
  1250. 2021-02-05 09:53:06.938005 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@3.85.245.147 [CS_DESTROY]
  1251. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@3.85.245.147) Running State Change CS_DESTROY (Cur 1 Tot 15008)
  1252. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY
  1253. 2021-02-05 09:53:06.938005 [DEBUG] mod_sofia.c:343 sofia/default/100@3.85.245.147 SOFIA DESTROY
  1254. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@3.85.245.147 Standard DESTROY
  1255. 2021-02-05 09:53:06.938005 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@3.85.245.147) State DESTROY going to sleep
  1256. 2021-02-05 09:53:07.077749 [DEBUG] switch_ivr_play_say.c:1942 done playing file /var/www/html/astpp/upload/pbx/Welcome-MSG1_030221015657.wav
  1257. 2021-02-05 09:53:07.077749 [DEBUG] switch_ivr_menu.c:377 waiting for 20/20 digits t/o 2000
  1258. 2021-02-05 09:53:07.397769 [DEBUG] switch_rtp.c:7534 RTP RECV DTMF 0:960
  1259. 2021-02-05 09:53:07.397769 [INFO] switch_channel.c:515 RECV DTMF 0:960
  1260. 2021-02-05 09:53:08.157748 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [110@hq.ubx.univasa.com] from ip 117.247.91.162
  1261. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_menu.c:424 digits '0'
  1262. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_menu.c:577 IVR action on menu 'HQ CC IVR0' matched '0' param 'bridge {absolute_codec_string=^^:PCMA:PCMU:G729,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=5}[call_timeout=5,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=110,leg_timeout=5,leg_delay_start=0]sofia/${sofia_profile_name}/110@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=120,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/120@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=130,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/130@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=140,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/140@${domain_name},[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=150,leg_timeout=0,leg_delay_start=0]sofia/${sofia_profile_name}/150@${domain_name},'
  1263. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
  1264. EXECUTE sofia/default/100@hq.ubx.univasa.com bridge({absolute_codec_string=^^:PCMA:PCMU:G729,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=5}[call_timeout=5,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=110,leg_timeout=5,leg_delay_start=0]sofia/default/110@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=120,leg_timeout=0,leg_delay_start=0]sofia/default/120@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=130,leg_timeout=0,leg_delay_start=0]sofia/default/130@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=140,leg_timeout=0,leg_delay_start=0]sofia/default/140@hq.ubx.univasa.com,[call_timeout=0,group_confirm_key=exec,group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/astpp.confirm.lua,confirm=true,announcement=no_file,dialed_user=150,leg_timeout=0,leg_delay_start=0]sofia/default/150@hq.ubx.univasa.com,)
  1265. 2021-02-05 09:53:09.398106 [DEBUG] switch_channel.c:1823 (sofia/default/100@hq.ubx.univasa.com) Callstate Change ACTIVE -> RING_WAIT
  1266. 2021-02-05 09:53:09.398106 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [domain_name]=[hq.ubx.univasa.com] to event
  1267. 2021-02-05 09:53:09.398106 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [sip_to_host]=[hq.ubx.univasa.com] to event
  1268. 2021-02-05 09:53:09.398106 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [presence_data]=[x|||Ebunoluwa(5169063157)|||^018887088.* // 018887088 // 0.00000||||||DID|||117.247.91.162] to event
  1269. 2021-02-05 09:53:09.398106 [DEBUG] switch_channel.c:1250 sofia/default/100@hq.ubx.univasa.com EXPORTING[export_vars] [call_type]=[10] to event
  1270. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1271. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  1272. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/110@hq.ubx.univasa.com [f3de1216-6797-11eb-b7ed-5b927fa9d4df]
  1273. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:4819 (sofia/default/110@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  1274. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:2963 sofia/default/110@hq.ubx.univasa.com Setting leg timeout to 5
  1275. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  1276. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 2 Tot 15009)
  1277. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/120@hq.ubx.univasa.com [f3de26ac-6797-11eb-b7f3-5b927fa9d4df]
  1278. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/110@hq.ubx.univasa.com) State INIT
  1279. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:4819 (sofia/default/120@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  1280. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:90 sofia/default/110@hq.ubx.univasa.com SOFIA INIT
  1281. 2021-02-05 09:53:09.398106 [DEBUG] sofia_glue.c:1295 sofia/default/110@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  1282. Local SDP:
  1283. v=0
  1284. o=FreeSWITCH 1612491239 1612491240 IN IP4 3.85.245.147
  1285. s=FreeSWITCH
  1286. c=IN IP4 3.85.245.147
  1287. t=0 0
  1288. m=audio 27550 RTP/AVP 8 0 18 101
  1289. a=rtpmap:8 PCMA/8000
  1290. a=rtpmap:0 PCMU/8000
  1291. a=rtpmap:18 G729/8000
  1292. a=rtpmap:101 telephone-event/8000
  1293. a=fmtp:101 0-16
  1294. a=ptime:20
  1295. a=sendrecv
  1296.  
  1297. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:40 sofia/default/110@hq.ubx.univasa.com Standard INIT
  1298. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:48 (sofia/default/110@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  1299. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/110@hq.ubx.univasa.com) State INIT going to sleep
  1300. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 3 Tot 15010)
  1301. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  1302. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/110@hq.ubx.univasa.com) State ROUTING
  1303. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:143 sofia/default/110@hq.ubx.univasa.com SOFIA ROUTING
  1304. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:67 (sofia/default/110@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1305. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/110@hq.ubx.univasa.com) State ROUTING going to sleep
  1306. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 15010)
  1307. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/130@hq.ubx.univasa.com [f3de3e76-6797-11eb-b7f9-5b927fa9d4df]
  1308. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/110@hq.ubx.univasa.com) State CONSUME_MEDIA
  1309. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:4819 (sofia/default/130@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  1310. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/110@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  1311. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 4 Tot 15011)
  1312. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/120@hq.ubx.univasa.com) State INIT
  1313. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:90 sofia/default/120@hq.ubx.univasa.com SOFIA INIT
  1314. 2021-02-05 09:53:09.398106 [DEBUG] sofia_glue.c:1295 sofia/default/120@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  1315. Local SDP:
  1316. v=0
  1317. o=FreeSWITCH 1612502131 1612502132 IN IP4 3.85.245.147
  1318. s=FreeSWITCH
  1319. c=IN IP4 3.85.245.147
  1320. t=0 0
  1321. m=audio 16658 RTP/AVP 8 0 18 101
  1322. a=rtpmap:8 PCMA/8000
  1323. a=rtpmap:0 PCMU/8000
  1324. a=rtpmap:18 G729/8000
  1325. a=rtpmap:101 telephone-event/8000
  1326. a=fmtp:101 0-16
  1327. a=ptime:20
  1328. a=sendrecv
  1329.  
  1330. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:40 sofia/default/120@hq.ubx.univasa.com Standard INIT
  1331. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:48 (sofia/default/120@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  1332. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/120@hq.ubx.univasa.com) State INIT going to sleep
  1333. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 4 Tot 15011)
  1334. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  1335. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/120@hq.ubx.univasa.com) State ROUTING
  1336. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:143 sofia/default/120@hq.ubx.univasa.com SOFIA ROUTING
  1337. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:67 (sofia/default/120@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1338. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/120@hq.ubx.univasa.com) State ROUTING going to sleep
  1339. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 15011)
  1340. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/140@hq.ubx.univasa.com [f3de55c8-6797-11eb-b7ff-5b927fa9d4df]
  1341. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/120@hq.ubx.univasa.com) State CONSUME_MEDIA
  1342. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/120@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  1343. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:4819 (sofia/default/140@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  1344. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  1345. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/150@hq.ubx.univasa.com [f3de69fa-6797-11eb-b805-5b927fa9d4df]
  1346. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:4819 (sofia/default/150@hq.ubx.univasa.com) State Change CS_NEW -> CS_INIT
  1347. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 6 Tot 15013)
  1348. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/130@hq.ubx.univasa.com) State INIT
  1349. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:90 sofia/default/130@hq.ubx.univasa.com SOFIA INIT
  1350. 2021-02-05 09:53:09.398106 [DEBUG] sofia_glue.c:1295 sofia/default/130@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  1351. Local SDP:
  1352. v=0
  1353. o=FreeSWITCH 1612486569 1612486570 IN IP4 3.85.245.147
  1354. s=FreeSWITCH
  1355. c=IN IP4 3.85.245.147
  1356. t=0 0
  1357. m=audio 32220 RTP/AVP 8 0 18 101
  1358. a=rtpmap:8 PCMA/8000
  1359. a=rtpmap:0 PCMU/8000
  1360. a=rtpmap:18 G729/8000
  1361. a=rtpmap:101 telephone-event/8000
  1362. a=fmtp:101 0-16
  1363. a=ptime:20
  1364. a=sendrecv
  1365.  
  1366. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:40 sofia/default/130@hq.ubx.univasa.com Standard INIT
  1367. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:48 (sofia/default/130@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  1368. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/130@hq.ubx.univasa.com) State INIT going to sleep
  1369. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 6 Tot 15013)
  1370. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/130@hq.ubx.univasa.com) State ROUTING
  1371. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:143 sofia/default/130@hq.ubx.univasa.com SOFIA ROUTING
  1372. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:67 (sofia/default/130@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1373. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/130@hq.ubx.univasa.com) State ROUTING going to sleep
  1374. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 15013)
  1375. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/130@hq.ubx.univasa.com) State CONSUME_MEDIA
  1376. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/130@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  1377. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 6 Tot 15013)
  1378. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/140@hq.ubx.univasa.com) State INIT
  1379. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:90 sofia/default/140@hq.ubx.univasa.com SOFIA INIT
  1380. 2021-02-05 09:53:09.398106 [DEBUG] sofia_glue.c:1295 sofia/default/140@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  1381. Local SDP:
  1382. v=0
  1383. o=FreeSWITCH 1612494995 1612494996 IN IP4 3.85.245.147
  1384. s=FreeSWITCH
  1385. c=IN IP4 3.85.245.147
  1386. t=0 0
  1387. m=audio 23794 RTP/AVP 8 0 18 101
  1388. a=rtpmap:8 PCMA/8000
  1389. a=rtpmap:0 PCMU/8000
  1390. a=rtpmap:18 G729/8000
  1391. a=rtpmap:101 telephone-event/8000
  1392. a=fmtp:101 0-16
  1393. a=ptime:20
  1394. a=sendrecv
  1395.  
  1396. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:40 sofia/default/140@hq.ubx.univasa.com Standard INIT
  1397. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:48 (sofia/default/140@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  1398. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/140@hq.ubx.univasa.com) State INIT going to sleep
  1399. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 6 Tot 15013)
  1400. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/140@hq.ubx.univasa.com) State ROUTING
  1401. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:143 sofia/default/140@hq.ubx.univasa.com SOFIA ROUTING
  1402. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:67 (sofia/default/140@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1403. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/140@hq.ubx.univasa.com) State ROUTING going to sleep
  1404. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 15013)
  1405. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/140@hq.ubx.univasa.com) State CONSUME_MEDIA
  1406. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/140@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  1407. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_INIT (Cur 6 Tot 15013)
  1408. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/150@hq.ubx.univasa.com) State INIT
  1409. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:90 sofia/default/150@hq.ubx.univasa.com SOFIA INIT
  1410. 2021-02-05 09:53:09.398106 [DEBUG] sofia_glue.c:1295 sofia/default/150@hq.ubx.univasa.com sending invite version: 1.6.20  64bit
  1411. Local SDP:
  1412. v=0
  1413. o=FreeSWITCH 1612494423 1612494424 IN IP4 3.85.245.147
  1414. s=FreeSWITCH
  1415. c=IN IP4 3.85.245.147
  1416. t=0 0
  1417. m=audio 24366 RTP/AVP 8 0 18 101
  1418. a=rtpmap:8 PCMA/8000
  1419. a=rtpmap:0 PCMU/8000
  1420. a=rtpmap:18 G729/8000
  1421. a=rtpmap:101 telephone-event/8000
  1422. a=fmtp:101 0-16
  1423. a=ptime:20
  1424. a=sendrecv
  1425.  
  1426. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:40 sofia/default/150@hq.ubx.univasa.com Standard INIT
  1427. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:48 (sofia/default/150@hq.ubx.univasa.com) State Change CS_INIT -> CS_ROUTING
  1428. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:627 (sofia/default/150@hq.ubx.univasa.com) State INIT going to sleep
  1429. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_ROUTING (Cur 6 Tot 15013)
  1430. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/150@hq.ubx.univasa.com) State ROUTING
  1431. 2021-02-05 09:53:09.398106 [DEBUG] mod_sofia.c:143 sofia/default/150@hq.ubx.univasa.com SOFIA ROUTING
  1432. 2021-02-05 09:53:09.398106 [DEBUG] switch_ivr_originate.c:67 (sofia/default/150@hq.ubx.univasa.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1433. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:643 (sofia/default/150@hq.ubx.univasa.com) State ROUTING going to sleep
  1434. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 15013)
  1435. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/150@hq.ubx.univasa.com) State CONSUME_MEDIA
  1436. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:662 (sofia/default/150@hq.ubx.univasa.com) State CONSUME_MEDIA going to sleep
  1437. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [f3df2f34-6797-11eb-b809-5b927fa9d4df]
  1438. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:7084 Channel sofia/default/130@hq.ubx.univasa.com entering state [calling][0]
  1439. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 7 Tot 15014)
  1440. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  1441. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [f3df3b8c-6797-11eb-b80a-5b927fa9d4df]
  1442. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  1443. 2021-02-05 09:53:09.398106 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [110@hq.ubx.univasa.com] from ip 3.85.245.147
  1444. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  1445. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:7084 Channel sofia/default/140@hq.ubx.univasa.com entering state [calling][0]
  1446. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 8 Tot 15015)
  1447. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  1448. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  1449. 2021-02-05 09:53:09.398106 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [120@hq.ubx.univasa.com] from ip 3.85.245.147
  1450. 2021-02-05 09:53:09.398106 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  1451. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:2334 detaching session f3df2f34-6797-11eb-b809-5b927fa9d4df
  1452. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:7084 Channel sofia/default/150@hq.ubx.univasa.com entering state [calling][0]
  1453. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [f3dfa824-6797-11eb-b80d-5b927fa9d4df]
  1454. 2021-02-05 09:53:09.398106 [DEBUG] sofia.c:2334 detaching session f3df3b8c-6797-11eb-b80a-5b927fa9d4df
  1455. 2021-02-05 09:53:09.398106 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [f3dfac52-6797-11eb-b80e-5b927fa9d4df]
  1456. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:7084 Channel sofia/default/120@hq.ubx.univasa.com entering state [calling][0]
  1457. 2021-02-05 09:53:09.417872 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 10 Tot 15017)
  1458. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  1459. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  1460. 2021-02-05 09:53:09.417872 [NOTICE] switch_channel.c:1104 New Channel sofia/default/100@3.85.245.147 [f3dfc9da-6797-11eb-b810-5b927fa9d4df]
  1461. 2021-02-05 09:53:09.417872 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 11 Tot 15018)
  1462. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  1463. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  1464. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:7084 Channel sofia/default/110@hq.ubx.univasa.com entering state [calling][0]
  1465. 2021-02-05 09:53:09.417872 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [130@hq.ubx.univasa.com] from ip 3.85.245.147
  1466. 2021-02-05 09:53:09.417872 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  1467. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:2334 detaching session f3dfa824-6797-11eb-b80d-5b927fa9d4df
  1468. 2021-02-05 09:53:09.417872 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [140@hq.ubx.univasa.com] from ip 3.85.245.147
  1469. 2021-02-05 09:53:09.417872 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  1470. 2021-02-05 09:53:09.417872 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@3.85.245.147) Running State Change CS_NEW (Cur 11 Tot 15018)
  1471. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:9873 sofia/default/100@3.85.245.147 receiving invite from 3.85.245.147:5060 version: 1.6.20  64bit
  1472. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:2334 detaching session f3dfac52-6797-11eb-b80e-5b927fa9d4df
  1473. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:10044 IP 3.85.245.147 Rejected by acl "default". Falling back to Digest auth.
  1474. 2021-02-05 09:53:09.417872 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [150@hq.ubx.univasa.com] from ip 3.85.245.147
  1475. 2021-02-05 09:53:09.417872 [DEBUG] switch_core_state_machine.c:603 (sofia/default/100@3.85.245.147) State NEW
  1476. 2021-02-05 09:53:09.417872 [DEBUG] sofia.c:2334 detaching session f3dfc9da-6797-11eb-b810-5b927fa9d4df
  1477. 2021-02-05 09:53:09.438130 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  1478. 2021-02-05 09:53:09.438130 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/120@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  1479. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 15018)
  1480. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:850 (sofia/default/120@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  1481. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:852 (sofia/default/120@hq.ubx.univasa.com) State HANGUP
  1482. 2021-02-05 09:53:09.438130 [DEBUG] mod_sofia.c:438 Channel sofia/default/120@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  1483. 2021-02-05 09:53:09.438130 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/120@hq.ubx.univasa.com
  1484. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:60 sofia/default/120@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  1485. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:852 (sofia/default/120@hq.ubx.univasa.com) State HANGUP going to sleep
  1486. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:619 (sofia/default/120@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  1487. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:584 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 15018)
  1488. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:938 (sofia/default/120@hq.ubx.univasa.com) State REPORTING
  1489. 2021-02-05 09:53:09.438130 [INFO] mod_json_cdr.c:271 Process [f3de26ac-6797-11eb-b7f3-5b927fa9d4df.cdr.json]
  1490. 2021-02-05 09:53:09.438130 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  1491. 2021-02-05 09:53:09.438130 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/140@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  1492. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 15018)
  1493. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:850 (sofia/default/140@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  1494. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:852 (sofia/default/140@hq.ubx.univasa.com) State HANGUP
  1495. 2021-02-05 09:53:09.438130 [DEBUG] mod_sofia.c:438 Channel sofia/default/140@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  1496. 2021-02-05 09:53:09.438130 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/140@hq.ubx.univasa.com
  1497. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:60 sofia/default/140@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  1498. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:852 (sofia/default/140@hq.ubx.univasa.com) State HANGUP going to sleep
  1499. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:619 (sofia/default/140@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  1500. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:584 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 15018)
  1501. 2021-02-05 09:53:09.438130 [DEBUG] switch_core_state_machine.c:938 (sofia/default/140@hq.ubx.univasa.com) State REPORTING
  1502. 2021-02-05 09:53:09.438130 [INFO] mod_json_cdr.c:271 Process [f3de55c8-6797-11eb-b7ff-5b927fa9d4df.cdr.json]
  1503. 2021-02-05 09:53:09.460443 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  1504. 2021-02-05 09:53:09.460443 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/130@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  1505. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 15018)
  1506. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:850 (sofia/default/130@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  1507. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:852 (sofia/default/130@hq.ubx.univasa.com) State HANGUP
  1508. 2021-02-05 09:53:09.460443 [DEBUG] mod_sofia.c:438 Channel sofia/default/130@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  1509. 2021-02-05 09:53:09.460443 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/130@hq.ubx.univasa.com
  1510. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:60 sofia/default/130@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  1511. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:852 (sofia/default/130@hq.ubx.univasa.com) State HANGUP going to sleep
  1512. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:619 (sofia/default/130@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  1513. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:584 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 15018)
  1514. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:938 (sofia/default/130@hq.ubx.univasa.com) State REPORTING
  1515. 2021-02-05 09:53:09.460443 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  1516. 2021-02-05 09:53:09.460443 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/110@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  1517. 2021-02-05 09:53:09.460443 [INFO] mod_json_cdr.c:271 Process [f3de3e76-6797-11eb-b7f9-5b927fa9d4df.cdr.json]
  1518. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 15018)
  1519. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:850 (sofia/default/110@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  1520. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:852 (sofia/default/110@hq.ubx.univasa.com) State HANGUP
  1521. 2021-02-05 09:53:09.460443 [DEBUG] mod_sofia.c:438 Channel sofia/default/110@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  1522. 2021-02-05 09:53:09.460443 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/110@hq.ubx.univasa.com
  1523. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:60 sofia/default/110@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  1524. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:852 (sofia/default/110@hq.ubx.univasa.com) State HANGUP going to sleep
  1525. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:619 (sofia/default/110@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  1526. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:584 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 15018)
  1527. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:938 (sofia/default/110@hq.ubx.univasa.com) State REPORTING
  1528. 2021-02-05 09:53:09.460443 [INFO] mod_json_cdr.c:271 Process [f3de1216-6797-11eb-b7ed-5b927fa9d4df.cdr.json]
  1529. 2021-02-05 09:53:09.460443 [ERR] sofia_reg.c:2616 Cannot locate any authentication credentials to complete an authentication request for realm '"3.85.245.147"'
  1530. 2021-02-05 09:53:09.460443 [NOTICE] sofia_reg.c:2639 Hangup sofia/default/150@hq.ubx.univasa.com [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
  1531. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 11 Tot 15018)
  1532. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:850 (sofia/default/150@hq.ubx.univasa.com) Callstate Change DOWN -> HANGUP
  1533. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:852 (sofia/default/150@hq.ubx.univasa.com) State HANGUP
  1534. 2021-02-05 09:53:09.460443 [DEBUG] mod_sofia.c:438 Channel sofia/default/150@hq.ubx.univasa.com hanging up, cause: MANDATORY_IE_MISSING
  1535. 2021-02-05 09:53:09.460443 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/150@hq.ubx.univasa.com
  1536. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:60 sofia/default/150@hq.ubx.univasa.com Standard HANGUP, cause: MANDATORY_IE_MISSING
  1537. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:852 (sofia/default/150@hq.ubx.univasa.com) State HANGUP going to sleep
  1538. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:619 (sofia/default/150@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  1539. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:584 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 11 Tot 15018)
  1540. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_state_machine.c:938 (sofia/default/150@hq.ubx.univasa.com) State REPORTING
  1541. 2021-02-05 09:53:09.460443 [INFO] mod_json_cdr.c:271 Process [f3de69fa-6797-11eb-b805-5b927fa9d4df.cdr.json]
  1542. 2021-02-05 09:53:09.460443 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING]
  1543. 2021-02-05 09:53:09.460443 [DEBUG] switch_channel.c:2047 (sofia/default/100@hq.ubx.univasa.com) Callstate Change RING_WAIT -> ACTIVE
  1544. 2021-02-05 09:53:09.460443 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: MANDATORY_IE_MISSING
  1545. 2021-02-05 09:53:09.460443 [DEBUG] switch_ivr_menu.c:559 action regex [0] [/^(\d{2,20})$/] [0]
  1546. 2021-02-05 09:53:09.460443 [DEBUG] switch_core_file.c:342 File /var/www/html/astpp/upload/pbx/Welcome-MSG1_030221015657.wav sample rate 24000 doesn't match requested rate 8000
  1547. 2021-02-05 09:53:09.460443 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  1548. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:174 sofia/default/140@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  1549. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:938 (sofia/default/140@hq.ubx.univasa.com) State REPORTING going to sleep
  1550. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:610 (sofia/default/140@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  1551. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_session.c:1665 Session 15012 (sofia/default/140@hq.ubx.univasa.com) Locked, Waiting on external entities
  1552. 2021-02-05 09:53:09.478571 [NOTICE] switch_core_session.c:1683 Session 15012 (sofia/default/140@hq.ubx.univasa.com) Ended
  1553. 2021-02-05 09:53:09.478571 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/140@hq.ubx.univasa.com [CS_DESTROY]
  1554. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:741 (sofia/default/140@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 10 Tot 15018)
  1555. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:751 (sofia/default/140@hq.ubx.univasa.com) State DESTROY
  1556. 2021-02-05 09:53:09.478571 [DEBUG] mod_sofia.c:343 sofia/default/140@hq.ubx.univasa.com SOFIA DESTROY
  1557. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:181 sofia/default/140@hq.ubx.univasa.com Standard DESTROY
  1558. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:751 (sofia/default/140@hq.ubx.univasa.com) State DESTROY going to sleep
  1559. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:174 sofia/default/130@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  1560. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:938 (sofia/default/130@hq.ubx.univasa.com) State REPORTING going to sleep
  1561. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:610 (sofia/default/130@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  1562. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_session.c:1665 Session 15011 (sofia/default/130@hq.ubx.univasa.com) Locked, Waiting on external entities
  1563. 2021-02-05 09:53:09.478571 [NOTICE] switch_core_session.c:1683 Session 15011 (sofia/default/130@hq.ubx.univasa.com) Ended
  1564. 2021-02-05 09:53:09.478571 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/130@hq.ubx.univasa.com [CS_DESTROY]
  1565. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:741 (sofia/default/130@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 9 Tot 15018)
  1566. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:751 (sofia/default/130@hq.ubx.univasa.com) State DESTROY
  1567. 2021-02-05 09:53:09.478571 [DEBUG] mod_sofia.c:343 sofia/default/130@hq.ubx.univasa.com SOFIA DESTROY
  1568. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:181 sofia/default/130@hq.ubx.univasa.com Standard DESTROY
  1569. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:751 (sofia/default/130@hq.ubx.univasa.com) State DESTROY going to sleep
  1570. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:174 sofia/default/150@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  1571. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:938 (sofia/default/150@hq.ubx.univasa.com) State REPORTING going to sleep
  1572. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:610 (sofia/default/150@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  1573. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_session.c:1665 Session 15013 (sofia/default/150@hq.ubx.univasa.com) Locked, Waiting on external entities
  1574. 2021-02-05 09:53:09.478571 [NOTICE] switch_core_session.c:1683 Session 15013 (sofia/default/150@hq.ubx.univasa.com) Ended
  1575. 2021-02-05 09:53:09.478571 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/150@hq.ubx.univasa.com [CS_DESTROY]
  1576. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:741 (sofia/default/150@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 8 Tot 15018)
  1577. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:751 (sofia/default/150@hq.ubx.univasa.com) State DESTROY
  1578. 2021-02-05 09:53:09.478571 [DEBUG] mod_sofia.c:343 sofia/default/150@hq.ubx.univasa.com SOFIA DESTROY
  1579. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:181 sofia/default/150@hq.ubx.univasa.com Standard DESTROY
  1580. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:751 (sofia/default/150@hq.ubx.univasa.com) State DESTROY going to sleep
  1581. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:174 sofia/default/120@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  1582. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:938 (sofia/default/120@hq.ubx.univasa.com) State REPORTING going to sleep
  1583. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:610 (sofia/default/120@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  1584. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_session.c:1665 Session 15010 (sofia/default/120@hq.ubx.univasa.com) Locked, Waiting on external entities
  1585. 2021-02-05 09:53:09.478571 [NOTICE] switch_core_session.c:1683 Session 15010 (sofia/default/120@hq.ubx.univasa.com) Ended
  1586. 2021-02-05 09:53:09.478571 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/120@hq.ubx.univasa.com [CS_DESTROY]
  1587. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:741 (sofia/default/120@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 7 Tot 15018)
  1588. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:751 (sofia/default/120@hq.ubx.univasa.com) State DESTROY
  1589. 2021-02-05 09:53:09.478571 [DEBUG] mod_sofia.c:343 sofia/default/120@hq.ubx.univasa.com SOFIA DESTROY
  1590. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:181 sofia/default/120@hq.ubx.univasa.com Standard DESTROY
  1591. 2021-02-05 09:53:09.478571 [DEBUG] switch_core_state_machine.c:751 (sofia/default/120@hq.ubx.univasa.com) State DESTROY going to sleep
  1592. 2021-02-05 09:53:09.497780 [DEBUG] switch_core_state_machine.c:174 sofia/default/110@hq.ubx.univasa.com Standard REPORTING, cause: MANDATORY_IE_MISSING
  1593. 2021-02-05 09:53:09.497780 [DEBUG] switch_core_state_machine.c:938 (sofia/default/110@hq.ubx.univasa.com) State REPORTING going to sleep
  1594. 2021-02-05 09:53:09.497780 [DEBUG] switch_core_state_machine.c:610 (sofia/default/110@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  1595. 2021-02-05 09:53:09.497780 [DEBUG] switch_core_session.c:1665 Session 15009 (sofia/default/110@hq.ubx.univasa.com) Locked, Waiting on external entities
  1596. 2021-02-05 09:53:09.497780 [NOTICE] switch_core_session.c:1683 Session 15009 (sofia/default/110@hq.ubx.univasa.com) Ended
  1597. 2021-02-05 09:53:09.497780 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/110@hq.ubx.univasa.com [CS_DESTROY]
  1598. 2021-02-05 09:53:09.497780 [DEBUG] switch_core_state_machine.c:741 (sofia/default/110@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 6 Tot 15018)
  1599. 2021-02-05 09:53:09.497780 [DEBUG] switch_core_state_machine.c:751 (sofia/default/110@hq.ubx.univasa.com) State DESTROY
  1600. 2021-02-05 09:53:09.497780 [DEBUG] mod_sofia.c:343 sofia/default/110@hq.ubx.univasa.com SOFIA DESTROY
  1601. 2021-02-05 09:53:09.497780 [DEBUG] switch_core_state_machine.c:181 sofia/default/110@hq.ubx.univasa.com Standard DESTROY
  1602. 2021-02-05 09:53:09.497780 [DEBUG] switch_core_state_machine.c:751 (sofia/default/110@hq.ubx.univasa.com) State DESTROY going to sleep
  1603. 2021-02-05 09:53:13.837751 [NOTICE] sofia.c:1012 Hangup sofia/default/100@hq.ubx.univasa.com [CS_EXECUTE] [NORMAL_CLEARING]
  1604. 2021-02-05 09:53:13.837751 [DEBUG] mod_hash.c:297 Usage for CPS_5169063157_CPS_user_5169063157 is now 0
  1605. 2021-02-05 09:53:13.837751 [DEBUG] switch_ivr_play_say.c:1942 done playing file /var/www/html/astpp/upload/pbx/Welcome-MSG1_030221015657.wav
  1606. 2021-02-05 09:53:13.837751 [DEBUG] switch_ivr_menu.c:377 waiting for 20/20 digits t/o 2000
  1607. 2021-02-05 09:53:13.837751 [DEBUG] switch_ivr_menu.c:424 digits ''
  1608. 2021-02-05 09:53:13.837751 [DEBUG] switch_ivr_menu.c:661 IVR menu 'HQ CC IVR0' no input detected
  1609. 2021-02-05 09:53:13.837751 [DEBUG] switch_ivr_menu.c:673 exit-sound '(null)'
  1610. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_session.c:2815 sofia/default/100@hq.ubx.univasa.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1611. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:650 (sofia/default/100@hq.ubx.univasa.com) State EXECUTE going to sleep
  1612. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@hq.ubx.univasa.com) Running State Change CS_HANGUP (Cur 6 Tot 15018)
  1613. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@hq.ubx.univasa.com) Callstate Change ACTIVE -> HANGUP
  1614. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@hq.ubx.univasa.com) State HANGUP
  1615. 2021-02-05 09:53:13.837751 [DEBUG] mod_sofia.c:438 Channel sofia/default/100@hq.ubx.univasa.com hanging up, cause: NORMAL_CLEARING
  1616. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@hq.ubx.univasa.com Standard HANGUP, cause: NORMAL_CLEARING
  1617. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:852 (sofia/default/100@hq.ubx.univasa.com) State HANGUP going to sleep
  1618. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:619 (sofia/default/100@hq.ubx.univasa.com) State Change CS_HANGUP -> CS_REPORTING
  1619. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:584 (sofia/default/100@hq.ubx.univasa.com) Running State Change CS_REPORTING (Cur 6 Tot 15018)
  1620. 2021-02-05 09:53:13.837751 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@hq.ubx.univasa.com) State REPORTING
  1621. 2021-02-05 09:53:13.837751 [INFO] mod_json_cdr.c:271 Process [e2aa51da-6797-11eb-b755-5b927fa9d4df.cdr.json]
  1622. 2021-02-05 09:53:13.878085 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@hq.ubx.univasa.com Standard REPORTING, cause: NORMAL_CLEARING
  1623. 2021-02-05 09:53:13.878085 [DEBUG] switch_core_state_machine.c:938 (sofia/default/100@hq.ubx.univasa.com) State REPORTING going to sleep
  1624. 2021-02-05 09:53:13.878085 [DEBUG] switch_core_state_machine.c:610 (sofia/default/100@hq.ubx.univasa.com) State Change CS_REPORTING -> CS_DESTROY
  1625. 2021-02-05 09:53:13.878085 [DEBUG] switch_core_session.c:1665 Session 14988 (sofia/default/100@hq.ubx.univasa.com) Locked, Waiting on external entities
  1626. 2021-02-05 09:53:13.878085 [NOTICE] switch_core_session.c:1683 Session 14988 (sofia/default/100@hq.ubx.univasa.com) Ended
  1627. 2021-02-05 09:53:13.878085 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/100@hq.ubx.univasa.com [CS_DESTROY]
  1628. 2021-02-05 09:53:13.878085 [DEBUG] switch_core_state_machine.c:741 (sofia/default/100@hq.ubx.univasa.com) Running State Change CS_DESTROY (Cur 5 Tot 15018)
  1629. 2021-02-05 09:53:13.878085 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@hq.ubx.univasa.com) State DESTROY
  1630. 2021-02-05 09:53:13.878085 [DEBUG] mod_sofia.c:343 sofia/default/100@hq.ubx.univasa.com SOFIA DESTROY
  1631. 2021-02-05 09:53:13.878085 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@hq.ubx.univasa.com Standard DESTROY
  1632. 2021-02-05 09:53:13.878085 [DEBUG] switch_core_state_machine.c:751 (sofia/default/100@hq.ubx.univasa.com) State DESTROY going to sleep
  1633.  

Reply to "SIP -> DID -> IVR -> Ring group."