freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> 2023-11-10 03:58:32.274356 91.10% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9000100044@cluster.astppbilling.org:5073 [8d73be50-47b0-4206-ab18-e79df024803c] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9000100044@cluster.astppbilling.org:5073) Running State Change CS_NEW (Cur 1 Tot 12570) 2023-11-10 03:58:32.274356 91.10% [INFO] sofia.c:10462 sofia/default/9000100044@cluster.astppbilling.org:5073 receiving invite from 78.47.101.152:5073 version: 1.10.7 -release 64bit call-id: DLGCH_ATQ3AgkdO2ACNCBaHiMeOQIdLBQdDh1iADcgXQkeHWIBNzMFHQ0WPgEaJEA- 2023-11-10 03:58:32.274356 91.10% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 78.47.101.152:0. 2023-11-10 03:58:32.274356 91.10% [DEBUG] sofia.c:10585 IP 78.47.101.152 Approved by acl "default[]". Access Granted. 2023-11-10 03:58:32.274356 91.10% [DEBUG] sofia.c:7499 Channel sofia/default/9000100044@cluster.astppbilling.org:5073 entering state [received][100] 2023-11-10 03:58:32.274356 91.10% [DEBUG] sofia.c:7509 Remote SDP: v=0 o=Z 0 0 IN IP4 78.47.101.152 s=Z c=IN IP4 78.47.101.152 t=0 0 m=audio 35130 RTP/AVP 3 110 8 0 98 101 a=rtpmap:3 GSM/8000 a=rtpmap:110 speex/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:98 iLBC/8000 a=fmtp:98 mode=20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtcp:35131 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1] 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9000100044@cluster.astppbilling.org:5073 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_codec.c:111 sofia/default/9000100044@cluster.astppbilling.org:5073 Original read codec set to PCMA:8 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_media.c:5973 sofia/default/9000100044@cluster.astppbilling.org:5073 Set 2833 dtmf send payload to 101 recv payload to 101 2023-11-10 03:58:32.274356 91.10% [DEBUG] sofia.c:7933 (sofia/default/9000100044@cluster.astppbilling.org:5073) State Change CS_NEW -> CS_INIT 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9000100044@cluster.astppbilling.org:5073) State NEW 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9000100044@cluster.astppbilling.org:5073) Running State Change CS_INIT (Cur 1 Tot 12570) 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9000100044@cluster.astppbilling.org:5073) State INIT 2023-11-10 03:58:32.274356 91.10% [DEBUG] mod_sofia.c:97 sofia/default/9000100044@cluster.astppbilling.org:5073 SOFIA INIT 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:40 sofia/default/9000100044@cluster.astppbilling.org:5073 Standard INIT 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9000100044@cluster.astppbilling.org:5073) State Change CS_INIT -> CS_ROUTING 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9000100044@cluster.astppbilling.org:5073) State INIT going to sleep 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9000100044@cluster.astppbilling.org:5073) Running State Change CS_ROUTING (Cur 1 Tot 12570) 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_channel.c:2380 (sofia/default/9000100044@cluster.astppbilling.org:5073) Callstate Change DOWN -> RINGING 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9000100044@cluster.astppbilling.org:5073) State ROUTING 2023-11-10 03:58:32.274356 91.10% [DEBUG] mod_sofia.c:158 sofia/default/9000100044@cluster.astppbilling.org:5073 SOFIA ROUTING 2023-11-10 03:58:32.274356 91.10% [DEBUG] switch_core_state_machine.c:230 sofia/default/9000100044@cluster.astppbilling.org:5073 Standard ROUTING 2023-11-10 03:58:32.274356 91.10% [INFO] mod_dialplan_xml.c:639 Processing 9000100044 <9000100044>->1234 in context default 2023-11-10 03:58:32.294323 91.10% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL 2023-11-10 03:58:32.294323 91.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2023-11-10 03:58:32.294323 91.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code') 2023-11-10 03:58:32.294323 91.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2023-11-10 03:58:32.334321 91.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0 2023-11-10 03:58:32.334321 91.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1234 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '1234' limit 1 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1234' AND status=0 limit 1 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '1234' and status=0 LIMIT 1 2023-11-10 03:58:32.334321 91.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] queue_destination_number,agent_hold_file : 1234 2023-11-10 03:58:32.334321 91.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] Fifo Agent Flag1 2023-11-10 03:58:32.334321 91.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Queue Destination Number : 1234 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '${be-ring}' LIMIT 1 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
Dialplan: sofia/default/9000100044@cluster.astppbilling.org:5073 parsing [default->1234] continue=false Dialplan: sofia/default/9000100044@cluster.astppbilling.org:5073 Regex (PASS) [1234] destination_number(1234) =~ /1234/ break=on-false Dialplan: sofia/default/9000100044@cluster.astppbilling.org:5073 Action export(did_user_domain=cluster.astppbilling.org) Dialplan: sofia/default/9000100044@cluster.astppbilling.org:5073 Action set(fifo_music=${be-ring}) Dialplan: sofia/default/9000100044@cluster.astppbilling.org:5073 Action set(fifo_music=$${hold_music}) Dialplan: sofia/default/9000100044@cluster.astppbilling.org:5073 Action answer() Dialplan: sofia/default/9000100044@cluster.astppbilling.org:5073 Action fifo(myqueue_1 out wait) 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9000100044@cluster.astppbilling.org:5073) State Change CS_ROUTING -> CS_EXECUTE 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9000100044@cluster.astppbilling.org:5073) State ROUTING going to sleep 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9000100044@cluster.astppbilling.org:5073) Running State Change CS_EXECUTE (Cur 1 Tot 12570) 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9000100044@cluster.astppbilling.org:5073) State EXECUTE 2023-11-10 03:58:32.334321 91.10% [DEBUG] mod_sofia.c:213 sofia/default/9000100044@cluster.astppbilling.org:5073 SOFIA EXECUTE 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_core_state_machine.c:323 sofia/default/9000100044@cluster.astppbilling.org:5073 Standard EXECUTE EXECUTE [depth=0] sofia/default/9000100044@cluster.astppbilling.org:5073 export(did_user_domain=cluster.astppbilling.org) 2023-11-10 03:58:32.334321 91.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [did_user_domain]=[cluster.astppbilling.org] EXECUTE [depth=0] sofia/default/9000100044@cluster.astppbilling.org:5073 set(fifo_music=%(1000,3000,425)) 2023-11-10 03:58:32.334321 91.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/9000100044@cluster.astppbilling.org:5073 [fifo_music]=[%(1000,3000,425)] EXECUTE [depth=0] sofia/default/9000100044@cluster.astppbilling.org:5073 set(fifo_music=local_stream://moh) 2023-11-10 03:58:32.354313 91.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/9000100044@cluster.astppbilling.org:5073 [fifo_music]=[local_stream://moh] EXECUTE [depth=0] sofia/default/9000100044@cluster.astppbilling.org:5073 answer() 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9000100044@cluster.astppbilling.org:5073] 167.235.194.5 port 28776 -> 78.47.101.152 port 35130 codec: 8 ms: 20 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_media.c:8997 Activating RTCP PORT 35131 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_rtp.c:5017 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 35131 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_rtp.c:2776 Setting RTCP remote addr to 78.47.101.152:35131 2 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_media.c:9089 sofia/default/9000100044@cluster.astppbilling.org:5073 Set 2833 dtmf send payload to 101 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_media.c:9096 sofia/default/9000100044@cluster.astppbilling.org:5073 Set 2833 dtmf receive payload to 101 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_media.c:9119 sofia/default/9000100044@cluster.astppbilling.org:5073 Set rtp dtmf delay to 40 2023-11-10 03:58:32.354313 91.10% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/9000100044@cluster.astppbilling.org:5073: v=0 o=FreeSWITCH 1699559936 1699559937 IN IP4 167.235.194.5 s=FreeSWITCH c=IN IP4 167.235.194.5 t=0 0 m=audio 28776 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv a=rtcp:28777 IN IP4 167.235.194.5 2023-11-10 03:58:32.354313 91.10% [NOTICE] mod_dptools.c:1419 Channel [sofia/default/9000100044@cluster.astppbilling.org:5073] has been answered 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_channel.c:3950 (sofia/default/9000100044@cluster.astppbilling.org:5073) Callstate Change RINGING -> ACTIVE 2023-11-10 03:58:32.354313 91.10% [DEBUG] sofia.c:7499 Channel sofia/default/9000100044@cluster.astppbilling.org:5073 entering state [completed][200] 2023-11-10 03:58:32.354313 91.10% [ERR] switch_core_session.c:2770 Invalid Application fifo 2023-11-10 03:58:32.354313 91.10% [NOTICE] switch_core_session.c:2771 Hangup sofia/default/9000100044@cluster.astppbilling.org:5073 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER] 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9000100044@cluster.astppbilling.org:5073) State EXECUTE going to sleep 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9000100044@cluster.astppbilling.org:5073) Running State Change CS_HANGUP (Cur 1 Tot 12570) 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_channel.c:3990 sofia/default/9000100044@cluster.astppbilling.org:5073 skip receive message [VIDEO_SYNC] (channel is hungup already) 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9000100044@cluster.astppbilling.org:5073) Callstate Change ACTIVE -> HANGUP 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9000100044@cluster.astppbilling.org:5073) State HANGUP 2023-11-10 03:58:32.354313 91.10% [DEBUG] mod_sofia.c:468 Channel sofia/default/9000100044@cluster.astppbilling.org:5073 hanging up, cause: DESTINATION_OUT_OF_ORDER 2023-11-10 03:58:32.354313 91.10% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/9000100044@cluster.astppbilling.org:5073 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:59 sofia/default/9000100044@cluster.astppbilling.org:5073 Standard HANGUP, cause: DESTINATION_OUT_OF_ORDER 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9000100044@cluster.astppbilling.org:5073) State HANGUP going to sleep 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9000100044@cluster.astppbilling.org:5073) State Change CS_HANGUP -> CS_REPORTING 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9000100044@cluster.astppbilling.org:5073) Running State Change CS_REPORTING (Cur 1 Tot 12570) 2023-11-10 03:58:32.354313 91.10% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9000100044@cluster.astppbilling.org:5073) State REPORTING 2023-11-10 03:58:32.354313 91.10% [INFO] mod_json_cdr.c:271 Process [8d73be50-47b0-4206-ab18-e79df024803c.cdr.json] 2023-11-10 03:58:32.374354 91.10% [DEBUG] switch_core_state_machine.c:168 sofia/default/9000100044@cluster.astppbilling.org:5073 Standard REPORTING, cause: DESTINATION_OUT_OF_ORDER 2023-11-10 03:58:32.374354 91.10% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9000100044@cluster.astppbilling.org:5073) State REPORTING going to sleep 2023-11-10 03:58:32.374354 91.10% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9000100044@cluster.astppbilling.org:5073) State Change CS_REPORTING -> CS_DESTROY 2023-11-10 03:58:32.374354 91.10% [DEBUG] switch_core_session.c:1753 Session 12570 (sofia/default/9000100044@cluster.astppbilling.org:5073) Locked, Waiting on external entities 2023-11-10 03:58:32.374354 91.10% [NOTICE] switch_core_session.c:1771 Session 12570 (sofia/default/9000100044@cluster.astppbilling.org:5073) Ended 2023-11-10 03:58:32.374354 91.10% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9000100044@cluster.astppbilling.org:5073 [CS_DESTROY] 2023-11-10 03:58:32.374354 91.10% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9000100044@cluster.astppbilling.org:5073) Running State Change CS_DESTROY (Cur 0 Tot 12570) 2023-11-10 03:58:32.374354 91.10% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9000100044@cluster.astppbilling.org:5073) State DESTROY 2023-11-10 03:58:32.374354 91.10% [DEBUG] mod_sofia.c:379 sofia/default/9000100044@cluster.astppbilling.org:5073 SOFIA DESTROY 2023-11-10 03:58:32.374354 91.10% [DEBUG] switch_core_state_machine.c:175 sofia/default/9000100044@cluster.astppbilling.org:5073 Standard DESTROY 2023-11-10 03:58:32.374354 91.10% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9000100044@cluster.astppbilling.org:5073) State DESTROY going to sleep Type control-D or /exit or /quit or /bye to exit. freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> freeswitch@FS1ClusterQA> /exit