2023-10-05 08:05:02.191992 [NOTICE] switch_channel.c:1118 New Channel sofia/default/7920@185.249.40.236 [a3aab597-251c-4c82-8feb-a427452c4d93] 2023-10-05 08:05:02.191992 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7920@185.249.40.236) Running State Change CS_NEW (Cur 7 Tot 482117) 2023-10-05 08:05:02.191992 [DEBUG] sofia.c:10280 sofia/default/7920@185.249.40.236 receiving invite from 132.148.79.38:64326 version: 1.10.5 -release-17-25569c1631 64bit 2023-10-05 08:05:02.191992 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 132.148.79.38:0. 2023-10-05 08:05:02.191992 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [100972594937228@185.249.40.236] from ip 132.148.79.38 2023-10-05 08:05:02.191992 [DEBUG] switch_core_state_machine.c:604 (sofia/default/7920@185.249.40.236) State NEW 2023-10-05 08:05:02.191992 [DEBUG] sofia.c:2434 detaching session a3aab597-251c-4c82-8feb-a427452c4d93 2023-10-05 08:05:02.511996 [DEBUG] sofia.c:2544 Re-attaching to session a3aab597-251c-4c82-8feb-a427452c4d93 2023-10-05 08:05:02.531989 [DEBUG] sofia.c:10280 sofia/default/7920@185.249.40.236 receiving invite from 132.148.79.38:64326 version: 1.10.5 -release-17-25569c1631 64bit 2023-10-05 08:05:02.531989 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 132.148.79.38:0. 2023-10-05 08:05:02.531989 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [100972594937228@185.249.40.236] from ip 132.148.79.38 2023-10-05 08:05:02.531989 [DEBUG] sofia.c:2434 detaching session a3aab597-251c-4c82-8feb-a427452c4d93 2023-10-05 08:05:11.411993 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables 2023-10-05 08:05:11.411993 [NOTICE] switch_channel.c:1118 New Channel sofia/default/31104226413@185.249.40.236:5060 [526e9fc3-3cc8-48b9-8d25-9194da9e96a2] 2023-10-05 08:05:11.411993 [DEBUG] mod_sofia.c:5089 (sofia/default/31104226413@185.249.40.236:5060) State Change CS_NEW -> CS_INIT 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236:5060) Running State Change CS_INIT (Cur 8 Tot 482118) 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31104226413@185.249.40.236:5060) State INIT 2023-10-05 08:05:11.411993 [DEBUG] mod_sofia.c:93 sofia/default/31104226413@185.249.40.236:5060 SOFIA INIT 2023-10-05 08:05:11.411993 [DEBUG] sofia_glue.c:1618 sofia/default/31104226413@185.249.40.236:5060 sending invite version: 1.10.5 -release-17-25569c1631 64bit Local SDP: v=0 o=FreeSWITCH 1696460839 1696460840 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=audio 25072 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:40 sofia/default/31104226413@185.249.40.236:5060 Standard INIT 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:48 (sofia/default/31104226413@185.249.40.236:5060) State Change CS_INIT -> CS_ROUTING 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31104226413@185.249.40.236:5060) State INIT going to sleep 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236:5060) Running State Change CS_ROUTING (Cur 8 Tot 482118) 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31104226413@185.249.40.236:5060) State ROUTING 2023-10-05 08:05:11.411993 [DEBUG] mod_sofia.c:154 sofia/default/31104226413@185.249.40.236:5060 SOFIA ROUTING 2023-10-05 08:05:11.411993 [DEBUG] switch_ivr_originate.c:67 (sofia/default/31104226413@185.249.40.236:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31104226413@185.249.40.236:5060) State ROUTING going to sleep 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236:5060) Running State Change CS_CONSUME_MEDIA (Cur 8 Tot 482118) 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:663 (sofia/default/31104226413@185.249.40.236:5060) State CONSUME_MEDIA 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:663 (sofia/default/31104226413@185.249.40.236:5060) State CONSUME_MEDIA going to sleep 2023-10-05 08:05:11.411993 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236:5060 entering state [calling][0] 2023-10-05 08:05:11.411993 [NOTICE] switch_channel.c:1118 New Channel sofia/default/31104226413@185.249.40.236 [60a893e4-7abd-4910-b838-7a9a735cf7a9] 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236) Running State Change CS_NEW (Cur 9 Tot 482119) 2023-10-05 08:05:11.411993 [DEBUG] sofia.c:10280 sofia/default/31104226413@185.249.40.236 receiving invite from 185.249.40.236:5060 version: 1.10.5 -release-17-25569c1631 64bit 2023-10-05 08:05:11.411993 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 185.249.40.236:0. 2023-10-05 08:05:11.411993 [DEBUG] sofia.c:10403 IP 185.249.40.236 Approved by acl "default[]". Access Granted. 2023-10-05 08:05:11.411993 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236 entering state [received][100] 2023-10-05 08:05:11.411993 [DEBUG] sofia.c:7336 Remote SDP: v=0 o=FreeSWITCH 1696460839 1696460840 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=audio 25072 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2023-10-05 08:05:11.411993 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2023-10-05 08:05:11.411993 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2023-10-05 08:05:11.411993 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-10-05 08:05:11.411993 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2023-10-05 08:05:11.411993 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000 2023-10-05 08:05:11.411993 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/31104226413@185.249.40.236 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2023-10-05 08:05:11.411993 [DEBUG] switch_core_codec.c:111 sofia/default/31104226413@185.249.40.236 Original read codec set to PCMA:8 2023-10-05 08:05:11.411993 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000 2023-10-05 08:05:11.411993 [DEBUG] switch_core_media.c:5911 sofia/default/31104226413@185.249.40.236 Set 2833 dtmf send payload to 101 recv payload to 101 2023-10-05 08:05:11.411993 [DEBUG] sofia.c:7760 (sofia/default/31104226413@185.249.40.236) State Change CS_NEW -> CS_INIT 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:604 (sofia/default/31104226413@185.249.40.236) State NEW 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236) Running State Change CS_INIT (Cur 9 Tot 482119) 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31104226413@185.249.40.236) State INIT 2023-10-05 08:05:11.411993 [DEBUG] mod_sofia.c:93 sofia/default/31104226413@185.249.40.236 SOFIA INIT 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:40 sofia/default/31104226413@185.249.40.236 Standard INIT 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:48 (sofia/default/31104226413@185.249.40.236) State Change CS_INIT -> CS_ROUTING 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31104226413@185.249.40.236) State INIT going to sleep 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236) Running State Change CS_ROUTING (Cur 9 Tot 482119) 2023-10-05 08:05:11.411993 [DEBUG] switch_channel.c:2332 (sofia/default/31104226413@185.249.40.236) Callstate Change DOWN -> RINGING 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31104226413@185.249.40.236) State ROUTING 2023-10-05 08:05:11.411993 [DEBUG] mod_sofia.c:154 sofia/default/31104226413@185.249.40.236 SOFIA ROUTING 2023-10-05 08:05:11.411993 [DEBUG] switch_core_state_machine.c:236 sofia/default/31104226413@185.249.40.236 Standard ROUTING 2023-10-05 08:05:11.411993 [INFO] mod_dialplan_xml.c:637 Processing 31104226413 <31104226413>->31104226413 in context default 2023-10-05 08:05:11.411993 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes') 2023-10-05 08:05:11.411993 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2023-10-05 08:05:11.431989 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params: Event-Name: REQUEST_PARAMS Core-UUID: f6da2544-b048-4548-903c-7e9e82002117 FreeSWITCH-Hostname: multitenant-1 FreeSWITCH-Switchname: multitenant-1 FreeSWITCH-IPv4: 185.249.40.236 FreeSWITCH-IPv6: %3A%3A1 Event-Date-Local: 2023-10-05%2008%3A05%3A11 Event-Date-GMT: Thu,%2005%20Oct%202023%2006%3A05%3A11%20GMT Event-Date-Timestamp: 1696485911411993 Event-Calling-File: mod_dialplan_xml.c Event-Calling-Function: dialplan_xml_locate Event-Calling-Line-Number: 608 Event-Sequence: 50408639 Channel-State: CS_ROUTING Channel-Call-State: RINGING Channel-State-Number: 2 Channel-Name: sofia/default/31104226413%40185.249.40.236 Unique-ID: 60a893e4-7abd-4910-b838-7a9a735cf7a9 Call-Direction: inbound Presence-Call-Direction: inbound Channel-HIT-Dialplan: true Channel-Presence-ID: 31104226413%40185.249.40.236 Channel-Call-UUID: 60a893e4-7abd-4910-b838-7a9a735cf7a9 Answer-State: ringing Channel-Read-Codec-Name: PCMA Channel-Read-Codec-Rate: 8000 Channel-Read-Codec-Bit-Rate: 64000 Channel-Write-Codec-Name: PCMA Channel-Write-Codec-Rate: 8000 Channel-Write-Codec-Bit-Rate: 64000 Caller-Direction: inbound Caller-Logical-Direction: inbound Caller-Username: 31104226413 Caller-Dialplan: XML Caller-Caller-ID-Name: 31104226413 Caller-Caller-ID-Number: 31104226413 Caller-Orig-Caller-ID-Name: 31104226413 Caller-Orig-Caller-ID-Number: 31104226413 Caller-Network-Addr: 185.249.40.236 Caller-ANI: 31104226413 Caller-Destination-Number: 31104226413 Caller-Unique-ID: 60a893e4-7abd-4910-b838-7a9a735cf7a9 Caller-Source: mod_sofia Caller-Context: default Caller-Channel-Name: sofia/default/31104226413%40185.249.40.236 Caller-Profile-Index: 1 Caller-Profile-Created-Time: 1696485911411993 Caller-Channel-Created-Time: 1696485911411993 Caller-Channel-Answered-Time: 0 Caller-Channel-Progress-Time: 0 Caller-Channel-Progress-Media-Time: 0 Caller-Channel-Hangup-Time: 0 Caller-Channel-Transfer-Time: 0 Caller-Channel-Resurrect-Time: 0 Caller-Channel-Bridged-Time: 0 Caller-Channel-Last-Hold: 0 Caller-Channel-Hold-Accum: 0 Caller-Screen-Bit: true Caller-Privacy-Hide-Name: false Caller-Privacy-Hide-Number: false variable_direction: inbound variable_uuid: 60a893e4-7abd-4910-b838-7a9a735cf7a9 variable_session_id: 482119 variable_sip_from_user: 31104226413 variable_sip_from_uri: 31104226413%40185.249.40.236 variable_sip_from_host: 185.249.40.236 variable_video_media_flow: disabled variable_text_media_flow: disabled variable_channel_name: sofia/default/31104226413%40185.249.40.236 variable_sip_call_id: fc0a0740-dde7-123c-a8ab-56465534f0d2 variable_sip_local_network_addr: 185.249.40.236 variable_sip_network_ip: 185.249.40.236 variable_sip_network_port: 5060 variable_sip_invite_stamp: 1696485911411993 variable_sip_received_ip: 185.249.40.236 variable_sip_received_port: 5060 variable_sip_via_protocol: udp variable_sip_authorized: true variable_sip_acl_authed_by: default variable_sip_from_user_stripped: 31104226413 variable_sip_from_tag: QZZFpgF4gXQNc variable_sofia_profile_name: default variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060 variable_recovery_profile_name: default variable_sip_Remote-Party-ID: %3Csip%3A31104226413%40185.249.40.236%3E%3Bparty%3Dcalling%3Bscreen%3Dyes%3Bprivacy%3Doff variable_sip_cid_type: rpid variable_sip_full_via: SIP/2.0/UDP%20185.249.40.236%3Brport%3D5060%3Bbranch%3Dz9hG4bKmjQvQ1HUm7t3B variable_sip_full_from: %3Csip%3A31104226413%40185.249.40.236%3E%3Btag%3DQZZFpgF4gXQNc variable_sip_full_to: %3Csip%3A31104226413%40185.249.40.236%3A5060%3E variable_sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20OPTIONS,%20MESSAGE,%20INFO,%20UPDATE,%20REGISTER,%20REFER,%20NOTIFY,%20PUBLISH,%20SUBSCRIBE variable_sip_req_user: 31104226413 variable_sip_req_port: 5060 variable_sip_req_uri: 31104226413%40185.249.40.236%3A5060 variable_sip_req_host: 185.249.40.236 variable_sip_to_user: 31104226413 variable_sip_to_port: 5060 variable_sip_to_uri: 31104226413%40185.249.40.236%3A5060 variable_sip_to_host: 185.249.40.236 variable_sip_contact_user: mod_sofia variable_sip_contact_port: 5060 variable_sip_contact_uri: mod_sofia%40185.249.40.236%3A5060 variable_sip_contact_host: 185.249.40.236 variable_sip_user_agent: ASTPP variable_sip_via_host: 185.249.40.236 variable_sip_via_rport: 5060 variable_max_forwards: 70 variable_presence_id: 31104226413%40185.249.40.236 variable_sip_h_P-Accountcode: 669636 variable_sip_h_P-calltype: FAX variable_sip_h_P-fax_id: 127 variable_sip_h_P-fax_count: 1 variable_sip_h_P-retries_count: 3 variable_sip_h_P-e_number: 669636 variable_sip_h_P-e_name: 669636 variable_switch_r_sdp: v%3D0%0D%0Ao%3DFreeSWITCH%201696460839%201696460840%20IN%20IP4%20185.249.40.236%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%20185.249.40.236%0D%0At%3D0%200%0D%0Am%3Daudio%2025072%20RTP/AVP%208%20101%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b variable_rtp_use_codec_string: PCMA,PCMU,G722 variable_remote_video_media_flow: inactive variable_remote_text_media_flow: inactive variable_remote_audio_media_flow: sendrecv variable_audio_media_flow: sendrecv variable_remote_media_ip: 185.249.40.236 variable_remote_media_port: 25072 variable_rtp_audio_recv_pt: 8 variable_rtp_use_codec_name: PCMA variable_rtp_use_codec_rate: 8000 variable_rtp_use_codec_ptime: 20 variable_rtp_use_codec_channels: 1 variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c variable_read_codec: PCMA variable_original_read_codec: PCMA variable_read_rate: 8000 variable_original_read_rate: 8000 variable_write_codec: PCMA variable_write_rate: 8000 variable_dtmf_type: rfc2833 variable_endpoint_disposition: RECEIVED variable_call_uuid: 60a893e4-7abd-4910-b838-7a9a735cf7a9 Hunt-Direction: inbound Hunt-Logical-Direction: inbound Hunt-Username: 31104226413 Hunt-Dialplan: XML Hunt-Caller-ID-Name: 31104226413 Hunt-Caller-ID-Number: 31104226413 Hunt-Orig-Caller-ID-Name: 31104226413 Hunt-Orig-Caller-ID-Number: 31104226413 Hunt-Network-Addr: 185.249.40.236 Hunt-ANI: 31104226413 Hunt-Destination-Number: 31104226413 Hunt-Unique-ID: 60a893e4-7abd-4910-b838-7a9a735cf7a9 Hunt-Source: mod_sofia Hunt-Context: default Hunt-Channel-Name: sofia/default/31104226413%40185.249.40.236 Hunt-Profile-Index: 1 Hunt-Profile-Created-Time: 1696485911411993 Hunt-Channel-Created-Time: 1696485911411993 Hunt-Channel-Answered-Time: 0 Hunt-Channel-Progress-Time: 0 Hunt-Channel-Progress-Media-Time: 0 Hunt-Channel-Hangup-Time: 0 Hunt-Channel-Transfer-Time: 0 Hunt-Channel-Resurrect-Time: 0 Hunt-Channel-Bridged-Time: 0 Hunt-Channel-Last-Hold: 0 Hunt-Channel-Hold-Accum: 0 Hunt-Screen-Bit: true Hunt-Privacy-Hide-Name: false Hunt-Privacy-Hide-Number: false 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name: 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name: 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value: 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 31104226413 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '31104226413' limit 1 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '31104226413' AND status=0 limit 1 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '31104226413' and status=0 LIMIT 1 2023-10-05 08:05:11.431989 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number : 669636 / 669636 2023-10-05 08:05:11.431989 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Call Type FAX 2023-10-05 08:05:11.431989 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Account Code :669636 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [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 ="31104226413" LIMIT 1 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : inbound 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 669636] 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "669636" AND deleted = 0 limit 1 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="31104226413" AND (accountid = 0 OR status = 1) LIMIT 1 2023-10-05 08:05:11.431989 [NOTICE] switch_cpp.cpp:1447 [ASTPP] Localization Id: 1 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] Call Direction : inbound 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] DID ACCOUNT ID : 1245 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [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 P.is_terminated = 0 AND (patterns = '^31104226413.*' OR patterns = '^3110422641.*' OR patterns = '^311042264.*' OR patterns = '^31104226.*' OR patterns = '^3110422.*' OR patterns = '^311042.*' OR patterns = '^31104.*' OR patterns = '^3110.*' OR patterns = '^311.*' OR patterns = '^31.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 1245 ORDER BY LENGTH(PKGPTR.patterns) DESC 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [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 ="31104226413" AND A.accountid = '1245' limit 1 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 31104226413 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [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 ="31104226413" LIMIT 1 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : inbound 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '31104226413' OR number = '3110422641' OR number = '311042264' OR number = '31104226' OR number = '3110422' OR number = '311042' OR number = '31104' OR number = '3110' OR number = '311' OR number = '31' OR number = '3' OR number ='--') OR (number = '31104226413*' OR number = '3110422641*' OR number = '311042264*' OR number = '31104226*' OR number = '3110422*' OR number = '311042*' OR number = '31104*' OR number = '3110*' OR number = '311*' OR number = '31*' OR number = '3*' OR number ='--') OR (number = '669636' OR number = '66963' OR number = '6696' OR number = '669' OR number = '66' OR number = '6' OR number ='--') OR (number = '669636*' OR number = '66963*' OR number = '6696*' OR number = '669*' OR number = '66*' OR number = '6*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '31104226413' OR routing_prefix = '3110422641' OR routing_prefix = '311042264' OR routing_prefix = '31104226' OR routing_prefix = '3110422' OR routing_prefix = '311042' OR routing_prefix = '31104' OR routing_prefix = '3110' OR routing_prefix = '311' OR routing_prefix = '31' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 80 limit 1 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information =================== 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 1245 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 669636 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 499.9747 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid] 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 77 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0 2023-10-05 08:05:11.431989 [INFO] switch_cpp.cpp:1447 [ASTPP] ======================================================== 2023-10-05 08:05:11.431989 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 77 AND status = 0 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :inbound 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [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 ="31104226413" LIMIT 1 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Call Direction : inbound 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information =================== 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 6892 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 0 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.00000 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment : 31104226413 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 136 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 1245 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================ 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your499.9747 balance Accountid 1245 !!! 2023-10-05 08:05:11.451987 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length :: 100 2023-10-05 08:05:11.451987 [NOTICE] switch_cpp.cpp:1447 [ASTPP] FINDING LIMIT FOR RESELLER: 80 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "80" AND deleted = 0 limit 1 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Reseller Information =================== 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 80 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 156557 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 9999.91366 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid] 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 8 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 8 AND status = 0 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :inbound 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [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 ="31104226413" LIMIT 1 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Call Direction : inbound 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information =================== 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 6892 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 0 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.00000 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment : 31104226413 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 136 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 80 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================ 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your9999.91366 balance Accountid 80 !!! 2023-10-05 08:05:11.451987 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length :: 100 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Reseller xml_user_rates : ID:6892|CODE:^31104226413.*|DESTINATION:31104226413|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1245||ID:6892|CODE:^31104226413.*|DESTINATION:31104226413|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1245 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] ======================================================== 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Lossless Routing Status : 1 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Call Max Length Duration : 100 minutes 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] INB_FREE:TRUE 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] free_inbound:1 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1245 AND status=0 LIMIT 1 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1245" AND deleted = 0 limit 1 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Actual CustomerInfo XML:1245 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Userinfo XML:1245 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Actual CustomerInfo XML : 1245 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 1245 limit 1 2023-10-05 08:05:11.451987 [WARNING] switch_cpp.cpp:1447 [ASTPP] [user_domain] User Domain : LibertyFabrics986.yoursipcloud.com 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] Maxlength : 100 2023-10-05 08:05:11.451987 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Outbound Dialplan 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [XML] Customer Recording Flag : 1 2023-10-05 08:05:11.451987 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] Call Direction : inbound 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET get_record_info] Query :SELECT sip_device_routing.is_recording FROM sip_devices AS sip_devices, accounts AS accounts ,sip_device_routing AS sip_device_routing WHERE sip_devices.accountid = accounts.id AND sip_devices.id = sip_device_routing.sip_device_id AND sip_devices.username = "tushar.devani@inextrix.com" AND accounts.status = 0 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 1245 limit 1 2023-10-05 08:05:11.451987 [WARNING] switch_cpp.cpp:1447 [ASTPP] [XML] User Domain : LibertyFabrics986.yoursipcloud.com 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 1245 and status = 0 limit 1 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [XML] Custom Function Name :: custom_inbound_6 2023-10-05 08:05:11.451987 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Inbound Dialplan 2023-10-05 08:05:11.451987 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Generated XML:
Dialplan: sofia/default/31104226413@185.249.40.236 parsing [default->31104226413] continue=false Dialplan: sofia/default/31104226413@185.249.40.236 Regex (PASS) [31104226413] destination_number(31104226413) =~ /31104226413/ break=on-false Dialplan: sofia/default/31104226413@185.249.40.236 Action set(effective_destination_number=31104226413) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(bridge_pre_execute_bleg_app=sched_hangup) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(callstart=2023-10-05 06:05:11) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(hangup_after_bridge=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(continue_on_fail=TRUE) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(account_id=1245) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(parent_id=80) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(entity_id=0) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(call_processed=internal) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(call_direction=inbound) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(accountname=default) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(filename1=astpp.pbx.lua) Dialplan: sofia/default/31104226413@185.249.40.236 Action export(t38_passthru=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action playback(silence_stream://2000) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_enable_t38_request=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(sip_require_timer=false) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(call_request=3) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(module_name=apfax) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_use_ecm=false) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_v17_disabled=false) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_disable_v17=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_enable_t38=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(session_in_hangup_hook=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(origination_rates_did=ID:6892|CODE:^31104226413.*|DESTINATION:31104226413|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1245||ID:6892|CODE:^31104226413.*|DESTINATION:31104226413|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1245) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(origination_rates=0) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(original_caller_id_name=669636) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(original_caller_id_number=669636) Dialplan: sofia/default/31104226413@185.249.40.236 Action limit(db 669636 user_669636 10 ) Dialplan: sofia/default/31104226413@185.249.40.236 Action limit(hash CPS_669636 CPS_user_669636 1/1 ) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(effective_caller_id_name=669636) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(effective_caller_id_number=669636) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(receiver_accid=1245) Dialplan: sofia/default/31104226413@185.249.40.236 Action export(domain_name=LibertyFabrics986.yoursipcloud.com) Dialplan: sofia/default/31104226413@185.249.40.236 Action export(sip_to_host=LibertyFabrics986.yoursipcloud.com) Dialplan: sofia/default/31104226413@185.249.40.236 Action export(presence_data=x|||Liberty Fabrics(669636)|||||||||DID|||185.249.40.236) Dialplan: sofia/default/31104226413@185.249.40.236 Action export(call_type=6) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(calltype=FAX) Dialplan: sofia/default/31104226413@185.249.40.236 Action answer() Dialplan: sofia/default/31104226413@185.249.40.236 Action set(callfax=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action playback(silence_stream://2000) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_enable_t38_request=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(sip_require_timer=false) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(call_request=6) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(module_name=apfax) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_transfer_rate=9600) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_use_ecm=false) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_v17_disabled=false) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_disable_v17=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(fax_enable_t38=true) Dialplan: sofia/default/31104226413@185.249.40.236 Action set(api_hangup_hook=system /var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.tif 669636 Liberty+Fabrics_ tushar.devani@inextrix.com /var/www/html/astpp/attachments/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.pdf tushar.devani@inextrix.com 31104226413 669636) Dialplan: sofia/default/31104226413@185.249.40.236 Action rxfax(/var/www/html/astpp/FAX/receive_fax_files/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.tif) Dialplan: sofia/default/31104226413@185.249.40.236 Action hangup() 2023-10-05 08:05:11.451987 [DEBUG] switch_core_state_machine.c:287 (sofia/default/31104226413@185.249.40.236) State Change CS_ROUTING -> CS_EXECUTE 2023-10-05 08:05:11.451987 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31104226413@185.249.40.236) State ROUTING going to sleep 2023-10-05 08:05:11.451987 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236) Running State Change CS_EXECUTE (Cur 9 Tot 482119) 2023-10-05 08:05:11.451987 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31104226413@185.249.40.236) State EXECUTE 2023-10-05 08:05:11.451987 [DEBUG] mod_sofia.c:209 sofia/default/31104226413@185.249.40.236 SOFIA EXECUTE 2023-10-05 08:05:11.451987 [DEBUG] switch_core_state_machine.c:329 sofia/default/31104226413@185.249.40.236 Standard EXECUTE EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(effective_destination_number=31104226413) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [effective_destination_number]=[31104226413] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(bridge_pre_execute_bleg_app=sched_hangup) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [bridge_pre_execute_bleg_app]=[sched_hangup] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(bridge_pre_execute_bleg_data=+6000 normal_clearing) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(callstart=2023-10-05 06:05:11) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [callstart]=[2023-10-05 06:05:11] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(hangup_after_bridge=true) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [hangup_after_bridge]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(continue_on_fail=TRUE) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [continue_on_fail]=[TRUE] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(account_id=1245) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [account_id]=[1245] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(parent_id=80) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [parent_id]=[80] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(entity_id=0) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [entity_id]=[0] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(call_processed=internal) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [call_processed]=[internal] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(call_direction=inbound) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [call_direction]=[inbound] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(accountname=default) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [accountname]=[default] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(filename1=astpp.pbx.lua) 2023-10-05 08:05:11.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [filename1]=[astpp.pbx.lua] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 export(t38_passthru=true) 2023-10-05 08:05:11.451987 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [t38_passthru]=[true] 2023-10-05 08:05:11.451987 [DEBUG] switch_core_session.c:2722 Application playback Requires media! pre_answering channel sofia/default/31104226413@185.249.40.236 2023-10-05 08:05:11.451987 [INFO] switch_core_session.c:2724 Sending early media 2023-10-05 08:05:11.451987 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31104226413@185.249.40.236] 185.249.40.236 port 21842 -> 185.249.40.236 port 25072 codec: 8 ms: 20 2023-10-05 08:05:11.451987 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms 2023-10-05 08:05:11.451987 [DEBUG] switch_core_media.c:8977 sofia/default/31104226413@185.249.40.236 Set 2833 dtmf send payload to 101 2023-10-05 08:05:11.451987 [DEBUG] switch_core_media.c:8984 sofia/default/31104226413@185.249.40.236 Set 2833 dtmf receive payload to 101 2023-10-05 08:05:11.451987 [DEBUG] switch_core_media.c:9007 sofia/default/31104226413@185.249.40.236 Set rtp dtmf delay to 40 2023-10-05 08:05:11.451987 [DEBUG] mod_sofia.c:2593 Ring SDP: v=0 o=FreeSWITCH 1696464069 1696464070 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=audio 21842 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2023-10-05 08:05:11.451987 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/31104226413@185.249.40.236! 2023-10-05 08:05:11.451987 [DEBUG] switch_channel.c:3565 (sofia/default/31104226413@185.249.40.236) Callstate Change RINGING -> EARLY EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 playback(silence_stream://2000) 2023-10-05 08:05:11.471988 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms 2023-10-05 08:05:11.471988 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236 entering state [early][183] 2023-10-05 08:05:11.471988 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236:5060 entering state [proceeding][183] 2023-10-05 08:05:11.471988 [DEBUG] sofia.c:7336 Remote SDP: v=0 o=FreeSWITCH 1696464069 1696464070 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=audio 21842 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2023-10-05 08:05:11.471988 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/31104226413@185.249.40.236:5060! 2023-10-05 08:05:11.471988 [DEBUG] switch_channel.c:3565 (sofia/default/31104226413@185.249.40.236:5060) Callstate Change DOWN -> EARLY 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/31104226413@185.249.40.236:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2023-10-05 08:05:11.471988 [DEBUG] switch_core_codec.c:111 sofia/default/31104226413@185.249.40.236:5060 Original read codec set to PCMA:8 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:5911 sofia/default/31104226413@185.249.40.236:5060 Set 2833 dtmf send payload to 101 recv payload to 101 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31104226413@185.249.40.236:5060] 185.249.40.236 port 25072 -> 185.249.40.236 port 21842 codec: 8 ms: 20 2023-10-05 08:05:11.471988 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:8977 sofia/default/31104226413@185.249.40.236:5060 Set 2833 dtmf send payload to 101 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:8984 sofia/default/31104226413@185.249.40.236:5060 Set 2833 dtmf receive payload to 101 2023-10-05 08:05:11.471988 [DEBUG] switch_core_media.c:9007 sofia/default/31104226413@185.249.40.236:5060 Set rtp dtmf delay to 40 2023-10-05 08:05:11.911986 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [202@260715.yoursipcloud.com] from ip 185.249.42.137 freeswitch@multitenant-1> 2023-10-05 08:05:12.191986 [WARNING] switch_core_state_machine.c:688 a3aab597-251c-4c82-8feb-a427452c4d93 sofia/default/7920@185.249.40.236 Abandoned 2023-10-05 08:05:12.191986 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/7920@185.249.40.236 [CS_NEW] [WRONG_CALL_STATE] 2023-10-05 08:05:12.191986 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7920@185.249.40.236) Running State Change CS_HANGUP (Cur 9 Tot 482119) 2023-10-05 08:05:12.191986 [DEBUG] switch_core_state_machine.c:848 (sofia/default/7920@185.249.40.236) Callstate Change DOWN -> HANGUP 2023-10-05 08:05:12.191986 [DEBUG] switch_core_state_machine.c:850 (sofia/default/7920@185.249.40.236) State HANGUP 2023-10-05 08:05:12.191986 [DEBUG] mod_sofia.c:453 Channel sofia/default/7920@185.249.40.236 hanging up, cause: WRONG_CALL_STATE 2023-10-05 08:05:12.191986 [DEBUG] switch_core_state_machine.c:60 sofia/default/7920@185.249.40.236 Standard HANGUP, cause: WRONG_CALL_STATE 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:850 (sofia/default/7920@185.249.40.236) State HANGUP going to sleep 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:620 (sofia/default/7920@185.249.40.236) State Change CS_HANGUP -> CS_REPORTING 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:585 (sofia/default/7920@185.249.40.236) Running State Change CS_REPORTING (Cur 9 Tot 482119) 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:936 (sofia/default/7920@185.249.40.236) State REPORTING 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:174 sofia/default/7920@185.249.40.236 Standard REPORTING, cause: WRONG_CALL_STATE 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:936 (sofia/default/7920@185.249.40.236) State REPORTING going to sleep 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:611 (sofia/default/7920@185.249.40.236) State Change CS_REPORTING -> CS_DESTROY 2023-10-05 08:05:12.211996 [DEBUG] switch_core_session.c:1726 Session 482117 (sofia/default/7920@185.249.40.236) Locked, Waiting on external entities 2023-10-05 08:05:12.211996 [NOTICE] switch_core_session.c:1744 Session 482117 (sofia/default/7920@185.249.40.236) Ended 2023-10-05 08:05:12.211996 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/7920@185.249.40.236 [CS_DESTROY] 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:739 (sofia/default/7920@185.249.40.236) Running State Change CS_DESTROY (Cur 8 Tot 482119) 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:749 (sofia/default/7920@185.249.40.236) State DESTROY 2023-10-05 08:05:12.211996 [DEBUG] mod_sofia.c:364 sofia/default/7920@185.249.40.236 SOFIA DESTROY 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:181 sofia/default/7920@185.249.40.236 Standard DESTROY 2023-10-05 08:05:12.211996 [DEBUG] switch_core_state_machine.c:749 (sofia/default/7920@185.249.40.236) State DESTROY going to sleep 2023-10-05 08:05:13.451996 [DEBUG] switch_ivr_play_say.c:1933 done playing file silence_stream://2000 EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_enable_t38_request=true) 2023-10-05 08:05:13.451996 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_enable_t38_request]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(sip_require_timer=false) 2023-10-05 08:05:13.451996 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [sip_require_timer]=[false] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(call_request=3) 2023-10-05 08:05:13.451996 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [call_request]=[3] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(module_name=apfax) 2023-10-05 08:05:13.451996 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [module_name]=[apfax] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_use_ecm=false) 2023-10-05 08:05:13.451996 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_use_ecm]=[false] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_v17_disabled=false) 2023-10-05 08:05:13.451996 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_v17_disabled]=[false] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_disable_v17=true) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_disable_v17]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_enable_t38=true) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_enable_t38]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(session_in_hangup_hook=true) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [session_in_hangup_hook]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(origination_rates_did=ID:6892|CODE:^31104226413.*|DESTINATION:31104226413|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1245||ID:6892|CODE:^31104226413.*|DESTINATION:31104226413|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1245) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [origination_rates_did]=[ID:6892|CODE:^31104226413.*|DESTINATION:31104226413|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1245||ID:6892|CODE:^31104226413.*|DESTINATION:31104226413|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1245] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(origination_rates=0) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [origination_rates]=[0] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(original_caller_id_name=669636) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [original_caller_id_name]=[669636] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(original_caller_id_number=669636) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [original_caller_id_number]=[669636] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 limit(db 669636 user_669636 10 ) 2023-10-05 08:05:13.471990 [DEBUG] switch_limit.c:124 incr called: 669636_user_669636 max:10, interval:0 2023-10-05 08:05:13.471990 [DEBUG] mod_db.c:194 Usage for 669636_user_669636 is now 1/10 EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 limit(hash CPS_669636 CPS_user_669636 1/1 ) 2023-10-05 08:05:13.471990 [DEBUG] switch_limit.c:124 incr called: CPS_669636_CPS_user_669636 max:1, interval:1 2023-10-05 08:05:13.471990 [DEBUG] mod_hash.c:198 Usage for CPS_669636_CPS_user_669636 is now 1/1 for the last 1 seconds EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(effective_caller_id_name=669636) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [effective_caller_id_name]=[669636] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(effective_caller_id_number=669636) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [effective_caller_id_number]=[669636] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(receiver_accid=1245) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [receiver_accid]=[1245] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 export(domain_name=LibertyFabrics986.yoursipcloud.com) 2023-10-05 08:05:13.471990 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [domain_name]=[LibertyFabrics986.yoursipcloud.com] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 export(sip_to_host=LibertyFabrics986.yoursipcloud.com) 2023-10-05 08:05:13.471990 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [sip_to_host]=[LibertyFabrics986.yoursipcloud.com] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 export(presence_data=x|||Liberty Fabrics(669636)|||||||||DID|||185.249.40.236) 2023-10-05 08:05:13.471990 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||Liberty Fabrics(669636)|||||||||DID|||185.249.40.236] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 export(call_type=6) 2023-10-05 08:05:13.471990 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_type]=[6] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(calltype=FAX) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [calltype]=[FAX] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 answer() 2023-10-05 08:05:13.471990 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/default/31104226413@185.249.40.236. 2023-10-05 08:05:13.471990 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/31104226413@185.249.40.236: v=0 o=FreeSWITCH 1696464069 1696464071 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=audio 21842 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2023-10-05 08:05:13.471990 [NOTICE] mod_dptools.c:1406 Channel [sofia/default/31104226413@185.249.40.236] has been answered 2023-10-05 08:05:13.471990 [DEBUG] switch_channel.c:3865 (sofia/default/31104226413@185.249.40.236) Callstate Change EARLY -> ACTIVE 2023-10-05 08:05:13.471990 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236 entering state [completed][200] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(callfax=true) 2023-10-05 08:05:13.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [callfax]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 playback(silence_stream://2000) 2023-10-05 08:05:13.471990 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms 2023-10-05 08:05:13.471990 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236:5060 entering state [completing][200] 2023-10-05 08:05:13.471990 [DEBUG] sofia.c:7333 Duplicate SDP v=0 o=FreeSWITCH 1696464069 1696464070 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=audio 21842 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2023-10-05 08:05:13.471990 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236:5060 entering state [ready][200] 2023-10-05 08:05:13.471990 [NOTICE] sofia.c:8445 Channel [sofia/default/31104226413@185.249.40.236:5060] has been answered 2023-10-05 08:05:13.471990 [DEBUG] switch_channel.c:3865 (sofia/default/31104226413@185.249.40.236:5060) Callstate Change EARLY -> ACTIVE 2023-10-05 08:05:13.471990 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/31104226413@185.249.40.236:5060] 2023-10-05 08:05:13.471990 [INFO] switch_channel.c:3213 sofia/default/31104226413@185.249.40.236:5060 Flipping CID from "" <31104226413> to "Outbound Call" <31104226413> 2023-10-05 08:05:13.471990 [DEBUG] mod_commands.c:5094 (sofia/default/31104226413@185.249.40.236:5060) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2023-10-05 08:05:13.471990 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236:5060) Running State Change CS_EXECUTE (Cur 8 Tot 482119) 2023-10-05 08:05:13.471990 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31104226413@185.249.40.236:5060) State EXECUTE 2023-10-05 08:05:13.471990 [DEBUG] mod_sofia.c:209 sofia/default/31104226413@185.249.40.236:5060 SOFIA EXECUTE 2023-10-05 08:05:13.471990 [DEBUG] switch_core_state_machine.c:329 sofia/default/31104226413@185.249.40.236:5060 Standard EXECUTE EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236:5060 txfax(/opt/ASTPP/web_interface/astpp/FAX/send_fax_files/20231005060500.tiff) 2023-10-05 08:05:13.471990 [DEBUG] mod_spandsp_fax.c:1514 Raw read codec activation Success L16 20000 2023-10-05 08:05:13.471990 [DEBUG] switch_core_codec.c:223 sofia/default/31104226413@185.249.40.236:5060 Push codec L16:100 2023-10-05 08:05:13.471990 [DEBUG] mod_spandsp_fax.c:1532 Raw write codec activation Success L16 2023-10-05 08:05:13.471990 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236 entering state [ready][200] 2023-10-05 08:05:13.491989 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed. 2023-10-05 08:05:13.791992 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed. 2023-10-05 08:05:15.451987 [DEBUG] switch_ivr_play_say.c:1933 done playing file silence_stream://2000 EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_enable_t38_request=true) 2023-10-05 08:05:15.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_enable_t38_request]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(sip_require_timer=false) 2023-10-05 08:05:15.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [sip_require_timer]=[false] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(call_request=6) 2023-10-05 08:05:15.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [call_request]=[6] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(module_name=apfax) 2023-10-05 08:05:15.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [module_name]=[apfax] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_transfer_rate=9600) 2023-10-05 08:05:15.451987 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_transfer_rate]=[9600] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_use_ecm=false) 2023-10-05 08:05:15.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_use_ecm]=[false] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_v17_disabled=false) 2023-10-05 08:05:15.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_v17_disabled]=[false] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_disable_v17=true) 2023-10-05 08:05:15.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_disable_v17]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(fax_enable_t38=true) 2023-10-05 08:05:15.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [fax_enable_t38]=[true] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 set(api_hangup_hook=system /var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.tif 669636 Liberty+Fabrics_ tushar.devani@inextrix.com /var/www/html/astpp/attachments/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.pdf tushar.devani@inextrix.com 31104226413 669636) 2023-10-05 08:05:15.471990 [DEBUG] mod_dptools.c:1672 SET sofia/default/31104226413@185.249.40.236 [api_hangup_hook]=[system /var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.tif 669636 Liberty+Fabrics_ tushar.devani@inextrix.com /var/www/html/astpp/attachments/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.pdf tushar.devani@inextrix.com 31104226413 669636] EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 rxfax(/var/www/html/astpp/FAX/receive_fax_files/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.tif) 2023-10-05 08:05:15.471990 [DEBUG] mod_spandsp_fax.c:1514 Raw read codec activation Success L16 20000 2023-10-05 08:05:15.471990 [DEBUG] switch_core_codec.c:223 sofia/default/31104226413@185.249.40.236 Push codec L16:100 2023-10-05 08:05:15.471990 [DEBUG] mod_spandsp_fax.c:1532 Raw write codec activation Success L16 2023-10-05 08:05:16.551991 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [10@ZT135225.yoursipcloud.com] from ip 45.129.144.205 2023-10-05 08:05:16.731991 [DEBUG] switch_core_media.c:11914 sofia/default/31104226413@185.249.40.236 image media sdp: v=0 o=FreeSWITCH 1696464069 1696464072 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=image 21842 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2023-10-05 08:05:16.731991 [DEBUG] sofia_glue.c:1618 sofia/default/31104226413@185.249.40.236 sending invite version: 1.10.5 -release-17-25569c1631 64bit Local SDP: v=0 o=FreeSWITCH 1696464069 1696464072 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=image 21842 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2023-10-05 08:05:16.751994 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236:5060 entering state [received][100] 2023-10-05 08:05:16.751994 [DEBUG] sofia.c:7336 Remote SDP: v=0 o=FreeSWITCH 1696464069 1696464072 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=image 21842 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2023-10-05 08:05:16.751994 [DEBUG] switch_core_media.c:5185 sofia/default/31104226413@185.249.40.236:5060 T38 ACCEPT on request 2023-10-05 08:05:16.751994 [DEBUG] switch_core_media.c:5289 sofia/default/31104226413@185.249.40.236:5060 T38 IS POSSIBLE on request 2023-10-05 08:05:16.751994 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [255@290225.yoursipcloud.com] from ip 185.249.42.49 2023-10-05 08:05:16.751994 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236 entering state [calling][0] 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1095 T38 SDP Origin = FreeSWITCH 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1096 T38FaxVersion = 0 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1097 T38MaxBitRate = 9600 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1098 T38FaxFillBitRemoval = 1 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1099 T38FaxTranscodingMMR = 0 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1100 T38FaxTranscodingJBIG = 0 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1101 T38FaxRateManagement = 'transferredTCF' 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1102 T38FaxMaxBuffer = 2000 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1103 T38FaxMaxDatagram = 400 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1104 T38FaxUdpEC = 't38UDPRedundancy' 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1105 T38VendorInfo = '' 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1106 ip = '185.249.40.236' 2023-10-05 08:05:16.771990 [DEBUG] mod_spandsp_fax.c:1108 port = 21842 2023-10-05 08:05:16.771990 [DEBUG] switch_core_media.c:12343 Remote address:port [185.249.40.236:21842] has not changed. 2023-10-05 08:05:16.771990 [DEBUG] switch_core_media.c:11914 sofia/default/31104226413@185.249.40.236:5060 image media sdp: v=0 o=FreeSWITCH 1696460839 1696460841 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=image 25072 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2023-10-05 08:05:16.771990 [DEBUG] switch_core_media.c:12343 Remote address:port [185.249.40.236:21842] has not changed. 2023-10-05 08:05:16.771990 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236:5060 entering state [completed][200] 2023-10-05 08:05:16.771990 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236 entering state [completing][200] 2023-10-05 08:05:16.771990 [DEBUG] sofia.c:7336 Remote SDP: v=0 o=FreeSWITCH 1696460839 1696460841 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=image 25072 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:2000 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPRedundancy 2023-10-05 08:05:16.791990 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236:5060 entering state [ready][200] 2023-10-05 08:05:16.791990 [DEBUG] sofia.c:7326 Channel sofia/default/31104226413@185.249.40.236 entering state [ready][200] 2023-10-05 08:05:16.811990 [DEBUG] switch_core_media.c:5185 sofia/default/31104226413@185.249.40.236 T38 ACCEPT on response 2023-10-05 08:05:16.811990 [DEBUG] switch_core_media.c:5289 sofia/default/31104226413@185.249.40.236 T38 IS POSSIBLE on response 2023-10-05 08:05:16.811990 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/default/31104226413@185.249.40.236. 2023-10-05 08:05:16.811990 [DEBUG] switch_core_media.c:12343 Remote address:port [185.249.40.236:25072] has not changed. 2023-10-05 08:05:16.811990 [DEBUG] switch_core_media.c:12343 Remote address:port [185.249.40.236:25072] has not changed. 2023-10-05 08:05:16.871992 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [11@ZT135225.yoursipcloud.com] from ip 45.129.144.205 2023-10-05 08:05:16.911989 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [1004@NewPeople959.yoursipcloud.com] from ip 77.249.198.103 2023-10-05 08:05:16.931988 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [1001@newpeople959.yoursipcloud.com] from ip 77.249.198.103 2023-10-05 08:05:17.111989 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [1003@NewPeople959.yoursipcloud.com] from ip 77.249.198.103 2023-10-05 08:05:17.231986 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@260715.yoursipcloud.com] from ip 185.249.42.137 2023-10-05 08:05:17.231986 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [1002@NewPeople959.yoursipcloud.com] from ip 77.249.198.103 2023-10-05 08:05:19.011988 [NOTICE] switch_ivr_originate.c:3759 Hangup sofia/default/31651333158 [CS_CONSUME_MEDIA] [NO_ANSWER] 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31651333158) Running State Change CS_HANGUP (Cur 8 Tot 482119) 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31651333158) Callstate Change RINGING -> HANGUP 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31651333158) State HANGUP 2023-10-05 08:05:19.011988 [DEBUG] mod_sofia.c:453 Channel sofia/default/31651333158 hanging up, cause: NO_ANSWER 2023-10-05 08:05:19.011988 [INFO] mod_dptools.c:3631 Originate Failed. Cause: NO_ANSWER EXECUTE [depth=0] sofia/default/788323@89.255.20.14 bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/sbcold2/31651333158) 2023-10-05 08:05:19.011988 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/31651333158 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:60 sofia/default/31651333158 Standard HANGUP, cause: NO_ANSWER 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31651333158) State HANGUP going to sleep 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31651333158) State Change CS_HANGUP -> CS_REPORTING 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31651333158) Running State Change CS_REPORTING (Cur 8 Tot 482119) 2023-10-05 08:05:19.011988 [DEBUG] switch_channel.c:1264 sofia/default/788323@89.255.20.14 EXPORTING[export_vars] [presence_data]=[x//189|||facta(788323)|||^316.* // // 0.09500 |||SBCs // ^3165.* // Netherlands Mobile // 0.00065 // trunk_id=4|||STD|||89.255.20.14|||0.00605 // ^3165.* // Netherlands Mobile] to event 2023-10-05 08:05:19.011988 [DEBUG] switch_channel.c:1264 sofia/default/788323@89.255.20.14 EXPORTING[export_vars] [presence_id]=[31651333158] to event 2023-10-05 08:05:19.011988 [DEBUG] switch_channel.c:1264 sofia/default/788323@89.255.20.14 EXPORTING[export_vars] [presence_data]=[x//189|||facta(788323)|||^316.* // // 0.09500 |||SBCs // ^3165.* // Netherlands Mobile // 0.00065 // trunk_id=4|||STD|||89.255.20.14|||0.00605 // ^3165.* // Netherlands Mobile] to event 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31651333158) State REPORTING 2023-10-05 08:05:19.011988 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables 2023-10-05 08:05:19.011988 [DEBUG] switch_ivr_originate.c:2804 Parsing session specific variables 2023-10-05 08:05:19.011988 [INFO] mod_json_cdr.c:271 Process [4a3953f4-33b3-4ac6-a718-8973d1679762.cdr.json] 2023-10-05 08:05:19.011988 [NOTICE] switch_channel.c:1118 New Channel sofia/default/31651333158 [37b05e7b-c815-4f1b-861e-01f0e19872a0] 2023-10-05 08:05:19.011988 [DEBUG] mod_sofia.c:5089 (sofia/default/31651333158) State Change CS_NEW -> CS_INIT 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31651333158) Running State Change CS_INIT (Cur 9 Tot 482120) 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31651333158) State INIT 2023-10-05 08:05:19.011988 [DEBUG] mod_sofia.c:93 sofia/default/31651333158 SOFIA INIT 2023-10-05 08:05:19.011988 [DEBUG] sofia_glue.c:1618 sofia/default/31651333158 sending invite version: 1.10.5 -release-17-25569c1631 64bit Local SDP: v=0 o=FreeSWITCH 1696455775 1696455776 IN IP4 185.249.40.236 s=FreeSWITCH c=IN IP4 185.249.40.236 t=0 0 m=audio 30144 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:40 sofia/default/31651333158 Standard INIT 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:48 (sofia/default/31651333158) State Change CS_INIT -> CS_ROUTING 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31651333158) State INIT going to sleep 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31651333158) Running State Change CS_ROUTING (Cur 9 Tot 482120) 2023-10-05 08:05:19.011988 [DEBUG] sofia.c:7326 Channel sofia/default/31651333158 entering state [calling][0] 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31651333158) State ROUTING 2023-10-05 08:05:19.011988 [DEBUG] mod_sofia.c:154 sofia/default/31651333158 SOFIA ROUTING 2023-10-05 08:05:19.011988 [DEBUG] switch_ivr_originate.c:67 (sofia/default/31651333158) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31651333158) State ROUTING going to sleep 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31651333158) Running State Change CS_CONSUME_MEDIA (Cur 9 Tot 482120) 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:663 (sofia/default/31651333158) State CONSUME_MEDIA 2023-10-05 08:05:19.011988 [DEBUG] switch_core_state_machine.c:663 (sofia/default/31651333158) State CONSUME_MEDIA going to sleep 2023-10-05 08:05:19.031986 [DEBUG] switch_core_state_machine.c:174 sofia/default/31651333158 Standard REPORTING, cause: NO_ANSWER 2023-10-05 08:05:19.031986 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31651333158) State REPORTING going to sleep 2023-10-05 08:05:19.031986 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31651333158) State Change CS_REPORTING -> CS_DESTROY 2023-10-05 08:05:19.031986 [DEBUG] switch_core_session.c:1726 Session 482116 (sofia/default/31651333158) Locked, Waiting on external entities 2023-10-05 08:05:19.031986 [NOTICE] switch_core_session.c:1744 Session 482116 (sofia/default/31651333158) Ended 2023-10-05 08:05:19.031986 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31651333158 [CS_DESTROY] 2023-10-05 08:05:19.031986 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31651333158) Running State Change CS_DESTROY (Cur 8 Tot 482120) 2023-10-05 08:05:19.031986 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31651333158) State DESTROY 2023-10-05 08:05:19.031986 [DEBUG] mod_sofia.c:364 sofia/default/31651333158 SOFIA DESTROY 2023-10-05 08:05:19.031986 [DEBUG] switch_core_state_machine.c:181 sofia/default/31651333158 Standard DESTROY 2023-10-05 08:05:19.031986 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31651333158) State DESTROY going to sleep 2023-10-05 08:05:19.351991 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [10@00135880.yoursipcloud.com] from ip 185.249.42.175 2023-10-05 08:05:19.591986 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [255@00135880.yoursipcloud.com] from ip 185.249.42.175 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:346 === Negotiation Result ======================================================= 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:347 Remote station id: SpanDSP Fax Ident 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:348 Local station id: 669636 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:349 Transfer Rate: 9600 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:350 ECM status off 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:351 T38 status negotiated 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:352 remote country: 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:353 remote vendor: 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:354 remote model: 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:356 Total fax pages: 0 2023-10-05 08:05:22.211988 [DEBUG] mod_spandsp_fax.c:359 ============================================================================== 2023-10-05 08:05:23.711986 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [11@00189001.yoursipcloud.com] from ip 45.129.144.100 2023-10-05 08:05:23.951987 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [12@00189001.yoursipcloud.com] from ip 45.129.144.100 2023-10-05 08:05:24.152033 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [13@00189001.yoursipcloud.com] from ip 45.129.144.100 2023-10-05 08:05:24.152033 [NOTICE] sofia.c:1089 Hangup sofia/default/31528225522 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2023-10-05 08:05:24.152033 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31528225522] 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31528225522) State EXCHANGE_MEDIA going to sleep 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31528225522) Running State Change CS_HANGUP (Cur 8 Tot 482120) 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31528225522) Callstate Change ACTIVE -> HANGUP 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31528225522) State HANGUP 2023-10-05 08:05:24.152033 [DEBUG] mod_sofia.c:453 Channel sofia/default/31528225522 hanging up, cause: NORMAL_CLEARING 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:60 sofia/default/31528225522 Standard HANGUP, cause: NORMAL_CLEARING 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31528225522) State HANGUP going to sleep 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31528225522) State Change CS_HANGUP -> CS_REPORTING 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31528225522) Running State Change CS_REPORTING (Cur 8 Tot 482120) 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31528225522) State REPORTING 2023-10-05 08:05:24.152033 [INFO] mod_json_cdr.c:271 Process [5d3e43df-6796-4ee0-93a7-ab3b1a8b9ffa.cdr.json] 2023-10-05 08:05:24.152033 [DEBUG] switch_ivr_bridge.c:820 sofia/default/31528225522 ending bridge by request from write function 2023-10-05 08:05:24.152033 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/201@00135895.yoursipcloud.com] 2023-10-05 08:05:24.152033 [DEBUG] switch_ivr_bridge.c:1892 sofia/default/31528225522 skip receive message [UNBRIDGE] (channel is hungup already) 2023-10-05 08:05:24.152033 [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/default/201@00135895.yoursipcloud.com [CS_EXECUTE] [NORMAL_CLEARING] 2023-10-05 08:05:24.152033 [DEBUG] mod_hash.c:296 Usage for CPS_00135895_CPS_user_00135895 is now 0 2023-10-05 08:05:24.152033 [DEBUG] switch_core_session.c:2905 sofia/default/201@00135895.yoursipcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:651 (sofia/default/201@00135895.yoursipcloud.com) State EXECUTE going to sleep 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:585 (sofia/default/201@00135895.yoursipcloud.com) Running State Change CS_HANGUP (Cur 8 Tot 482120) 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:848 (sofia/default/201@00135895.yoursipcloud.com) Callstate Change ACTIVE -> HANGUP 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:850 (sofia/default/201@00135895.yoursipcloud.com) State HANGUP 2023-10-05 08:05:24.152033 [DEBUG] mod_sofia.c:447 sofia/default/201@00135895.yoursipcloud.com Overriding SIP cause 480 with 200 from the other leg 2023-10-05 08:05:24.152033 [DEBUG] mod_sofia.c:453 Channel sofia/default/201@00135895.yoursipcloud.com hanging up, cause: NORMAL_CLEARING 2023-10-05 08:05:24.152033 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/201@00135895.yoursipcloud.com 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:60 sofia/default/201@00135895.yoursipcloud.com Standard HANGUP, cause: NORMAL_CLEARING 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:850 (sofia/default/201@00135895.yoursipcloud.com) State HANGUP going to sleep 2023-10-05 08:05:24.152033 [DEBUG] switch_core_state_machine.c:620 (sofia/default/201@00135895.yoursipcloud.com) State Change CS_HANGUP -> CS_REPORTING 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:585 (sofia/default/201@00135895.yoursipcloud.com) Running State Change CS_REPORTING (Cur 8 Tot 482120) 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:936 (sofia/default/201@00135895.yoursipcloud.com) State REPORTING 2023-10-05 08:05:24.191987 [INFO] mod_json_cdr.c:271 Process [b4bc9a03-e9f2-47e2-b8e9-15011adf241c.cdr.json] 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:174 sofia/default/31528225522 Standard REPORTING, cause: NORMAL_CLEARING 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31528225522) State REPORTING going to sleep 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31528225522) State Change CS_REPORTING -> CS_DESTROY 2023-10-05 08:05:24.191987 [DEBUG] switch_core_session.c:1726 Session 482109 (sofia/default/31528225522) Locked, Waiting on external entities 2023-10-05 08:05:24.191987 [NOTICE] switch_core_session.c:1744 Session 482109 (sofia/default/31528225522) Ended 2023-10-05 08:05:24.191987 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31528225522 [CS_DESTROY] 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31528225522) Running State Change CS_DESTROY (Cur 7 Tot 482120) 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31528225522) State DESTROY 2023-10-05 08:05:24.191987 [DEBUG] mod_sofia.c:364 sofia/default/31528225522 SOFIA DESTROY 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:181 sofia/default/31528225522 Standard DESTROY 2023-10-05 08:05:24.191987 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31528225522) State DESTROY going to sleep 2023-10-05 08:05:24.231998 [DEBUG] switch_core_state_machine.c:174 sofia/default/201@00135895.yoursipcloud.com Standard REPORTING, cause: NORMAL_CLEARING 2023-10-05 08:05:24.231998 [DEBUG] switch_core_state_machine.c:936 (sofia/default/201@00135895.yoursipcloud.com) State REPORTING going to sleep 2023-10-05 08:05:24.231998 [DEBUG] switch_core_state_machine.c:611 (sofia/default/201@00135895.yoursipcloud.com) State Change CS_REPORTING -> CS_DESTROY 2023-10-05 08:05:24.231998 [DEBUG] switch_core_session.c:1726 Session 482108 (sofia/default/201@00135895.yoursipcloud.com) Locked, Waiting on external entities 2023-10-05 08:05:24.231998 [NOTICE] switch_core_session.c:1744 Session 482108 (sofia/default/201@00135895.yoursipcloud.com) Ended 2023-10-05 08:05:24.231998 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/201@00135895.yoursipcloud.com [CS_DESTROY] 2023-10-05 08:05:24.231998 [DEBUG] switch_core_state_machine.c:739 (sofia/default/201@00135895.yoursipcloud.com) Running State Change CS_DESTROY (Cur 6 Tot 482120) 2023-10-05 08:05:24.231998 [DEBUG] switch_core_state_machine.c:749 (sofia/default/201@00135895.yoursipcloud.com) State DESTROY 2023-10-05 08:05:24.231998 [DEBUG] mod_sofia.c:364 sofia/default/201@00135895.yoursipcloud.com SOFIA DESTROY 2023-10-05 08:05:24.231998 [DEBUG] switch_core_state_machine.c:181 sofia/default/201@00135895.yoursipcloud.com Standard DESTROY 2023-10-05 08:05:24.231998 [DEBUG] switch_core_state_machine.c:749 (sofia/default/201@00135895.yoursipcloud.com) State DESTROY going to sleep 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:346 === Negotiation Result ======================================================= 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:347 Remote station id: 669636 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:348 Local station id: SpanDSP Fax Ident 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:349 Transfer Rate: 9600 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:350 ECM status off 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:351 T38 status negotiated 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:352 remote country: 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:353 remote vendor: 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:354 remote model: 2023-10-05 08:05:24.391989 [DEBUG] mod_spandsp_fax.c:359 ============================================================================== 2023-10-05 08:05:24.511989 [DEBUG] switch_scheduler.c:147 Deleting task 86212 switch_ivr_schedule_hangup (5d3e43df-6796-4ee0-93a7-ab3b1a8b9ffa) 2023-10-05 08:05:26.131987 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [202@260278.yoursipcloud.com] from ip 45.129.144.58 2023-10-05 08:05:27.251988 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@260278.yoursipcloud.com] from ip 45.129.144.58 2023-10-05 08:05:30.191990 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@MT135401.yoursipcloud.com] from ip 45.129.144.213 2023-10-05 08:05:30.251985 [WARNING] sofia_reg.c:2930 Can't find user [201@MT135401.yoursipcloud.com] from 45.129.144.213 You must define a domain called 'MT135401.yoursipcloud.com' in your directory and add a user with the id="201" attribute and you must configure your device to use the proper domain in its authentication credentials. 2023-10-05 08:05:30.291990 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@MT135401.yoursipcloud.com] from ip 45.129.144.213 2023-10-05 08:05:30.351989 [WARNING] sofia_reg.c:2930 Can't find user [201@MT135401.yoursipcloud.com] from 45.129.144.213 You must define a domain called 'MT135401.yoursipcloud.com' in your directory and add a user with the id="201" attribute and you must configure your device to use the proper domain in its authentication credentials. 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:471 ==== Page Received =========================================================== 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:472 Page no = 1 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:476 Image type = bi-level (bi-level in the file) 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:477 Image size = 1728 x 1202 pixels (1728 x 1202 pixels in the file) 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:478 Image resolution = 8040/m x 3850/m (8040/m x 3850/m in the file) 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:479 Compression = T.4 2-D (4) 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:480 Compressed image size = 3820 bytes 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:481 Bad rows = 0 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:482 Longest bad row run = 0 2023-10-05 08:05:32.711987 [DEBUG] mod_spandsp_fax.c:483 ============================================================================== 2023-10-05 08:05:33.291988 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [100@401433.yoursipcloud.com] from ip 45.129.146.163 2023-10-05 08:05:33.471988 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [103@401433.yoursipcloud.com] from ip 45.129.146.163 2023-10-05 08:05:33.631989 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [106@401433.yoursipcloud.com] from ip 45.129.146.163 2023-10-05 08:05:33.831997 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [105@401433.yoursipcloud.com] from ip 45.129.146.163 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:471 ==== Page Sent =============================================================== 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:472 Page no = 1 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:474 Total fax pages: 1 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:476 Image type = bi-level (bi-level in the file) 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:477 Image size = 1728 x 1202 pixels (1728 x 1186 pixels in the file) 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:478 Image resolution = 8040/m x 3850/m (8031/m x 3858/m in the file) 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:479 Compression = T.4 2-D (4) 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:480 Compressed image size = 3820 bytes 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:481 Bad rows = 0 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:482 Longest bad row run = 0 2023-10-05 08:05:33.892021 [DEBUG] mod_spandsp_fax.c:483 ============================================================================== 2023-10-05 08:05:34.271985 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [102@401433.yoursipcloud.com] from ip 45.129.146.163 2023-10-05 08:05:34.451988 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [101@401433.yoursipcloud.com] from ip 45.129.146.163 2023-10-05 08:05:34.631991 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [104@401433.yoursipcloud.com] from ip 45.129.146.163 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:545 ============================================================================== 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:551 Fax successfully received. 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:562 Remote station id: 669636 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:563 Local station id: SpanDSP Fax Ident 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:564 Pages transferred: 1 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:566 Total fax pages: 1 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:567 Image resolution: 8040x3850 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:568 Transfer Rate: 9600 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:570 ECM status off 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:571 T38 status negotiated 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:572 remote country: 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:573 remote vendor: 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:574 remote model: 2023-10-05 08:05:35.071989 [DEBUG] mod_spandsp_fax.c:576 ============================================================================== 2023-10-05 08:05:35.071989 [DEBUG] switch_core_codec.c:248 sofia/default/31104226413@185.249.40.236 Restore previous codec PCMA:8. EXECUTE [depth=0] sofia/default/31104226413@185.249.40.236 hangup() 2023-10-05 08:05:35.071989 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/31104226413@185.249.40.236 [CS_EXECUTE] [NORMAL_CLEARING] 2023-10-05 08:05:35.071989 [DEBUG] mod_hash.c:296 Usage for CPS_669636_CPS_user_669636 is now 0 2023-10-05 08:05:35.071989 [DEBUG] switch_core_session.c:2905 sofia/default/31104226413@185.249.40.236 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2023-10-05 08:05:35.071989 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31104226413@185.249.40.236) State EXECUTE going to sleep 2023-10-05 08:05:35.071989 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236) Running State Change CS_HANGUP (Cur 6 Tot 482120) 2023-10-05 08:05:35.071989 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31104226413@185.249.40.236) Callstate Change ACTIVE -> HANGUP 2023-10-05 08:05:35.071989 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31104226413@185.249.40.236) State HANGUP 2023-10-05 08:05:35.071989 [DEBUG] mod_sofia.c:453 Channel sofia/default/31104226413@185.249.40.236 hanging up, cause: NORMAL_CLEARING 2023-10-05 08:05:35.071989 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/31104226413@185.249.40.236 2023-10-05 08:05:35.071989 [DEBUG] switch_core_state_machine.c:60 sofia/default/31104226413@185.249.40.236 Standard HANGUP, cause: NORMAL_CLEARING 2023-10-05 08:05:35.071989 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31104226413@185.249.40.236) State HANGUP going to sleep 2023-10-05 08:05:35.092001 [NOTICE] sofia.c:1089 Hangup sofia/default/31104226413@185.249.40.236:5060 [CS_EXECUTE] [NORMAL_CLEARING] 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:545 ============================================================================== 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:549 Fax successfully sent. 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:562 Remote station id: SpanDSP Fax Ident 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:563 Local station id: 669636 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:564 Pages transferred: 1 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:566 Total fax pages: 1 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:567 Image resolution: 8040x3850 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:568 Transfer Rate: 9600 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:570 ECM status off 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:571 T38 status negotiated 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:572 remote country: 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:573 remote vendor: 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:574 remote model: 2023-10-05 08:05:35.092001 [DEBUG] mod_spandsp_fax.c:576 ============================================================================== 2023-10-05 08:05:35.092001 [DEBUG] switch_core_codec.c:248 sofia/default/31104226413@185.249.40.236:5060 Restore previous codec PCMA:8. 2023-10-05 08:05:35.092001 [DEBUG] switch_core_session.c:2905 sofia/default/31104226413@185.249.40.236:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2023-10-05 08:05:35.092001 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31104226413@185.249.40.236:5060) State EXECUTE going to sleep 2023-10-05 08:05:35.092001 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236:5060) Running State Change CS_HANGUP (Cur 6 Tot 482120) 2023-10-05 08:05:35.092001 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31104226413@185.249.40.236:5060) Callstate Change ACTIVE -> HANGUP 2023-10-05 08:05:35.092001 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31104226413@185.249.40.236:5060) State HANGUP 2023-10-05 08:05:35.092001 [DEBUG] mod_sofia.c:453 Channel sofia/default/31104226413@185.249.40.236:5060 hanging up, cause: NORMAL_CLEARING 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:60 sofia/default/31104226413@185.249.40.236:5060 Standard HANGUP, cause: NORMAL_CLEARING 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31104226413@185.249.40.236:5060) State HANGUP going to sleep 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31104226413@185.249.40.236:5060) State Change CS_HANGUP -> CS_REPORTING 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236:5060) Running State Change CS_REPORTING (Cur 6 Tot 482120) 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31104226413@185.249.40.236:5060) State REPORTING 2023-10-05 08:05:35.112037 [INFO] mod_json_cdr.c:271 Process [526e9fc3-3cc8-48b9-8d25-9194da9e96a2.cdr.json] 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:174 sofia/default/31104226413@185.249.40.236:5060 Standard REPORTING, cause: NORMAL_CLEARING 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31104226413@185.249.40.236:5060) State REPORTING going to sleep 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31104226413@185.249.40.236:5060) State Change CS_REPORTING -> CS_DESTROY 2023-10-05 08:05:35.112037 [DEBUG] switch_core_session.c:1726 Session 482118 (sofia/default/31104226413@185.249.40.236:5060) Locked, Waiting on external entities 2023-10-05 08:05:35.112037 [NOTICE] switch_core_session.c:1744 Session 482118 (sofia/default/31104226413@185.249.40.236:5060) Ended 2023-10-05 08:05:35.112037 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31104226413@185.249.40.236:5060 [CS_DESTROY] 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31104226413@185.249.40.236:5060) Running State Change CS_DESTROY (Cur 5 Tot 482120) 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31104226413@185.249.40.236:5060) State DESTROY 2023-10-05 08:05:35.112037 [DEBUG] mod_sofia.c:364 sofia/default/31104226413@185.249.40.236:5060 SOFIA DESTROY 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:181 sofia/default/31104226413@185.249.40.236:5060 Standard DESTROY 2023-10-05 08:05:35.112037 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31104226413@185.249.40.236:5060) State DESTROY going to sleep 2023-10-05 08:05:35.471996 [DEBUG] switch_core_state_machine.c:781 Hangup Command with Session system(/var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.tif 669636 Liberty+Fabrics_ tushar.devani@inextrix.com /var/www/html/astpp/attachments/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.pdf tushar.devani@inextrix.com 31104226413 669636): /var/www/html/astpp/FAX/scripts/emailfax.php/var/www/html/astpp/FAX/receive_fax_files/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.tif669636Liberty+Fabrics_tushar.devani@inextrix.com31104226413/var/www/html/astpp/attachments/60a893e4-7abd-4910-b838-7a9a735cf7a9-FAX.pdf669636 2023-10-05 08:05:35.471996 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31104226413@185.249.40.236) State Change CS_HANGUP -> CS_REPORTING 2023-10-05 08:05:35.491994 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31104226413@185.249.40.236) Running State Change CS_REPORTING (Cur 5 Tot 482120) 2023-10-05 08:05:35.491994 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31104226413@185.249.40.236) State REPORTING 2023-10-05 08:05:35.491994 [INFO] mod_json_cdr.c:271 Process [60a893e4-7abd-4910-b838-7a9a735cf7a9.cdr.json] 2023-10-05 08:05:35.551988 [DEBUG] switch_core_state_machine.c:174 sofia/default/31104226413@185.249.40.236 Standard REPORTING, cause: NORMAL_CLEARING 2023-10-05 08:05:35.551988 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31104226413@185.249.40.236) State REPORTING going to sleep 2023-10-05 08:05:35.551988 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31104226413@185.249.40.236) State Change CS_REPORTING -> CS_DESTROY 2023-10-05 08:05:35.551988 [DEBUG] switch_core_session.c:1726 Session 482119 (sofia/default/31104226413@185.249.40.236) Locked, Waiting on external entities 2023-10-05 08:05:35.551988 [NOTICE] switch_core_session.c:1744 Session 482119 (sofia/default/31104226413@185.249.40.236) Ended 2023-10-05 08:05:35.551988 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31104226413@185.249.40.236 [CS_DESTROY] 2023-10-05 08:05:35.551988 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31104226413@185.249.40.236) Running State Change CS_DESTROY (Cur 4 Tot 482120) 2023-10-05 08:05:35.551988 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31104226413@185.249.40.236) State DESTROY 2023-10-05 08:05:35.551988 [DEBUG] mod_sofia.c:364 sofia/default/31104226413@185.249.40.236 SOFIA DESTROY 2023-10-05 08:05:35.551988 [DEBUG] switch_core_state_machine.c:181 sofia/default/31104226413@185.249.40.236 Standard DESTROY 2023-10-05 08:05:35.551988 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31104226413@185.249.40.236) State DESTROY going to sleep