+OK log level [7] 2018-12-08 12:35:30.112024 [NOTICE] switch_channel.c:1104 New Channel sofia/default/32490112449@sip.voip.united-telecom.be [6d77aa66-a0e4-49d6-8cd4-53329204ec0c] 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_NEW (Cur 1 Tot 76) 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:9873 sofia/default/32490112449@sip.voip.united-telecom.be receiving invite from 87.238.166.8:5060 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:9989 IP 87.238.166.8 Approved by acl "default[]". Access Granted. 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [received][100] 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=root 178137107 178137107 IN IP4 87.238.166.51 s=SpeakUp Gateway c=IN IP4 87.238.166.51 t=0 0 m=audio 12582 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=nortpproxy:yes 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/32490112449@sip.voip.united-telecom.be PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2018-12-08 12:35:30.112024 [DEBUG] switch_core_codec.c:111 sofia/default/32490112449@sip.voip.united-telecom.be Original read codec set to PCMA:8 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4767 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 recv payload to 101 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:7507 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_NEW -> CS_INIT 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:603 (sofia/default/32490112449@sip.voip.united-telecom.be) State NEW 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_INIT (Cur 1 Tot 76) 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT 2018-12-08 12:35:30.112024 [DEBUG] mod_sofia.c:90 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA INIT 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:40 sofia/default/32490112449@sip.voip.united-telecom.be Standard INIT 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:48 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_INIT -> CS_ROUTING 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT going to sleep 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_ROUTING (Cur 1 Tot 76) 2018-12-08 12:35:30.112024 [DEBUG] switch_channel.c:2249 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change DOWN -> RINGING 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING 2018-12-08 12:35:30.112024 [DEBUG] mod_sofia.c:143 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA ROUTING 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:236 sofia/default/32490112449@sip.voip.united-telecom.be Standard ROUTING 2018-12-08 12:35:30.112024 [INFO] mod_dialplan_xml.c:637 Processing 32490112449 <32490112449>->fwwwastpp in context default 2018-12-08 12:35:30.132006 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fe684065980 Connected. 2018-12-08 12:35:30.132006 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard') 2018-12-08 12:35:30.132006 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : fwwwastpp 2018-12-08 12:35:30.132006 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number : 32490112449 / 32490112449 2018-12-08 12:35:30.132006 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="fwwwastpp" LIMIT 1 2018-12-08 12:35:30.132006 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="fwwwastpp" limit 1 2018-12-08 12:35:30.132006 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound 2018-12-08 12:35:30.132006 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("87.238.166.8") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 - SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1)) AND "fwwwastpp" LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1 2018-12-08 12:35:30.132006 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call authentication fail..!!1 2018-12-08 12:35:30.132006 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Generated XML:
2018-12-08 12:35:30.132006 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fe684065980 released. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be parsing [default->fwwwastpp] continue=false Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Regex (PASS) [fwwwastpp] destination_number(fwwwastpp) =~ /fwwwastpp/ break=on-false Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action log(WARNING Accountcode is not authenticated!!) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_name=32490112449) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_number=32490112449) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(error_cdr=1) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(callstart=2018-12-08 11:35:30) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(account_id=0) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_direction=outbound) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(sip_ignore_remote_cause=true) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_processed=internal) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(effective_destination_number=fwwwastpp) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(last_bridge_hangup_cause=AUTHENTICATION_FAIL) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action hangup(AUTHENTICATION_FAIL) 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:286 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_ROUTING -> CS_EXECUTE 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING going to sleep 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_EXECUTE (Cur 1 Tot 76) 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE 2018-12-08 12:35:30.132006 [DEBUG] mod_sofia.c:198 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA EXECUTE 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:328 sofia/default/32490112449@sip.voip.united-telecom.be Standard EXECUTE EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be log(WARNING Accountcode is not authenticated!!) 2018-12-08 12:35:30.132006 [WARNING] mod_dptools.c:1742 Accountcode is not authenticated!! 2018-12-08 12:35:30.132006 [DEBUG] switch_core_session.c:2647 Application playback Requires media! pre_answering channel sofia/default/32490112449@sip.voip.united-telecom.be 2018-12-08 12:35:30.132006 [INFO] switch_core_session.c:2649 Sending early media 2018-12-08 12:35:30.132006 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/32490112449@sip.voip.united-telecom.be] 109.68.167.132 port 25076 -> 87.238.166.51 port 12582 codec: 8 ms: 20 2018-12-08 12:35:30.132006 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms 2018-12-08 12:35:30.132006 [DEBUG] switch_core_media.c:7179 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 2018-12-08 12:35:30.132006 [DEBUG] switch_core_media.c:7186 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf receive payload to 101 2018-12-08 12:35:30.132006 [DEBUG] switch_core_media.c:7209 sofia/default/32490112449@sip.voip.united-telecom.be Set rtp dtmf delay to 40 2018-12-08 12:35:30.132006 [DEBUG] mod_sofia.c:2364 Ring SDP: v=0 o=FreeSWITCH 1544243854 1544243855 IN IP4 109.68.167.132 s=FreeSWITCH c=IN IP4 109.68.167.132 t=0 0 m=audio 25076 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 2018-12-08 12:35:30.132006 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/32490112449@sip.voip.united-telecom.be! 2018-12-08 12:35:30.132006 [DEBUG] switch_channel.c:3474 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change RINGING -> EARLY EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav) 2018-12-08 12:35:30.132006 [DEBUG] switch_core_file.c:342 File /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav sample rate 16000 doesn't match requested rate 8000 2018-12-08 12:35:30.132006 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 2018-12-08 12:35:30.132006 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [early][183] 2018-12-08 12:35:30.192020 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed. 2018-12-08 12:35:31.652014 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_name=32490112449) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_name]=[32490112449] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_number=32490112449) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_number]=[32490112449] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(error_cdr=1) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [error_cdr]=[1] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(callstart=2018-12-08 11:35:30) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [callstart]=[2018-12-08 11:35:30] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(account_id=0) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [account_id]=[0] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_direction=outbound) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_direction]=[outbound] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(sip_ignore_remote_cause=true) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [sip_ignore_remote_cause]=[true] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_processed=internal) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_processed]=[internal] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(effective_destination_number=fwwwastpp) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [effective_destination_number]=[fwwwastpp] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(last_bridge_hangup_cause=AUTHENTICATION_FAIL) 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [last_bridge_hangup_cause]=[AUTHENTICATION_FAIL] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be hangup(AUTHENTICATION_FAIL) 2018-12-08 12:35:31.652014 [NOTICE] mod_dptools.c:1286 Hangup sofia/default/32490112449@sip.voip.united-telecom.be [CS_EXECUTE] [NORMAL_CLEARING] 2018-12-08 12:35:31.652014 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@sip.voip.united-telecom.be skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE going to sleep 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_HANGUP (Cur 1 Tot 76) 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change EARLY -> HANGUP 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP 2018-12-08 12:35:31.652014 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@sip.voip.united-telecom.be hanging up, cause: NORMAL_CLEARING 2018-12-08 12:35:31.652014 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@sip.voip.united-telecom.be Standard HANGUP, cause: NORMAL_CLEARING 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP going to sleep 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_HANGUP -> CS_REPORTING 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_REPORTING (Cur 1 Tot 76) 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING 2018-12-08 12:35:31.652014 [INFO] mod_json_cdr.c:271 Process [6d77aa66-a0e4-49d6-8cd4-53329204ec0c.cdr.json] 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@sip.voip.united-telecom.be Standard REPORTING, cause: NORMAL_CLEARING 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING going to sleep 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_REPORTING -> CS_DESTROY 2018-12-08 12:35:31.672002 [DEBUG] switch_core_session.c:1665 Session 76 (sofia/default/32490112449@sip.voip.united-telecom.be) Locked, Waiting on external entities 2018-12-08 12:35:31.672002 [NOTICE] switch_core_session.c:1683 Session 76 (sofia/default/32490112449@sip.voip.united-telecom.be) Ended 2018-12-08 12:35:31.672002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@sip.voip.united-telecom.be [CS_DESTROY] 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_DESTROY (Cur 0 Tot 76) 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY 2018-12-08 12:35:31.672002 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA DESTROY 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@sip.voip.united-telecom.be Standard DESTROY 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY going to sleep Type control-D or /exit or /quit or /bye to exit. 2018-12-08 12:41:05.132024 [NOTICE] switch_channel.c:1104 New Channel sofia/default/32490112449@sip.voip.united-telecom.be [8d323a2f-f956-4906-bc4b-5c6bb0ff58b7] 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_NEW (Cur 1 Tot 77) 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:9873 sofia/default/32490112449@sip.voip.united-telecom.be receiving invite from 87.238.166.8:5060 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:9989 IP 87.238.166.8 Approved by acl "default[]". Access Granted. 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [received][100] 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=root 98860711 98860711 IN IP4 87.238.166.50 s=SpeakUp Gateway c=IN IP4 87.238.166.50 t=0 0 m=audio 19904 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=nortpproxy:yes 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/32490112449@sip.voip.united-telecom.be PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2018-12-08 12:41:05.132024 [DEBUG] switch_core_codec.c:111 sofia/default/32490112449@sip.voip.united-telecom.be Original read codec set to PCMA:8 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4767 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 recv payload to 101 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:7507 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_NEW -> CS_INIT 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:603 (sofia/default/32490112449@sip.voip.united-telecom.be) State NEW 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_INIT (Cur 1 Tot 77) 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT 2018-12-08 12:41:05.132024 [DEBUG] mod_sofia.c:90 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA INIT 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:40 sofia/default/32490112449@sip.voip.united-telecom.be Standard INIT 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:48 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_INIT -> CS_ROUTING 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT going to sleep 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_ROUTING (Cur 1 Tot 77) 2018-12-08 12:41:05.132024 [DEBUG] switch_channel.c:2249 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change DOWN -> RINGING 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING 2018-12-08 12:41:05.132024 [DEBUG] mod_sofia.c:143 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA ROUTING 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:236 sofia/default/32490112449@sip.voip.united-telecom.be Standard ROUTING 2018-12-08 12:41:05.132024 [INFO] mod_dialplan_xml.c:637 Processing 32490112449 <32490112449>->fwwwastpp in context default 2018-12-08 12:41:05.152020 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fe6840661d0 Connected. 2018-12-08 12:41:05.152020 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard') 2018-12-08 12:41:05.152020 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : fwwwastpp 2018-12-08 12:41:05.152020 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number : 32490112449 / 32490112449 2018-12-08 12:41:05.152020 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="fwwwastpp" LIMIT 1 2018-12-08 12:41:05.152020 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="fwwwastpp" limit 1 2018-12-08 12:41:05.152020 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound 2018-12-08 12:41:05.152020 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("87.238.166.8") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 - SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1)) AND "fwwwastpp" LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1 2018-12-08 12:41:05.152020 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call authentication fail..!!1 2018-12-08 12:41:05.152020 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Generated XML:
2018-12-08 12:41:05.152020 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fe6840661d0 released. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be parsing [default->fwwwastpp] continue=false Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Regex (PASS) [fwwwastpp] destination_number(fwwwastpp) =~ /fwwwastpp/ break=on-false Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action log(WARNING Accountcode is not authenticated!!) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_name=32490112449) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_number=32490112449) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(error_cdr=1) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(callstart=2018-12-08 11:41:05) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(account_id=0) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_direction=outbound) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(sip_ignore_remote_cause=true) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_processed=internal) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(effective_destination_number=fwwwastpp) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(last_bridge_hangup_cause=AUTHENTICATION_FAIL) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action hangup(AUTHENTICATION_FAIL) 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:286 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_ROUTING -> CS_EXECUTE 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING going to sleep 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_EXECUTE (Cur 1 Tot 77) 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE 2018-12-08 12:41:05.152020 [DEBUG] mod_sofia.c:198 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA EXECUTE 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:328 sofia/default/32490112449@sip.voip.united-telecom.be Standard EXECUTE EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be log(WARNING Accountcode is not authenticated!!) 2018-12-08 12:41:05.152020 [WARNING] mod_dptools.c:1742 Accountcode is not authenticated!! 2018-12-08 12:41:05.152020 [DEBUG] switch_core_session.c:2647 Application playback Requires media! pre_answering channel sofia/default/32490112449@sip.voip.united-telecom.be 2018-12-08 12:41:05.152020 [INFO] switch_core_session.c:2649 Sending early media 2018-12-08 12:41:05.152020 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/32490112449@sip.voip.united-telecom.be] 109.68.167.132 port 19718 -> 87.238.166.50 port 19904 codec: 8 ms: 20 2018-12-08 12:41:05.152020 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms 2018-12-08 12:41:05.152020 [DEBUG] switch_core_media.c:7179 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 2018-12-08 12:41:05.152020 [DEBUG] switch_core_media.c:7186 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf receive payload to 101 2018-12-08 12:41:05.152020 [DEBUG] switch_core_media.c:7209 sofia/default/32490112449@sip.voip.united-telecom.be Set rtp dtmf delay to 40 2018-12-08 12:41:05.152020 [DEBUG] mod_sofia.c:2364 Ring SDP: v=0 o=FreeSWITCH 1544249547 1544249548 IN IP4 109.68.167.132 s=FreeSWITCH c=IN IP4 109.68.167.132 t=0 0 m=audio 19718 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 2018-12-08 12:41:05.152020 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/32490112449@sip.voip.united-telecom.be! 2018-12-08 12:41:05.152020 [DEBUG] switch_channel.c:3474 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change RINGING -> EARLY EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav) 2018-12-08 12:41:05.152020 [DEBUG] switch_core_file.c:342 File /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav sample rate 16000 doesn't match requested rate 8000 2018-12-08 12:41:05.152020 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 2018-12-08 12:41:05.152020 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [early][183] 2018-12-08 12:41:05.212009 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed. 2018-12-08 12:41:06.672028 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_name=32490112449) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_name]=[32490112449] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_number=32490112449) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_number]=[32490112449] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(error_cdr=1) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [error_cdr]=[1] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(callstart=2018-12-08 11:41:05) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [callstart]=[2018-12-08 11:41:05] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(account_id=0) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [account_id]=[0] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_direction=outbound) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_direction]=[outbound] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(sip_ignore_remote_cause=true) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [sip_ignore_remote_cause]=[true] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_processed=internal) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_processed]=[internal] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(effective_destination_number=fwwwastpp) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [effective_destination_number]=[fwwwastpp] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(last_bridge_hangup_cause=AUTHENTICATION_FAIL) 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [last_bridge_hangup_cause]=[AUTHENTICATION_FAIL] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be hangup(AUTHENTICATION_FAIL) 2018-12-08 12:41:06.672028 [NOTICE] mod_dptools.c:1286 Hangup sofia/default/32490112449@sip.voip.united-telecom.be [CS_EXECUTE] [NORMAL_CLEARING] 2018-12-08 12:41:06.672028 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@sip.voip.united-telecom.be skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE going to sleep 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_HANGUP (Cur 1 Tot 77) 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change EARLY -> HANGUP 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP 2018-12-08 12:41:06.672028 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@sip.voip.united-telecom.be hanging up, cause: NORMAL_CLEARING 2018-12-08 12:41:06.672028 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@sip.voip.united-telecom.be Standard HANGUP, cause: NORMAL_CLEARING 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP going to sleep 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_HANGUP -> CS_REPORTING 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_REPORTING (Cur 1 Tot 77) 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING 2018-12-08 12:41:06.672028 [INFO] mod_json_cdr.c:271 Process [8d323a2f-f956-4906-bc4b-5c6bb0ff58b7.cdr.json] 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@sip.voip.united-telecom.be Standard REPORTING, cause: NORMAL_CLEARING 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING going to sleep 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_REPORTING -> CS_DESTROY 2018-12-08 12:41:06.672028 [DEBUG] switch_core_session.c:1665 Session 77 (sofia/default/32490112449@sip.voip.united-telecom.be) Locked, Waiting on external entities 2018-12-08 12:41:06.672028 [NOTICE] switch_core_session.c:1683 Session 77 (sofia/default/32490112449@sip.voip.united-telecom.be) Ended 2018-12-08 12:41:06.672028 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@sip.voip.united-telecom.be [CS_DESTROY] 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_DESTROY (Cur 0 Tot 77) 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY 2018-12-08 12:41:06.672028 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA DESTROY 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@sip.voip.united-telecom.be Standard DESTROY 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY going to sleep freeswitch@astpp> freeswitch@astpp> Type control-D or /exit or /quit or /bye to exit. Type control-D or /exit or /quit or /bye to exit. freeswitch@astpp> show registrationsfreeswitch@astpp> freeswitch@astpp> freeswitch@astpp> freeswitch@astpp> /exit root@astpp:/home/zakariya# fs_cli .=======================================================. | _____ ____ ____ _ ___ | | | ___/ ___| / ___| | |_ _| | | | |_ \___ \ | | | | | | | | | _| ___) | | |___| |___ | | | | |_| |____/ \____|_____|___| | | | .=======================================================. | Anthony Minessale II, Ken Rice, | | Michael Jerris, Travis Cross | | FreeSWITCH (http://www.freeswitch.org) | | Paypal Donations Appreciated: paypal@freeswitch.org | | Brought to you by ClueCon http://www.cluecon.com/ | .=======================================================. .=======================================================================================================. | ____ _ ____ | | / ___| |_ _ ___ / ___|___ _ __ | | | | | | | | |/ _ \ | / _ \| '_ \ | | | |___| | |_| | __/ |__| (_) | | | | | | \____|_|\__,_|\___|\____\___/|_| |_| | | | | _____ _ _ ____ __ | | |_ _|__| | ___ _ __ | |__ ___ _ __ _ _ / ___|___ _ __ / _| ___ _ __ ___ _ __ ___ ___ | | | |/ _ \ |/ _ \ '_ \| '_ \ / _ \| '_ \| | | | | | / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \ | | | | __/ | __/ |_) | | | | (_) | | | | |_| | | |__| (_) | | | | _| __/ | | __/ | | | (_| __/ | | |_|\___|_|\___| .__/|_| |_|\___/|_| |_|\__, | \____\___/|_| |_|_| \___|_| \___|_| |_|\___\___| | | |_| |___/ | | _____ _ _ | | | ____|_ _____ _ __ _ _ / \ _ _ __ _ _ _ ___| |_ | | | _| \ \ / / _ \ '__| | | | / _ \| | | |/ _` | | | / __| __| | | | |___ \ V / __/ | | |_| | / ___ \ |_| | (_| | |_| \__ \ |_ | | |_____| \_/ \___|_| \__, | /_/ \_\__,_|\__, |\__,_|___/\__| | | |___/ |___/ | | ____ _ ____ | | __ ____ ____ __ / ___| |_ _ ___ / ___|___ _ __ ___ ___ _ __ ___ | | \ \ /\ / /\ \ /\ / /\ \ /\ / / | | | | | | |/ _ \ | / _ \| '_ \ / __/ _ \| '_ ` _ \ | | \ V V / \ V V / \ V V / _ | |___| | |_| | __/ |__| (_) | | | | _ | (_| (_) | | | | | | | | \_/\_/ \_/\_/ \_/\_/ (_) \____|_|\__,_|\___|\____\___/|_| |_| (_) \___\___/|_| |_| |_| | | | .=======================================================================================================. Type /help to see a list of commands +OK log level [7] 2018-12-08 12:47:13.632025 [NOTICE] switch_channel.c:1104 New Channel sofia/default/32490112449@sip.voip.united-telecom.be [0f9c6130-675c-49f6-a7c9-fb7491b5482f] 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_NEW (Cur 1 Tot 78) 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:9873 sofia/default/32490112449@sip.voip.united-telecom.be receiving invite from 87.238.166.8:5060 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:9989 IP 87.238.166.8 Approved by acl "default[]". Access Granted. 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [received][100] 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=root 2040389285 2040389285 IN IP4 87.238.166.51 s=SpeakUp Gateway c=IN IP4 87.238.166.51 t=0 0 m=audio 10548 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=nortpproxy:yes 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/32490112449@sip.voip.united-telecom.be PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2018-12-08 12:47:13.632025 [DEBUG] switch_core_codec.c:111 sofia/default/32490112449@sip.voip.united-telecom.be Original read codec set to PCMA:8 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4767 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 recv payload to 101 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:7507 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_NEW -> CS_INIT 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:603 (sofia/default/32490112449@sip.voip.united-telecom.be) State NEW 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_INIT (Cur 1 Tot 78) 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT 2018-12-08 12:47:13.632025 [DEBUG] mod_sofia.c:90 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA INIT 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:40 sofia/default/32490112449@sip.voip.united-telecom.be Standard INIT 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:48 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_INIT -> CS_ROUTING 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT going to sleep 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_ROUTING (Cur 1 Tot 78) 2018-12-08 12:47:13.632025 [DEBUG] switch_channel.c:2249 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change DOWN -> RINGING 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING 2018-12-08 12:47:13.632025 [DEBUG] mod_sofia.c:143 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA ROUTING 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:236 sofia/default/32490112449@sip.voip.united-telecom.be Standard ROUTING 2018-12-08 12:47:13.632025 [INFO] mod_dialplan_xml.c:637 Processing 32490112449 <32490112449>->fwwwastpp in context default 2018-12-08 12:47:13.632025 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fe684065e90 Connected. 2018-12-08 12:47:13.632025 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard') 2018-12-08 12:47:13.632025 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : fwwwastpp 2018-12-08 12:47:13.632025 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number : 32490112449 / 32490112449 2018-12-08 12:47:13.632025 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="fwwwastpp" LIMIT 1 2018-12-08 12:47:13.632025 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="fwwwastpp" limit 1 2018-12-08 12:47:13.632025 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound 2018-12-08 12:47:13.632025 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("87.238.166.8") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 - SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1)) AND "fwwwastpp" LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1 2018-12-08 12:47:13.632025 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call authentication fail..!!1 2018-12-08 12:47:13.632025 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Generated XML:
2018-12-08 12:47:13.632025 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fe684065e90 released. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be parsing [default->fwwwastpp] continue=false Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Regex (PASS) [fwwwastpp] destination_number(fwwwastpp) =~ /fwwwastpp/ break=on-false Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action log(WARNING Accountcode is not authenticated!!) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_name=32490112449) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_number=32490112449) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(error_cdr=1) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(callstart=2018-12-08 11:47:13) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(account_id=0) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_direction=outbound) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(sip_ignore_remote_cause=true) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_processed=internal) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(effective_destination_number=fwwwastpp) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(last_bridge_hangup_cause=AUTHENTICATION_FAIL) Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action hangup(AUTHENTICATION_FAIL) 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:286 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_ROUTING -> CS_EXECUTE 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING going to sleep 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_EXECUTE (Cur 1 Tot 78) 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE 2018-12-08 12:47:13.632025 [DEBUG] mod_sofia.c:198 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA EXECUTE 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:328 sofia/default/32490112449@sip.voip.united-telecom.be Standard EXECUTE EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be log(WARNING Accountcode is not authenticated!!) 2018-12-08 12:47:13.632025 [WARNING] mod_dptools.c:1742 Accountcode is not authenticated!! 2018-12-08 12:47:13.632025 [DEBUG] switch_core_session.c:2647 Application playback Requires media! pre_answering channel sofia/default/32490112449@sip.voip.united-telecom.be 2018-12-08 12:47:13.632025 [INFO] switch_core_session.c:2649 Sending early media 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/32490112449@sip.voip.united-telecom.be] 109.68.167.132 port 18056 -> 87.238.166.51 port 10548 codec: 8 ms: 20 2018-12-08 12:47:13.632025 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:7179 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:7186 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf receive payload to 101 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:7209 sofia/default/32490112449@sip.voip.united-telecom.be Set rtp dtmf delay to 40 2018-12-08 12:47:13.632025 [DEBUG] mod_sofia.c:2364 Ring SDP: v=0 o=FreeSWITCH 1544251577 1544251578 IN IP4 109.68.167.132 s=FreeSWITCH c=IN IP4 109.68.167.132 t=0 0 m=audio 18056 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 2018-12-08 12:47:13.632025 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/32490112449@sip.voip.united-telecom.be! 2018-12-08 12:47:13.632025 [DEBUG] switch_channel.c:3474 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change RINGING -> EARLY EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav) 2018-12-08 12:47:13.632025 [DEBUG] switch_core_file.c:342 File /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav sample rate 16000 doesn't match requested rate 8000 2018-12-08 12:47:13.632025 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [early][183] 2018-12-08 12:47:13.692020 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed. 2018-12-08 12:47:15.152018 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_name=32490112449) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_name]=[32490112449] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_number=32490112449) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_number]=[32490112449] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(error_cdr=1) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [error_cdr]=[1] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(callstart=2018-12-08 11:47:13) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [callstart]=[2018-12-08 11:47:13] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(account_id=0) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [account_id]=[0] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_direction=outbound) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_direction]=[outbound] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(sip_ignore_remote_cause=true) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [sip_ignore_remote_cause]=[true] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_processed=internal) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_processed]=[internal] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(effective_destination_number=fwwwastpp) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [effective_destination_number]=[fwwwastpp] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(last_bridge_hangup_cause=AUTHENTICATION_FAIL) 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [last_bridge_hangup_cause]=[AUTHENTICATION_FAIL] EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be hangup(AUTHENTICATION_FAIL) 2018-12-08 12:47:15.152018 [NOTICE] mod_dptools.c:1286 Hangup sofia/default/32490112449@sip.voip.united-telecom.be [CS_EXECUTE] [NORMAL_CLEARING] 2018-12-08 12:47:15.152018 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@sip.voip.united-telecom.be skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE going to sleep 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_HANGUP (Cur 1 Tot 78) 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change EARLY -> HANGUP 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP 2018-12-08 12:47:15.152018 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@sip.voip.united-telecom.be hanging up, cause: NORMAL_CLEARING 2018-12-08 12:47:15.152018 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@sip.voip.united-telecom.be Standard HANGUP, cause: NORMAL_CLEARING 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP going to sleep 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_HANGUP -> CS_REPORTING 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_REPORTING (Cur 1 Tot 78) 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING 2018-12-08 12:47:15.152018 [INFO] mod_json_cdr.c:271 Process [0f9c6130-675c-49f6-a7c9-fb7491b5482f.cdr.json] 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@sip.voip.united-telecom.be Standard REPORTING, cause: NORMAL_CLEARING 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING going to sleep 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_REPORTING -> CS_DESTROY 2018-12-08 12:47:15.172004 [DEBUG] switch_core_session.c:1665 Session 78 (sofia/default/32490112449@sip.voip.united-telecom.be) Locked, Waiting on external entities 2018-12-08 12:47:15.172004 [NOTICE] switch_core_session.c:1683 Session 78 (sofia/default/32490112449@sip.voip.united-telecom.be) Ended 2018-12-08 12:47:15.172004 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@sip.voip.united-telecom.be [CS_DESTROY] 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_DESTROY (Cur 0 Tot 78) 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY 2018-12-08 12:47:15.172004 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA DESTROY 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@sip.voip.united-telecom.be Standard DESTROY 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY going to sleep