long sms fails (nuran LC) - echo sms

2018-02-22 06:51:11.421577 [DEBUG] mod_smpp_gateway.c:271 New SMS received from[639360101902] to[919] message[]
2018-02-22 06:51:11.421577 [DEBUG] mod_smpp_gateway.c:365 Inbound SMS packet event: 
Event-Name: MESSAGE
Core-UUID: 72ba9b46-bcfc-487e-8a5c-b2a0cb680bf1
FreeSWITCH-Hostname: NTQ0130440
FreeSWITCH-Switchname: NTQ0130440
FreeSWITCH-IPv4: 192.168.1.201
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2018-02-22%2006%3A51%3A11
Event-Date-GMT: Thu,%2022%20Feb%202018%2006%3A51%3A11%20GMT
Event-Date-Timestamp: 1519282271421577
Event-Calling-File: mod_smpp_message.c
Event-Calling-Function: mod_smpp_message_decode
Event-Calling-Line-Number: 126
Event-Sequence: 573
endpoint: mod_smpp
sequence_number: 1140595229
command_status: 0
command_id: 5
smpp_gateway: openbsc
proto: smpp
source_addr_ton: 3
source_addr_npi: 1
from_user: 639360101902
dest_addr_ton: 1
dest_addr_npi: 1
to_user: 919
data_coding: 1
profile: default
Content-Length: 1



