PlaySMS and Smstools 3

Hello … good evening.I installed the new version 1.3 using with smstools 3, but is not leaving the playsms messages to smstools. It also does not appear in “My reports”.

Could help me?

Have you followed the fix in additional steps?

Anton

You refer to the SQL fix?

Hello. Anton already realized the steps to fix the playSMS 1.3, I am using SMS Server Tools 3 but did not succeed. Messages are not sent to the gateway.

I’ll spend the logs

playsms.log

    • 2015-09-17 19:29:28 PID55fb3ec23ef06 - L2 sendsmsd # finish processing queue_code:330dc70859295fe962595950f84208a8 uid:1 sender_id: queue_count:1 sms_count:1
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 sendsmsd # start processing queue_code:5a50a8436939a752ff7191b5d3357a09 chunk:0 queue_count:1 sms_count:1 scheduled:2015-09-17 21:39:50 uid:1 gpid:0 sender_id:
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 sendsmsd # sending queue_code:5a50a8436939a752ff7191b5d3357a09 smslog_id:3 to:554299155639 sms_count:1 counter:1
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 sendsms_process # start
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 simplerate__cansend # allowed user uid:1 sms_to:554299155639 credit:999.980 count:1 rate:0.010 charge:0.01 balance:999.97
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 sendsms # saving smslog_id:3 u:1 g:0 gw:smstools smsc:smstools s: d:554299155639 type:text unicode:0 status:0
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 sendsms_process # saved smslog_id:3 id:3
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 smstools_hook_sendsms # saved outfile:/var/spool/sms//out.20150918003951.0.1.3 smsc:[smstools]
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 simplerate__deduct # enter smslog_id:3
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 credit_hook_rate_setusercredit # saving uid:1 balance:999.97
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 credit_hook_rate_setusercredit # saved uid:1 balance:999.97
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 simplerate__deduct # user uid:1 parent_uid: smslog_id:3 msglen:26 count:1 rate:0.010 charge:0.01 credit:999.980 balance:999.97
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 simplebilling__post # saving smslog_id:3 rate:0.010 credit:999.980 count:1 charge:0.01
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 simplebilling__post # saved smslog_id:3 id:3
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 sendsms_process # end
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 sendsmsd # result queue_code:5a50a8436939a752ff7191b5d3357a09 to:554299155639 flag:1 smslog_id:3
    • 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 sendsmsd # finish processing queue_code:5a50a8436939a752ff7191b5d3357a09 uid:1 sender_id: queue_count:1 sms_count:1

audit.log

localhost 2015-09-17 21:39:40 PID55fb5d4c387f2 admin ip:::1 app:main inc:core_sendsms op:sendsms
localhost 2015-09-17 21:39:50 PID55fb5d56519dd admin ip:::1 app:main inc:core_sendsms op:sendsms_yes X-CSRF-Token:f71ef0a6f12f9443d34909275f416810 footerlen:0 maxchar:153 maxchar_unicode:67 hiddcount:459 hiddcount_unicode:201 chars:chars SMS:SMS return_url: p_num_text:554299155639 smstemplate: message:Hi u there, good morning!! txtcount:26 chars : 1 SMS sms_sender: sms_footer: sms_schedule:
localhost 2015-09-17 21:39:50 PID55fb5d56c9126 admin ip:::1 app:main inc:core_sendsms op:sendsms

smsd.log

2015-09-17 21:35:57,3, GSM1: Unexpected input: ^BOOT:33640594,0,0,0,77
2015-09-17 21:38:26,3, GSM1: Unexpected input: ^BOOT:33640594,0,0,0,77
2015-09-17 21:41:26,3, GSM1: Unexpected input: ^BOOT:33640594,0,0,0,77

smsd.conf

Configuração Global

devices = GSM1

Logs

stats = /var/log/smstools/smsd_stats/
logfile = /var/log/smstools/smsd.log

Filas

outgoing = /var/spool/sms/outgoing
checked = /var/spool/sms/checked
failed = /var/spool/sms/failed
incoming = /var/spool/sms/incoming
sent = /var/spool/sms/sent
delaytime = 2
errorsleeptime = 10
blocktime = 180
loglevel = 5
autosplit = 3
receive_before_send = no

[queues]
GSM1 = /var/spool/sms/GSM1

[GSM1]
init = AT+CPMS=“MT”,“MT”,"MT"
init2 = AT+CPMS=“ME”,“ME”,"ME"
device = /dev/MOD
incoming = yes
baudrate = 19200
report = yes
queues = GSM1

Looks like wrong config

