It took the need to use a third-party gateway, the choice fell on “Twilio”. In terms of sending no problems, SMS go without problems. But the problem is that loans do not change. The callback URL is also specified, but still the cretida remain unchanged.
Piece of log file:
192.168.100.216 smsd.uz 2019-04-02 14:23:16 PID5ca37053e2196 tr7 L2 sendsms # start uid:17 sender_id:[SMSd] smsc:[]https://api.twilio.com/2010-04-01/Accounts/ACf62145b6e01cf34edab929d05cfe6b61/SMS/Messages.json ] callback:[https://abcd.aa/plugin/gateway/twilio/callback.php
Please, help me.
Regards,
anton
April 2, 2019, 5:08pm
2
First thing first, to debug always use Log level 3
And then make sure callback url is reachable from the internet (by twilio server)
Log output after format - 3
Callback address is available
192.168.100.225 192.168.100.218 2019-04-02 18:09:32 PID5ca3a55cb924f - L2 kannel__call # start load:/var/www/html/plugin/gateway/kannel/dlr.php@tr7 ] msglen:10 message:[asdfasdfas]@tr7 ] sms_to:[+998935343698] sms_msg:[asdfasdfas] uid:[17] gpid:[0] sms_type:[text] unicode:[0] queue_code:[b3c5c8f03e1bbf614a77b4326940c1b8] smsc:[]@tr7 ] sms_to:[+998935343698] sms_msg:[asdfasdfas] uid:[17] gpid:[0] sms_type:[text] unicode:[0] queue_code:[b3c5c8f03e1bbf614a77b4326940c1b8] smsc:[twilio]@tr7 len:15https://api.twilio.com/2010-04-01/Accounts/ACf62145b6e01cf34edab929d05cfe6b61/SMS/Messages.json ] callback:[https://abcd.aa/plugin/gateway/twilio/callback.php kannel__call # start load:/var/www/html/plugin/gateway/kannel/dlr.phpkannel__dlr # remote_addr:192.168.100.225 remote_host:192.168.100.218 type:[8] smslog_id:[55433] uid:[8] smsc:[kannel ]kannel__call # end load dlr
I can’t understand what kannel is doing here.
Regards,
anton
April 2, 2019, 6:26pm
5
do you have kannel installed ? looks like kannel pushing DLR to your playSMS
as for twilio, can you check in database, table playsms_gatewayTwilio, is there anything in there ?
anton
Yes, but kannel is installed on another host.
Regards,
anton
April 2, 2019, 6:45pm
8
hmmm not sure why, yet, that part of log should meant that playSMS saved the message id to table playsms_gatewayTwilio
Anton, please tell me how to be in this situation?
Respectfully,
anton
April 2, 2019, 6:49pm
10
you can try to test the table by inserting somethingREPAIR TABLE just incase
anton
added test string
balance values haven’t changed yet
anton
April 2, 2019, 7:29pm
13
can you try again sending 1 SMS, and see the db table
fyi, the code that saves delivery info to db is in twilio/fn.php line 64~67
anton
Before sending SMS:
After senind two SMS:@tr7 ] msglen:10 message:[asdfasdfas]
2019-04-02 20:04:20 PID5ca3c0442a645 - L2 sendsmsd # start processing queue_code:8f91eb1beb3558212cabe1fb2bfa1867 chunk:0 queue_count:1 sms_count:1 scheduled:2019-04-02 20:04:18 uid:17 gpid:0 sender_id:SMSd
2019-04-02 20:04:20 PID5ca3c0442a645 - L2 sendsmsd # sending queue_code:8f91eb1beb3558212cabe1fb2bfa1867 smslog_id:56425 to:+998935217698 sms_count:1 counter:1
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 sendsms_intercept # msgtemplate modified sms_sender:[SMSd] sms_footer:[@tr7 ] sms_to:[+998935217698] sms_msg:[asdfasdfas] uid:[17] gpid:[0] sms_type:[text] unicode:[0] queue_code:[8f91eb1beb3558212cabe1fb2bfa1867] smsc:
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 outgoing_hook_sendsms_intercept # found SMSCs:[twilio]
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 outgoing_hook_sendsms_intercept # using prefix based smsc smsc:[twilio] uid:17 parent_uid:0 from:SMSd to:+998935217698
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 sendsms_intercept # outgoing modified sms_sender:[SMSd] sms_footer:[@tr7 ] sms_to:[+998935217698] sms_msg:[asdfasdfas] uid:[17] gpid:[0] sms_type:[text] unicode:[0] queue_code:[8f91eb1beb3558212cabe1fb2bfa1867] smsc:[twilio]
2019-04-02 20:04:20 PID5ca3c0442a645 - L2 sendsms_process # start
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 simplerate_hook_rate_getbyprefix # rate not found to:+998935217698 default_rate:0.01
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 simplerate_hook_rate_getcharges # uid:17 u:tr7 len:15 unicode:0 to:+998935217698 enable_credit_unicode:0 count:1 rate:0.01 charge:0.01
2019-04-02 20:04:20 PID5ca3c0442a645 - L2 simplerate_hook_rate_cansend # allowed user uid:17 sms_to:+998935217698 adhoc_credit:0.1 count:1 rate:0.01 charge:0.01 adhoc_balance:0.09
2019-04-02 20:04:20 PID5ca3c0442a645 - L2 sendsms # saving smslog_id:56425 u:17 parent_uid:0 g:0 gw:twilio smsc:twilio s:SMSd d:+998935217698 type:text unicode:0 status:0
2019-04-02 20:04:20 PID5ca3c0442a645 - L2 sendsms_process # saved smslog_id:56425 id:54003
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 sendsms # final smslog_id:56425 gw:twilio smsc:twilio message:asdfasdfas @tr7 len:15
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 twilio_hook_sendsms # enter smsc:twilio smslog_id:56425 uid:17 to:+998935217698
2019-04-02 20:04:20 PID5ca3c0442a645 - L3 twilio_hook_sendsms # sendsms start
2019-04-02 20:04:21 PID5ca3c0442a645 - L2 twilio_hook_sendsms # sent smslog_id:56425 message_id:SM9bc039498911472b8e45f225eea98dee status:queued error:queued|| smsc:[twilio]
2019-04-02 20:04:21 PID5ca3c0442a645 - L3 dlr # isdlrd:1 smslog_id:56425 p_status:0 uid:17
2019-04-02 20:04:21 PID5ca3c0442a645 - L3 twilio_hook_sendsms # sendsms end
2019-04-02 20:04:21 PID5ca3c0442a645 - L2 simplerate_hook_rate_deduct # enter smslog_id:56425
2019-04-02 20:04:21 PID5ca3c0442a645 - L3 simplerate_hook_rate_getbyprefix # rate not found to:+998935217698 default_rate:0.01
2019-04-02 20:04:21 PID5ca3c0442a645 - L3 simplerate_hook_rate_getcharges # uid:17 u:tr7 len:15 unicode:0 to:+998935217698 enable_credit_unicode:0 count:1 rate:0.01 charge:0.01
2019-04-02 20:04:21 PID5ca3c0442a645 - L2 simplebilling_hook_billing_post # saving smslog_id:56425 rate:0.01 count:1 charge:0.01
2019-04-02 20:04:21 PID5ca3c0442a645 - L2 simplebilling_hook_billing_post # saved smslog_id:56425 id:53821
2019-04-02 20:04:21 PID5ca3c0442a645 - L3 simplerate_hook_rate_deduct # deduct successful uid:17 parent_uid:0 smslog_id:56425
2019-04-02 20:04:21 PID5ca3c0442a645 - L2 sendsms_process # end
2019-04-02 20:04:21 PID5ca3c0442a645 - L2 sendsmsd # result queue_code:8f91eb1beb3558212cabe1fb2bfa1867 to:+998935217698 flag:1 smslog_id:56425
2019-04-02 20:04:21 PID5ca3c0442a645 - L2 sendsmsd # finish processing queue_code:8f91eb1beb3558212cabe1fb2bfa1867 uid:17 sender_id:SMSd queue_count:1 sms_count:1
2019-04-02 20:04:22 PID5ca3b79a7c599 - L3 dlrd # id:104815 smslog_id:56425 p_status:0 uid:17
anton
April 3, 2019, 1:35am
15
So the problem is it was failed to insert to database, but why I cant see it yet
Anton
Hi, Anton! What is the way out of the situation suggest?
Regards,
anton
April 3, 2019, 7:39am
17
I dont know yet, I have to test it. In the mean time you can ask around other forum why an sql insert can fail, etc
Anton
anton
April 3, 2019, 12:28pm
18
edit twilio/fn.php and insert below log code in line 67 :
_log("db_query:[" . $db_query . "]", 2, "twilio_hook_sendsms");
see this image:
send an sms and watch the log, see the db_query and try the insert SQL directly in mysql
anton
To start added code:
update the code
sent SMS and log outputNote: Credits do not change
192.168.100.216 smsd.uz 2019-04-03 13:30:49 PID5ca4b58915d46 tr7 L2 logout # re-login as u:admin uid: status:2 sid:u5c5mre67p2tib2hk1glulbje2 ip:192.168.100.216@tr7 ] msglen:7 message:[asdfasd]
2019-04-03 13:31:08 PID5ca4b59c358e8 - L2 sendsmsd # start processing queue_code:5043e462f9bd6d0037be76ba564c79ec chunk:0 queue_count:1 sms_count:1 scheduled:2019-04-03 13:31:07 uid:17 gpid:0 sender_id:SMSd
2019-04-03 13:31:08 PID5ca4b59c358e8 - L2 sendsmsd # sending queue_code:5043e462f9bd6d0037be76ba564c79ec smslog_id:56687 to:+998935217698 sms_count:1 counter:1
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 sendsms_intercept # msgtemplate modified sms_sender:[SMSd] sms_footer:[@tr7 ] sms_to:[+998935217698] sms_msg:[asdfasd] uid:[17] gpid:[0] sms_type:[text] unicode:[0] queue_code:[5043e462f9bd6d0037be76ba564c79ec] smsc:
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 outgoing_hook_sendsms_intercept # found SMSCs:[twilio]
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 outgoing_hook_sendsms_intercept # using prefix based smsc smsc:[twilio] uid:17 parent_uid:0 from:SMSd to:+998935217698
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 sendsms_intercept # outgoing modified sms_sender:[SMSd] sms_footer:[@tr7 ] sms_to:[+998935217698] sms_msg:[asdfasd] uid:[17] gpid:[0] sms_type:[text] unicode:[0] queue_code:[5043e462f9bd6d0037be76ba564c79ec] smsc:[twilio]
2019-04-03 13:31:08 PID5ca4b59c358e8 - L2 sendsms_process # start
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 simplerate_hook_rate_getbyprefix # rate not found to:+998935217698 default_rate:0.01
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 simplerate_hook_rate_getcharges # uid:17 u:tr7 len:12 unicode:0 to:+998935217698 enable_credit_unicode:0 count:1 rate:0.01 charge:0.01
2019-04-03 13:31:08 PID5ca4b59c358e8 - L2 simplerate_hook_rate_cansend # allowed user uid:17 sms_to:+998935217698 adhoc_credit:0.1 count:1 rate:0.01 charge:0.01 adhoc_balance:0.09
2019-04-03 13:31:08 PID5ca4b59c358e8 - L2 sendsms # saving smslog_id:56687 u:17 parent_uid:0 g:0 gw:twilio smsc:twilio s:SMSd d:+998935217698 type:text unicode:0 status:0
2019-04-03 13:31:08 PID5ca4b59c358e8 - L2 sendsms_process # saved smslog_id:56687 id:54265
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 sendsms # final smslog_id:56687 gw:twilio smsc:twilio message:asdfasd @tr7 len:12
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 twilio_hook_sendsms # enter smsc:twilio smslog_id:56687 uid:17 to:+998935217698
2019-04-03 13:31:08 PID5ca4b59c358e8 - L3 twilio_hook_sendsms # sendsms start
2019-04-03 13:31:09 PID5ca4b59c358e8 - L2 twilio_hook_sendsms # sent smslog_id:56687 message_id:SM0f6023c1deac423a8356e9d6127b8f94 status:queued error:queued|| smsc:[twilio]
2019-04-03 13:31:09 PID5ca4b59c358e8 - L2 twilio_hook_sendsms # db_query:[ INSERT INTO playsms_gatewayTwilio (local_smslog_id,remote_smslog_id,status,error_text) VALUES (‘56687’,‘SM0f6023c1deac423a8356e9d6127b8f94’,‘queued’,‘queued||’)]
2019-04-03 13:31:09 PID5ca4b59c358e8 - L3 dlr # isdlrd:1 smslog_id:56687 p_status:0 uid:17
2019-04-03 13:31:09 PID5ca4b59c358e8 - L3 twilio_hook_sendsms # sendsms end
2019-04-03 13:31:09 PID5ca4b59c358e8 - L2 simplerate_hook_rate_deduct # enter smslog_id:56687
2019-04-03 13:31:09 PID5ca4b59c358e8 - L3 simplerate_hook_rate_getbyprefix # rate not found to:+998935217698 default_rate:0.01
2019-04-03 13:31:09 PID5ca4b59c358e8 - L3 simplerate_hook_rate_getcharges # uid:17 u:tr7 len:12 unicode:0 to:+998935217698 enable_credit_unicode:0 count:1 rate:0.01 charge:0.01
2019-04-03 13:31:09 PID5ca4b59c358e8 - L2 simplebilling_hook_billing_post # saving smslog_id:56687 rate:0.01 count:1 charge:0.01
2019-04-03 13:31:09 PID5ca4b59c358e8 - L2 simplebilling_hook_billing_post # saved smslog_id:56687 id:54083
2019-04-03 13:31:09 PID5ca4b59c358e8 - L3 simplerate_hook_rate_deduct # deduct successful uid:17 parent_uid:0 smslog_id:56687
2019-04-03 13:31:09 PID5ca4b59c358e8 - L2 sendsms_process # end
2019-04-03 13:31:09 PID5ca4b59c358e8 - L2 sendsmsd # result queue_code:5043e462f9bd6d0037be76ba564c79ec to:+998935217698 flag:1 smslog_id:56687
2019-04-03 13:31:09 PID5ca4b59c358e8 - L2 sendsmsd # finish processing queue_code:5043e462f9bd6d0037be76ba564c79ec uid:17 sender_id:SMSd queue_count:1 sms_count:1
2019-04-03 13:31:10 PID5ca4b4c894901 - L3 dlrd # id:105305 smslog_id:56687 p_status:0 uid:17
Send massage and log output
192.168.100.216 smsd.uz 2019-04-03 13:54:13 PID5ca4bb054ca79 - L2 twilio__callback # pushed@tr7 ] msglen:7 message:[asdfasd]
2019-04-03 14:08:56 PID5ca4be78d6aed - L2 sendsmsd # start processing queue_code:da0d40c974cc7a4f58ed6c6caddb68ef chunk:0 queue_count:1 sms_count:1 scheduled:2019-04-03 14:08:56 uid:17 gpid:0 sender_id:SMSd
2019-04-03 14:08:56 PID5ca4be78d6aed - L2 sendsmsd # sending queue_code:da0d40c974cc7a4f58ed6c6caddb68ef smslog_id:56692 to:+998935217698 sms_count:1 counter:1
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 sendsms_intercept # msgtemplate modified sms_sender:[SMSd] sms_footer:[@tr7 ] sms_to:[+998935217698] sms_msg:[asdfasd] uid:[17] gpid:[0] sms_type:[text] unicode:[0] queue_code:[da0d40c974cc7a4f58ed6c6caddb68ef] smsc:
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 outgoing_hook_sendsms_intercept # found SMSCs:[twilio]
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 outgoing_hook_sendsms_intercept # using prefix based smsc smsc:[twilio] uid:17 parent_uid:0 from:SMSd to:+998935217698
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 sendsms_intercept # outgoing modified sms_sender:[SMSd] sms_footer:[@tr7 ] sms_to:[+998935217698] sms_msg:[asdfasd] uid:[17] gpid:[0] sms_type:[text] unicode:[0] queue_code:[da0d40c974cc7a4f58ed6c6caddb68ef] smsc:[twilio]
2019-04-03 14:08:56 PID5ca4be78d6aed - L2 sendsms_process # start
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 simplerate_hook_rate_getbyprefix # rate not found to:+998935217698 default_rate:0.01
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 simplerate_hook_rate_getcharges # uid:17 u:tr7 len:12 unicode:0 to:+998935217698 enable_credit_unicode:0 count:1 rate:0.01 charge:0.01
2019-04-03 14:08:56 PID5ca4be78d6aed - L2 simplerate_hook_rate_cansend # allowed user uid:17 sms_to:+998935217698 adhoc_credit:0.1 count:1 rate:0.01 charge:0.01 adhoc_balance:0.09
2019-04-03 14:08:56 PID5ca4be78d6aed - L2 sendsms # saving smslog_id:56692 u:17 parent_uid:0 g:0 gw:twilio smsc:twilio s:SMSd d:+998935217698 type:text unicode:0 status:0
2019-04-03 14:08:56 PID5ca4be78d6aed - L2 sendsms_process # saved smslog_id:56692 id:54270
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 sendsms # final smslog_id:56692 gw:twilio smsc:twilio message:asdfasd @tr7 len:12
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 twilio_hook_sendsms # enter smsc:twilio smslog_id:56692 uid:17 to:+998935217698
2019-04-03 14:08:56 PID5ca4be78d6aed - L3 twilio_hook_sendsms # sendsms start
2019-04-03 14:08:58 PID5ca4be78d6aed - L2 twilio_hook_sendsms # sent smslog_id:56692 message_id:SM2beda4c820274a678346287f320734ae status:queued error:queued|| smsc:[twilio]
2019-04-03 14:08:58 PID5ca4be78d6aed - L2 twilio_hook_sendsms # db_query:[ INSERT INTO playsms_gatewayTwilio (local_smslog_id,remote_smslog_id,status,error_text) VALUES (‘56692’,‘SM2beda4c820274a678346287f320734ae’,‘queued’,‘queued||’)]
2019-04-03 14:08:58 PID5ca4be78d6aed - L3 dlr # isdlrd:1 smslog_id:56692 p_status:0 uid:17
2019-04-03 14:08:58 PID5ca4be78d6aed - L3 twilio_hook_sendsms # sendsms end
2019-04-03 14:08:58 PID5ca4be78d6aed - L2 simplerate_hook_rate_deduct # enter smslog_id:56692
2019-04-03 14:08:58 PID5ca4be78d6aed - L3 simplerate_hook_rate_getbyprefix # rate not found to:+998935217698 default_rate:0.01
2019-04-03 14:08:58 PID5ca4be78d6aed - L3 simplerate_hook_rate_getcharges # uid:17 u:tr7 len:12 unicode:0 to:+998935217698 enable_credit_unicode:0 count:1 rate:0.01 charge:0.01
2019-04-03 14:08:58 PID5ca4be78d6aed - L2 simplebilling_hook_billing_post # saving smslog_id:56692 rate:0.01 count:1 charge:0.01
2019-04-03 14:08:58 PID5ca4be78d6aed - L2 simplebilling_hook_billing_post # saved smslog_id:56692 id:54088
2019-04-03 14:08:58 PID5ca4be78d6aed - L3 simplerate_hook_rate_deduct # deduct successful uid:17 parent_uid:0 smslog_id:56692
2019-04-03 14:08:58 PID5ca4be78d6aed - L2 sendsms_process # end
2019-04-03 14:08:58 PID5ca4be78d6aed - L2 sendsmsd # result queue_code:da0d40c974cc7a4f58ed6c6caddb68ef to:+998935217698 flag:1 smslog_id:56692
2019-04-03 14:08:58 PID5ca4be78d6aed - L2 sendsmsd # finish processing queue_code:da0d40c974cc7a4f58ed6c6caddb68ef uid:17 sender_id:SMSd queue_count:1 sms_count:1
2019-04-03 14:08:59 PID5ca4b4c894901 - L3 dlrd # id:105314 smslog_id:56692 p_status:0 uid:17
it was 0.10 left 0.10
By the way, these dates began to appear intensively.