2018-02-22 06:51:11.421577 [INFO] mod_sms.c:368 Processing text message 639360101902->919 in context default
Chatplan: 919 parsing [default->setup] continue=true
Chatplan: nobody Absolute Condition [setup]
Chatplan: nobody Action python(VBTS_Parse_SMS) INLINE
2018-02-22 06:51:11.421577 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Parse_SMS
2018-02-22 06:51:11.421577 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Parsing:
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_text=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_tp_protocol_id=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_rp_message_reference=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_rp_dest_address=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_tp_dest_address_type=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_tp_message_type=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_rp_message_type=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_tp_dest_address=919
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_rp_originator_address=639360101902
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_rp_originator_address_type=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_tp_user_data=
2018-02-22 06:51:11.421577 [INFO] switch_cpp.cpp:1365 Setting vbts_tp_data_coding_scheme=
2018-02-22 06:51:11.421577 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Action python(VBTS_Get_IMSI_From_Username ${from_user}) INLINE
2018-02-22 06:51:11.441575 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Get_IMSI_From_Username
2018-02-22 06:51:11.441575 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.441575 [INFO] switch_cpp.cpp:1365 Returned Chat: IMSI515026000001987
2018-02-22 06:51:11.441575 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Action set(from_imsi=${_openbts_ret}) INLINE
Chatplan: nobody Action python(VBTS_Get_CallerID ${from_imsi}) INLINE
2018-02-22 06:51:11.441575 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Get_CallerID
2018-02-22 06:51:11.441575 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.441575 [INFO] switch_cpp.cpp:1365 Returned Chat: 639360101902
2018-02-22 06:51:11.441575 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Action set(vbts_callerid=${_openbts_ret}) INLINE
Chatplan: nobody Action python(VBTS_Canonicalize_Phone_Number ${vbts_tp_dest_address}) INLINE
2018-02-22 06:51:11.441575 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Canonicalize_Phone_Number
2018-02-22 06:51:11.441575 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.461583 [INFO] switch_cpp.cpp:1365 Returned Chat: 63919
2018-02-22 06:51:11.461583 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Action set(vbts_canonical_tp_dest_address=${_openbts_ret}) INLINE
Chatplan: nobody Action python(VBTS_Get_IMSI_From_Number ${vbts_canonical_tp_dest_address}) INLINE
2018-02-22 06:51:11.461583 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Get_IMSI_From_Number
2018-02-22 06:51:11.461583 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.461583 [INFO] switch_cpp.cpp:1365 Returned Chat: 
2018-02-22 06:51:11.461583 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Action set(vbts_destid=${_openbts_ret}) INLINE
Chatplan: nobody Action python(VBTS_Get_Account_Balance ${from_imsi}) INLINE
2018-02-22 06:51:11.461583 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Get_Account_Balance
2018-02-22 06:51:11.461583 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.461583 [INFO] switch_cpp.cpp:1365 Returned Chat: 79217000
2018-02-22 06:51:11.461583 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Action set(vbts_account_balance=${_openbts_ret}) INLINE
Chatplan: nobody Action set(service_type=free_sms) INLINE
Chatplan: nobody Action set(call_tariff=${python(VBTS_Get_Service_Tariff ${service_type}|sms|)}) INLINE
2018-02-22 06:51:11.461583 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Get_Service_Tariff
2018-02-22 06:51:11.481575 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.481575 [INFO] switch_cpp.cpp:1365 Returned FSAPI: 0
2018-02-22 06:51:11.481575 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Action python(endaga_camped ${vbts_canonical_tp_dest_address}) INLINE
2018-02-22 06:51:11.481575 [NOTICE] mod_python.c:212 Invoking py module: endaga_camped
2018-02-22 06:51:11.481575 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.481575 [INFO] switch_cpp.cpp:1365 Returned Chat: FALSE
2018-02-22 06:51:11.481575 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Action set(endaga_islocal=${_openbts_ret}) INLINE
Chatplan: nobody Action set(final_delivery=true) 
Chatplan: 919 parsing [default->registration_endaga] continue=false
Chatplan: nobody Regex (FAIL) [registration_endaga] ${vbts_tp_dest_address}(919) =~ /^101$/ break=on-false
Chatplan: 919 parsing [default->unprovisioned] continue=false
2018-02-22 06:51:11.481575 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Get_Auth_From_IMSI
2018-02-22 06:51:11.481575 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.481575 [INFO] switch_cpp.cpp:1365 Returned FSAPI: True
2018-02-22 06:51:11.481575 [DEBUG] mod_python.c:286 Finished calling python script 
Chatplan: nobody Regex (FAIL) [unprovisioned] ${python(VBTS_Get_Auth_From_IMSI ${from_imsi})}(True) =~ /False/ break=on-false
Chatplan: 919 parsing [default->credit-transfer] continue=false
Chatplan: nobody Regex (FAIL) [credit-transfer] ${vbts_tp_dest_address}(919) =~ /^102$/ break=on-false
Chatplan: 919 parsing [default->billing-check] continue=false
Chatplan: nobody Regex (FAIL) [billing-check] ${vbts_tp_dest_address}(919) =~ /^103$/ break=on-false
Chatplan: 919 parsing [default->number-check] continue=false
Chatplan: nobody Regex (FAIL) [number-check] ${vbts_tp_dest_address}(919) =~ /^104$/ break=on-false
Chatplan: 919 parsing [default->echo] continue=false
Chatplan: nobody Regex (PASS) [echo] ${vbts_tp_dest_address}(919) =~ /^919$/ break=on-false
Chatplan: nobody Action python(VBTS_Send_SMS ${vbts_callerid}|${vbts_tp_dest_address}|${vbts_text}) 
Chatplan: nobody Action system(curl --data "from_number=${vbts_callerid}&from_name=${from_imsi}&service_type=${service_type}&destination=${vbts_tp_dest_address}" http://127.0.0.1/smscdr) 
2018-02-22 06:51:11.481575 [NOTICE] mod_python.c:212 Invoking py module: VBTS_Send_SMS
2018-02-22 06:51:11.501574 [DEBUG] mod_python.c:283 Call python script 
2018-02-22 06:51:11.501574 [INFO] switch_cpp.cpp:1365 Args: ['639360101902', '919', '\x05']
2018-02-22 06:51:11.501574 [DEBUG] mod_python.c:286 Finished calling python script 
2018-02-22 06:51:11.501574 [NOTICE] mod_sms.c:503 Executing command: curl --data "from_number=639360101902&from_name=IMSI515026000001987&service_type=free_sms&destination=919" http://127.0.0.1/smscdr