- 2015-09-17 21:39:51 PID55fb5d56e90a5 - L2 smstools_hook_sendsms # saved outfile:/var/spool/sms//out.20150918003951.0.1.3 smsc:[smstools]

Go to web, edit your smstools smsc and fill queue directory with /var/spool/sms/GSM1

Anton

Ready. I made the change but not yet sent.

    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 sendsms_process # start
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 simplerate__cansend # allowed user uid:1 sms_to:554299155639 credit:999.950 count:1 rate:0.010 charge:0.01 balance:999.94
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 sendsms # saving smslog_id:7 u:1 g:0 gw:smstools smsc:smstools s: d:554299155639 type:text unicode:0 status:0
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 sendsms_process # saved smslog_id:7 id:7
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 smstools_hook_sendsms # saved outfile:/var/spool/sms/GSM1/out.20150918014607.0.1.7 smsc:[smstools]
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 simplerate__deduct # enter smslog_id:7
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 credit_hook_rate_setusercredit # saving uid:1 balance:999.94
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 credit_hook_rate_setusercredit # saved uid:1 balance:999.94
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 simplerate__deduct # user uid:1 parent_uid: smslog_id:7 msglen:5 count:1 rate:0.010 charge:0.01 credit:999.950 balance:999.94
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 simplebilling__post # saving smslog_id:7 rate:0.010 credit:999.950 count:1 charge:0.01
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 simplebilling__post # saved smslog_id:7 id:6
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 sendsms_process # end
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 sendsmsd # result queue_code:43e7d3479e3140def10ecc2fc5995d27 to:554299155639 flag:1 smslog_id:7
    • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 sendsmsd # finish processing queue_code:43e7d3479e3140def10ecc2fc5995d27 uid:1 sender_id: queue_count:1 sms_count:1

localhost 2015-09-17 22:46:06 PID55fb6cde10e57 admin ip:::1 app:main inc:core_sendsms op:sendsms_yes X-CSRF-Token:db7c641d6826e837410222ce9a82572e footerlen:0 maxchar:153 maxchar_unicode:67 hiddcount:459 hiddcount_unicode:201 chars:chars SMS:SMS return_url: p_num_text:554299155639 smstemplate: message:Teste txtcount:5 chars : 1 SMS sms_sender: sms_footer: sms_schedule:
localhost 2015-09-17 22:46:06 PID55fb6cde6dd62 admin ip:::1 app:main inc:core_sendsms op:sendsms
localhost 2015-09-17 22:46:10 PID55fb6ce2046ff admin ip:::1 app:main inc:feature_report route:user
localhost 2015-09-17 22:46:15 PID55fb6ce793ed2 admin ip:::1 app:main inc:feature_report route:user

this is logged in your log:

  • 2015-09-17 22:46:07 PID55fb6cdf65ae1 - L2 smstools_hook_sendsms # saved outfile:/var/spool/sms/GSM1/out.20150918014607.0.1.7 smsc:[smstools]

do you see file out.20150918014607.0.1.7 in your /var/spool/sms/GSM1 ?

anton

Hello Anton.
No, this is not aquivo in the /var/spool/sms/GSM1

A curiosity, if I want to add more modems GSMs how it would look on the web page to the SMSC configuration.

is the directory /var/spool/sms/GSM1 exists ?

anton

Yes, the directory exists

that doesn’t make any senses, playsmsd should be able to write the out file to that directory

was playsmsd run as root ?

anton

Yes Anton.

I’ll reinstall it again.

The owner of the directory /var/spool/sms is smsd.smsd with permissions for directories in 2777 and 2666 for files with the smstools 3.1.14-1.2 version

The playsmsd is running as root on Debian System 7.8 32b, Debian 8.0 had problems with smstools 3.1.15

Just to make sure you need /var/spool/sms/GSM1 folder as outgoing spool directory, all out files will be written there by playsmsd and smstools will pick them up for actual delivery, not just /var/spool/sms folder.

anton

Right. I will leave only in / var / spool / sms and reinstall the applications.
In sequence I’ll post here the result.

Thanks Anton.

Hello Anton.

So as I had commented that it would post so solve the problem.
In versions 1.1, 1.2 and 1.3 of playSMS and smstools in the versions 3.1.14 and 3.1.15 does not send the sms.

I am successfully sending only with version 1.0 playSMS and smstools version 3.1.14-1.2 debian system version 7.8 wheezy.

See the logs and settings

tail -f /var/log/smstools/smsd.log

09/22/2015 13: 32: 30.2, SMSD: SMSD mainprocess is awaiting the termination of all modem handlers. PID: 7298.
09/22/2015 13: 32: 30.2, GSM1: Modem handler 0 terminated. PID: 7299, was started 09/15/22 13:29:16.
09/22/2015 13: 32: 30.2, SMSD: SMSD mainprocess terminated. PID 7298, was started 09/15/22 13:29:16.
09/22/2015 13: 32: 31.2, SMSD: SMSD v3.1.14 started.
09/22/2015 13: 32: 31.2, SMSD: Running the SMSD: dialout.
09/22/2015 13: 32: 31.4, SMSD: File mode creation mask: 022 (0644, rw-r - r–).
09/22/2015 13: 32: 31.5, SMSD: Outgoing file checker has started. PID: 7346.
09/22/2015 13: 32: 31.5, GSM1: 0 Modem handler has started. PID: 7347.
09/22/2015 13: 32: 31.5, GSM1: Using check_memory_method 1: CPMS is used.
09/22/2015 13: 32: 36.5, GSM1: CGSN: 353,871,023,813,485
09/22/2015 13: 32: 36.5, GSM1: IMSI: 724,044,430,711,416
09/22/2015 13: 33: 27.5, SMSD: Moved /var/spool/sms/outgoing/out.0.1.3 file to / var / spool / sms / checked
09/22/2015 13: 33: 52.5, GSM1: SMS sent, message_id 25 To: 5542XXXXXXXX, sending Team 9 sec.

cat /var/log/smssl/playsmsd.log

    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 sendsms_process # start
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 simplerate__cansend # allowed user uid: 1 sms_to: 5542XXXXXXX credit: 999,980 count: 1 rate: 0.010 charge: 0.01 balance: 999.97
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 sendSms # saving smslog_id: 3 u: 1 g: 0 gw: smstools SMSC: smstools s: d: 5542XXXXXXX type: text unicode: 0 Status: 0
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 sendsms_process # saved smslog_id: 3 id: 3
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 smstools__outgoing # saving outfile: /var/spool/sms/outgoing/out.0.1.3
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 smstools__outgoing # saved outfile: /var/spool/sms/outgoing/out.0.1.3
    • 09/22/2015 13:33:22 PID560182d1f0621 - # L2 simplerate__deduct enter smslog_id: 3
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 credit_hook_rate_setusercredit # saving uid: 1 balance: 999.97
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 credit_hook_rate_setusercredit # saved uid: 1 balance: 999.97
    • 09/22/2015 13:33:22 PID560182d1f0621 - # L2 simplerate__deduct user uid: 1 parent_uid: smslog_id: 3 msglen: 33 count: 1 rate: 0.010 charge: 0.01 credit: 999,980 balance: 999.97
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 simplebilling__post # saving smslog_id: 3 rate: 0.010 credit: 999,980 count: 1 charge: 0.01
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 simplebilling__post # saved smslog_id: 3 id: 3
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 sendsms_process # end
    • 09/22/2015 13:33:22 PID560182d1f0621 - # L2 sendsmsd result queue_code: 2a416a54110942291c731abbe20de054 to: 5542XXXXXXX flag: 1 smslog_id: 3
    • 09/22/2015 13:33:22 PID560182d1f0621 - L2 sendsmsd # finish processing queue_code: 2a416a54110942291c731abbe20de054 uid: 1 sender_id: queue_count: 1 sms_count: 1
    • 09/22/2015 13:33:54 PID5601801d8103a - L2 smstools__getsmsstatus # DLR mapped id: 3 uid: 1 smslog_id: 3 message_id: 25
    • 09/22/2015 13:33:55 PID5601801d8103a - L2 simplebilling__finalize # saving smslog_id: 3
    • 09/22/2015 13:33:55 PID5601801d8103a - L2 simplebilling__finalize # saved smslog_id: 3
      127.0.0.1 127.0.0.1 9/22/2015 13:36:25 PID5601838958a6b admin L2 user_pref # saving username: admin error_string: [Preferences have been saved]
      127.0.0.1 127.0.0.1 9/22/2015 13:36:34 PID56018391be3c5 admin L2 user_pref # saving username: admin error_string: [Preferences have been saved]

cat /etc/smsd.conf

Global Configuration

devices = GSM1

Logs

stats = / var / log / smstools / smsd_stats /
logfile = /var/log/smstools/smsd.log

Queue

outgoing = / var / spool / sms / outgoing
checked = / var / spool / sms / checked
failed = / var / spool / sms / failed
incoming = / var / spool / sms / incoming
sent = / var / spool / sms / sent
delaytime = 2
errorsleeptime = 10
blocktime = 180
loglevel = 5
autosplit = 3
receive_before_send = no

[queues]

GSM1 # = / var / spool / sms / GSM1

[GSM1]
init = AT + CPMS = “MT,” “MT,” "MT"
init2 = AT + CPMS = “ME” “ME” "ME"
device = /dev/MOD
incoming = yes
baudrate = 19200
#report = yes
#queues = GSM1

Novamente as configurações.

cat /etc/smsd.conf

Global Configuration

devices = GSM1

Logs

stats = /var/log/smstools/smsd_stats/
logfile = /var/log/smstools/smsd.log

Queue

outgoing = /var/spool/sms/outgoing
checked = /var/spool/sms/checked
failed = /var/spool/sms/failed
incoming = /var/spool/sms/incoming
sent = /var/spool/sms/sent
delaytime = 2
errorsleeptime = 10
blocktime = 180
loglevel = 5
autosplit = 3
receive_before_send = no

[GSM1]
init = AT + CPMS = “MT,” “MT,” "MT"
init2 = AT + CPMS = “ME” “ME” "ME"
device = /dev/MOD
incoming = yes
baudrate = 19200

Anton, the team has the translation for pt_BR complete in version 1.0?

Hello I install Today and not send messages.
Please I use Raspberry Pi 2 with Huawei 3131
I need Upgrade SQL or in clean install is OK.

Please what Log rEgister need for Help me.

thanks in advance

I have same problem

the playsms dont send to smstools.

=========== BEGIN

10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L2 sendsms # start uid:1 sender_id:[557199715XXXX] smsc:[]
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L3 sendsms # maxlen:459 footerlen:7 footer:[ @admin] msglen:48 message:[Hello #NAME#, please hurry up, boss summons us !]
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L2 sendsms_queue_create # saving queue_code:4e81e6644473eda16ace17f61599b00f src:557199715XXXX scheduled:2016-02-18 15:44:58
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L2 sendsms_queue_create # saved queue_code:4e81e6644473eda16ace17f61599b00f id:37
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L3 sendsms_manipulate_prefix # before prefix manipulation:[557198816XXXX]
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L3 sendsms_manipulate_prefix # after prefix manipulation:[557198816XXXX]
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L3 simplerate_hook_rate_getbyprefix # rate not found to:557198816XXXX default_rate:0
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L3 simplerate_hook_rate_getcharges # uid:1 u:admin len:55 unicode:0 to:557198816XXXX enable_credit_unicode:1 count:1 rate: charge:0
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L2 sendsms # dst:1 sms_count:1 total_charges:0
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L2 sendsms_queue_push # saving queue_code:4e81e6644473eda16ace17f61599b00f dst:557198816XXXX
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L2 sendsms_queue_push # saved queue_code:4e81e6644473eda16ace17f61599b00f smslog_id:25
10.4.1.20 10.4.1.244 2016-02-18 15:44:58 PID56c6112a2473c admin L2 sendsms # end queue_code:4e81e6644473eda16ace17f61599b00f queue_count:1 sms_count:1 failed_queue:0 failed_sms:0

    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 sendsmsd # start processing queue_code:4e81e6644473eda16ace17f61599b00f chunk:0 queue_count:1 sms_count:1 scheduled:2016-02-18 15:44:58 uid:1 gpid:0 sender_id:557199715XXXX
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 sendsmsd # sending queue_code:4e81e6644473eda16ace17f61599b00f smslog_id:25 to:557198816XXXX sms_count:1 counter:1
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 sendsms_intercept # msgtemplate modified sms_sender:[557199715XXXX] sms_footer:[@admin] sms_to:[557198816XXXX] sms_msg:[Hello Mauricio, please hurry up, boss summons us !] uid:[1] gpid:[0] sms_type:[text] unicode:[0] queue_code:[4e81e6644473eda16ace17f61599b00f] smsc:[]
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 outgoing_hook_sendsms_intercept # found SMSCs:[smstools]
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 outgoing_hook_sendsms_intercept # using prefix based smsc smsc:[smstools] uid:1 parent_uid:0 from:557199715XXXX to:557198816XXXX
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 sendsms_intercept # outgoing modified sms_sender:[557199715XXXX] sms_footer:[@admin] sms_to:[557198816XXXX] sms_msg:[Hello Mauricio, please hurry up, boss summons us !] uid:[1] gpid:[0] sms_type:[text] unicode:[0] queue_code:[4e81e6644473eda16ace17f61599b00f] smsc:[smstools]
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 sendsms_process # start
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 simplerate_hook_rate_getbyprefix # rate not found to:557198816XXXX default_rate:0
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 simplerate_hook_rate_getcharges # uid:1 u:admin len:57 unicode:0 to:557198816XXXX enable_credit_unicode:1 count:1 rate: charge:0
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 simplerate__cansend # allowed user uid:1 sms_to:557198816XXXX credit:9.000 count:1 rate: charge:0 balance:9
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 sendsms # saving smslog_id:25 u:1 g:0 gw:smstools smsc:smstools s:557199715XXXX d:557198816XXXX type:text unicode:0 status:0
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 sendsms_process # saved smslog_id:25 id:19
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 sendsms # final smslog_id:25 gw:smstools smsc:smstools message:Hello Mauricio, please hurry up, boss summons us ! @admin len:57
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 smstools_hook_sendsms # enter smsc:smstools smslog_id:25 uid:1 to:557198816XXXX
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 smstools_hook_sendsms # saving outfile:/var/spool/sms/queue/out.20160218194459.0.1.25 smsc:[smstools]
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 smstools_hook_sendsms # saved outfile:/var/spool/sms/queue/out.20160218194459.0.1.25 smsc:[smstools]
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 dlr # isdlrd:1 smslog_id:25 p_status:0 uid:1
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 simplerate__deduct # enter smslog_id:25
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 simplerate_hook_rate_getbyprefix # rate not found to:557198816XXXX default_rate:0
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 simplerate_hook_rate_getcharges # uid:1 u:admin len:57 unicode:0 to:557198816XXXX enable_credit_unicode:1 count:1 rate: charge:0
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 credit_hook_rate_setusercredit # no changes uid:1 balance:9
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 simplerate__deduct # user uid:1 parent_uid: smslog_id:25 msglen:57 count:1 rate: charge:0 credit:9.000 balance:9
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 simplebilling__post # saving smslog_id:25 rate: credit:9.000 count:1 charge:0
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 simplebilling__post # saved smslog_id:25 id:19
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 simplerate__deduct # deduct successful uid:1 parent_uid: smslog_id:25
    • 2016-02-18 16:44:59 PID56c6112aec11f - L3 simplerate__deduct # credit_lowest_limit:0 balance:9 charge:0
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 sendsms_process # end
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 sendsmsd # result queue_code:4e81e6644473eda16ace17f61599b00f to:557198816XXXX flag:1 smslog_id:25
    • 2016-02-18 16:44:59 PID56c6112aec11f - L2 sendsmsd # finish processing queue_code:4e81e6644473eda16ace17f61599b00f uid:1 sender_id:557199715XXXX queue_count:1 sms_count:1
    • 2016-02-18 16:45:00 PID56c610a348246 - L3 dlrd # id:19 smslog_id:25 p_status:0 uid:1

==================== END

Log SMSTOOLS

2016-02-18 15:51:39,6, GSM1: Checking if Modem is registered to the network
2016-02-18 15:51:39,7, GSM1: -> AT+CREG?
2016-02-18 15:51:39,7, GSM1: Command is sent, waiting for the answer
2016-02-18 15:51:39,7, GSM1: <- +CREG: 2,1, 9C00EB, 75FA OK
2016-02-18 15:51:39,6, GSM1: Modem is registered to the network
2016-02-18 15:51:39,6, GSM1: Selecting PDU mode
2016-02-18 15:51:39,7, GSM1: -> AT+CMGF=0
2016-02-18 15:51:39,7, GSM1: Command is sent, waiting for the answer
2016-02-18 15:51:40,7, GSM1: <- OK
2016-02-18 15:51:40,6, GSM1: Changing SMSC
2016-02-18 15:51:40,7, GSM1: -> AT+CSCA="+550101102010"
2016-02-18 15:51:40,7, GSM1: Command is sent, waiting for the answer
2016-02-18 15:51:40,7, GSM1: <- OK
2016-02-18 15:51:40,6, GSM1: Checking memory size
2016-02-18 15:51:40,7, GSM1: -> AT+CPMS?
2016-02-18 15:51:40,7, GSM1: Command is sent, waiting for the answer
2016-02-18 15:51:41,7, GSM1: <- +CPMS: “SM”,0,15,“SM”,0,15,“SR”,0,0 OK
2016-02-18 15:51:41,6, GSM1: Used memory is 0 of 15
2016-02-18 15:51:41,6, GSM1: No SMS received

smstools is looking in other directory: outgoing = / var / spool / sms / outgoing
sms is put in /var/spool/sms/queue/out.20160218194459.0.1.25

so check your foldrs.