File Permission & SMSD Termination

playSMS Help
1

Hi Anton:

I just installed SMSTools alongside PlaySMS1.4 using a ZTE GSM Modem. The installation seems to be okay except for forced termination of smsd every time the application starts (see log below). I even added ‘sudo chmod -R 755 /var/spool/sms’ to /etc/rc.local to force the appropriate permission for that directory but the application shuts down due to the file permission problems. I have reproduced this error several times even with manual start/restart of smsd. Can you assist? I imagine this is a problem other installers would have resolved. (I am posting here since this forum is MUCH more active and responsive than the SMSTools Forum. Thanks for your earlier help.) The log is shown below. Thanks.

2016-08-02 03:30:01,2, smsd: Smsd v3.1.15 started.
2016-08-02 03:30:02,2, smsd: Running as smsd:dialout.
2016-08-02 03:30:02,2, smsd: Spool directory /var/spool/sms/outgoing is not writable.
2016-08-02 03:30:02,2, smsd: Failed directory /var/spool/sms/failed is not writable.
2016-08-02 03:30:02,2, smsd: Incoming directory /var/spool/sms/incoming is not writable.
2016-08-02 03:30:02,2, smsd: Sent directory /var/spool/sms/sent is not writable.
2016-08-02 03:30:02,2, smsd: Queue directory var/spool/sms/GSM1 is not writable.
2016-08-02 03:30:02,2, smsd: Shell /bin/sh testing failed: Cannot create test script file.
2016-08-02 03:30:02,2, smsd: There was 6 major problems found.
2016-08-02 03:30:02,2, smsd: Smsd mainprocess terminated.

2

Its basic file permission problem, try to chmod to 777

Or follow this manual:

anton

3

Hi Anton:

I followed your directions exactly and re-installed SMSTools. The installation went just fine. However, the installation is still not working. My smsd.conf file is shown below. Notice there is NO reference to ttyACM anywhere in the file (I removed it after the initial installation when I changed the USB modem). Meanwhile from the smsd log file, the service is STILL trying to connect to a device on ttyACM1 & 2. I am using an HSDPA Wireless Data Stick and a Sierra Wireless (USBCOnnect 881) modem on ttyUSB3 and USBtty6 respectively. It is unclear what the problem is since all references to /dev/ttyACM0 and /dev/ttyACM1 had been changed when I changed modems and updated the smsd.conf file. Any ideas? Thanks in advance.

file: /etc/smsd.conf

# Global configuration

devices = modem1, modem2

loglevel = 5

# logfiles
stats = /var/log/sms/stats
logfile = /var/log/sms/smsd.log

# Default queue directory = /var/spool/sms
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

# do not set report folder, let status report files saved in incoming
#report = /var/spool/sms/report

delaytime = 2
errorsleeptime = 10
blocktime = 180
autosplit = 3
#receive_before_send = yes

# Queue configurations

[queues]
modem1 = /var/spool/sms/modem1
modem2 = /var/spool/sms/modem2

# Modem configurations

# Modem name: modem1
# Modem type: Wavecom USB
[modem1]
#init = AT+CNMI=2,2,0,1,0;+CMEE=1
device = /dev/ttyUSB3
baudrate = 115200
incoming = yes
report = yes
queues = modem1

# Modem name: modem2
# Modem type: Wavecom USB
[modem2]
#init = AT+CNMI=2,2,0,1,0;+CMEE=1
device = /dev/ttyUSB6
baudrate = 115200
incoming = yes
report = yes
queues = modem2

file: /var/log/sms/smsd.log

2016-08-02 14:58:20,2, smsd: Smsd v3.1.15 started.
2016-08-02 14:58:20,2, smsd: Running as root:root.
2016-08-02 14:58:20,4, smsd: File mode creation mask: 022 (0644, rw-r--r--).
2016-08-02 14:58:20,5, smsd: Outgoing file checker has started. PID: 12394.
2016-08-02 14:58:20,5, modem1: Modem handler 0 has started. PID: 12395.
2016-08-02 14:58:20,5, modem2: Modem handler 1 has started. PID: 12396.
2016-08-02 14:58:20,5, modem2: Using check_memory_method 1: CPMS is used.
2016-08-02 14:58:20,5, modem1: Using check_memory_method 1: CPMS is used.
2016-08-02 14:59:52,3, modem2: Modem did not accept the pre-init string
2016-08-02 15:00:03,2, modem2: PIN handling: expected READY, modem answered 
2016-08-02 15:00:03,2, modem2: Modem handler 1 terminated abnormally. PID: 12396.
2016-08-02 15:00:22,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:02:24,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:04:27,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:06:31,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:08:35,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:10:38,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:12:42,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:14:45,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:16:49,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:18:53,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:20:56,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:23:00,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:25:04,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-02 15:25:42,3, modem1: write_to_modem: error 5: Input/output error
2016-08-02 15:25:53,3, modem1: write_to_modem: error 5: Input/output error
2016-08-02 15:26:03,3, modem1: write_to_modem: error 5: Input/output error
2016-08-02 15:26:14,3, modem1: Couldn't open serial port /dev/ttyACM1, error: No such file or directory, waiting 30 sec.
2016-08-02 15:26:44,3, modem1: Cannot open serial port /dev/ttyACM1, error: No such file or directory
2016-08-02 15:26:44,3, modem1: Modem is not ready to answer commands (Timeouts: 7)
2016-08-02 15:26:46,3, modem1: Couldn't open serial port /dev/ttyACM1, error: No such file or directory, waiting 30 sec.
2016-08-02 15:27:16,3, modem1: Cannot open serial port /dev/ttyACM1, error: No such file or directory
2016-08-02 15:27:16,2, modem1: Modem handler 0 terminated. PID: 12395, was started 16-08-02 14:58:20.
2016-08-02 15:34:23,2, smsd: Smsd mainprocess is awaiting the termination of all modem handlers. PID: 12394.
2016-08-02 15:34:23,2, smsd: Smsd mainprocess terminated. PID 12394, was started 16-08-02 14:58:20.
4

connect modem to USB port. Then in shell execute: ls -laF /dev/tty*

What the command returned?

5

can you also mention the file name of above config and log ? full path to those files

anton

6

Hi Edson:

I ran the command
ls -laF /dev/tty

and got the return:

crw-rw-rw- 1 root tty 5, 0 Aug 3 10:53 /dev/tty

This response was the same with or without the modem plugged in. I then ran the command

ls -laF /dev/tty*

and got the following output. Hope this helps. Thanks.

crw-rw-rw- 1 root tty 5, 0 Aug 3 10:53 /dev/tty
crw–w---- 1 root tty 4, 0 Aug 3 08:46 /dev/tty0
crw–w---- 1 root tty 4, 1 Aug 3 08:52 /dev/tty1
crw–w---- 1 root tty 4, 10 Aug 3 08:46 /dev/tty10
crw–w---- 1 root tty 4, 11 Aug 3 08:46 /dev/tty11
crw–w---- 1 root tty 4, 12 Aug 3 08:46 /dev/tty12
crw–w---- 1 root tty 4, 13 Aug 3 08:46 /dev/tty13
crw–w---- 1 root tty 4, 14 Aug 3 08:46 /dev/tty14
crw–w---- 1 root tty 4, 15 Aug 3 08:46 /dev/tty15
crw–w---- 1 root tty 4, 16 Aug 3 08:46 /dev/tty16
crw–w---- 1 root tty 4, 17 Aug 3 08:46 /dev/tty17
crw–w---- 1 root tty 4, 18 Aug 3 08:46 /dev/tty18
crw–w---- 1 root tty 4, 19 Aug 3 08:46 /dev/tty19
crw–w---- 1 dthold tty 4, 2 Aug 3 08:46 /dev/tty2
crw–w---- 1 root tty 4, 20 Aug 3 08:46 /dev/tty20
crw–w---- 1 root tty 4, 21 Aug 3 08:46 /dev/tty21
crw–w---- 1 root tty 4, 22 Aug 3 08:46 /dev/tty22
crw–w---- 1 root tty 4, 23 Aug 3 08:46 /dev/tty23
crw–w---- 1 root tty 4, 24 Aug 3 08:46 /dev/tty24
crw–w---- 1 root tty 4, 25 Aug 3 08:46 /dev/tty25
crw–w---- 1 root tty 4, 26 Aug 3 08:46 /dev/tty26
crw–w---- 1 root tty 4, 27 Aug 3 08:46 /dev/tty27
crw–w---- 1 root tty 4, 28 Aug 3 08:46 /dev/tty28
crw–w---- 1 root tty 4, 29 Aug 3 08:46 /dev/tty29
crw–w---- 1 root tty 4, 3 Aug 3 08:46 /dev/tty3
crw–w---- 1 root tty 4, 30 Aug 3 08:46 /dev/tty30
crw–w---- 1 root tty 4, 31 Aug 3 08:46 /dev/tty31
crw–w---- 1 root tty 4, 32 Aug 3 08:46 /dev/tty32
crw–w---- 1 root tty 4, 33 Aug 3 08:46 /dev/tty33
crw–w---- 1 root tty 4, 34 Aug 3 08:46 /dev/tty34
crw–w---- 1 root tty 4, 35 Aug 3 08:46 /dev/tty35
crw–w---- 1 root tty 4, 36 Aug 3 08:46 /dev/tty36
crw–w---- 1 root tty 4, 37 Aug 3 08:46 /dev/tty37
crw–w---- 1 root tty 4, 38 Aug 3 08:46 /dev/tty38
crw–w---- 1 root tty 4, 39 Aug 3 08:46 /dev/tty39
crw–w---- 1 root tty 4, 4 Aug 3 08:46 /dev/tty4
crw–w---- 1 root tty 4, 40 Aug 3 08:46 /dev/tty40
crw–w---- 1 root tty 4, 41 Aug 3 08:46 /dev/tty41
crw–w---- 1 root tty 4, 42 Aug 3 08:46 /dev/tty42
crw–w---- 1 root tty 4, 43 Aug 3 08:46 /dev/tty43
crw–w---- 1 root tty 4, 44 Aug 3 08:46 /dev/tty44
crw–w---- 1 root tty 4, 45 Aug 3 08:46 /dev/tty45
crw–w---- 1 root tty 4, 46 Aug 3 08:46 /dev/tty46
crw–w---- 1 root tty 4, 47 Aug 3 08:46 /dev/tty47
crw–w---- 1 root tty 4, 48 Aug 3 08:46 /dev/tty48
crw–w---- 1 root tty 4, 49 Aug 3 08:46 /dev/tty49
crw–w---- 1 root tty 4, 5 Aug 3 08:46 /dev/tty5
crw–w---- 1 root tty 4, 50 Aug 3 08:46 /dev/tty50
crw–w---- 1 root tty 4, 51 Aug 3 08:46 /dev/tty51
crw–w---- 1 root tty 4, 52 Aug 3 08:46 /dev/tty52
crw–w---- 1 root tty 4, 53 Aug 3 08:46 /dev/tty53
crw–w---- 1 root tty 4, 54 Aug 3 08:46 /dev/tty54
crw–w---- 1 root tty 4, 55 Aug 3 08:46 /dev/tty55
crw–w---- 1 root tty 4, 56 Aug 3 08:46 /dev/tty56
crw–w---- 1 root tty 4, 57 Aug 3 08:46 /dev/tty57
crw–w---- 1 root tty 4, 58 Aug 3 08:46 /dev/tty58
crw–w---- 1 root tty 4, 59 Aug 3 08:46 /dev/tty59
crw–w---- 1 root tty 4, 6 Aug 3 08:46 /dev/tty6
crw–w---- 1 root tty 4, 60 Aug 3 08:46 /dev/tty60
crw–w---- 1 root tty 4, 61 Aug 3 08:46 /dev/tty61
crw–w---- 1 root tty 4, 62 Aug 3 08:46 /dev/tty62
crw–w---- 1 root tty 4, 63 Aug 3 08:46 /dev/tty63
crw–w---- 1 gdm tty 4, 7 Aug 3 08:46 /dev/tty7
crw–w---- 1 root tty 4, 8 Aug 3 08:46 /dev/tty8
crw–w---- 1 root tty 4, 9 Aug 3 08:46 /dev/tty9
crw-rw---- 1 root dialout 166, 0 Aug 3 11:46 /dev/ttyACM0
crw-rw---- 1 root dialout 166, 1 Aug 3 10:55 /dev/ttyACM1
crw------- 1 root root 5, 3 Aug 3 08:46 /dev/ttyprintk
crw-rw---- 1 root dialout 4, 64 Aug 3 08:46 /dev/ttyS0
crw-rw---- 1 root dialout 4, 65 Aug 3 08:46 /dev/ttyS1
crw-rw---- 1 root dialout 4, 74 Aug 3 08:46 /dev/ttyS10
crw-rw---- 1 root dialout 4, 75 Aug 3 08:46 /dev/ttyS11
crw-rw---- 1 root dialout 4, 76 Aug 3 08:46 /dev/ttyS12
crw-rw---- 1 root dialout 4, 77 Aug 3 08:46 /dev/ttyS13
crw-rw---- 1 root dialout 4, 78 Aug 3 08:46 /dev/ttyS14
crw-rw---- 1 root dialout 4, 79 Aug 3 08:46 /dev/ttyS15
crw-rw---- 1 root dialout 4, 80 Aug 3 08:46 /dev/ttyS16
crw-rw---- 1 root dialout 4, 81 Aug 3 08:46 /dev/ttyS17
crw-rw---- 1 root dialout 4, 82 Aug 3 08:46 /dev/ttyS18
crw-rw---- 1 root dialout 4, 83 Aug 3 08:46 /dev/ttyS19
crw-rw---- 1 root dialout 4, 66 Aug 3 08:46 /dev/ttyS2
crw-rw---- 1 root dialout 4, 84 Aug 3 08:46 /dev/ttyS20
crw-rw---- 1 root dialout 4, 85 Aug 3 08:46 /dev/ttyS21
crw-rw---- 1 root dialout 4, 86 Aug 3 08:46 /dev/ttyS22
crw-rw---- 1 root dialout 4, 87 Aug 3 08:46 /dev/ttyS23
crw-rw---- 1 root dialout 4, 88 Aug 3 08:46 /dev/ttyS24
crw-rw---- 1 root dialout 4, 89 Aug 3 08:46 /dev/ttyS25
crw-rw---- 1 root dialout 4, 90 Aug 3 08:46 /dev/ttyS26
crw-rw---- 1 root dialout 4, 91 Aug 3 08:46 /dev/ttyS27
crw-rw---- 1 root dialout 4, 92 Aug 3 08:46 /dev/ttyS28
crw-rw---- 1 root dialout 4, 93 Aug 3 08:46 /dev/ttyS29
crw-rw---- 1 root dialout 4, 67 Aug 3 08:46 /dev/ttyS3
crw-rw---- 1 root dialout 4, 94 Aug 3 08:46 /dev/ttyS30
crw-rw---- 1 root dialout 4, 95 Aug 3 08:46 /dev/ttyS31
crw-rw---- 1 root dialout 4, 68 Aug 3 08:46 /dev/ttyS4
crw-rw---- 1 root dialout 4, 69 Aug 3 08:46 /dev/ttyS5
crw-rw---- 1 root dialout 4, 70 Aug 3 08:46 /dev/ttyS6
crw-rw---- 1 root dialout 4, 71 Aug 3 08:46 /dev/ttyS7
crw-rw---- 1 root dialout 4, 72 Aug 3 08:46 /dev/ttyS8
crw-rw---- 1 root dialout 4, 73 Aug 3 08:46 /dev/ttyS9

7

Hi Anton:

I have updated the post with the full path for the two files. Thanks once again.

8

You do have tty acm and I dont see tty usb in your log

Can you double check your config make sure its using correct tty dev

Restart smstools and check again the log

Anton

9

Clean /var/log/smstools/smsd.log then restart smstools service. Post new smsd.log here.

10

Hi:

Thanks for the pointers. I am afraid I still cannot get it to work. First I changed modems just to make sure the modem was not the problem and then went through a re-install. So now I have ONE modem (HSDPA 3.75G wireless modem)… When I connected it and ran

dmesg

I got the response shown below (irrelevant parts redacted)

[ 3513.261718] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB3
[ 3513.273279] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
[ 3513.273294] option 8-2:1.0: device disconnected
[ 3513.274186] option1 ttyUSB1: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.274194] option1 ttyUSB1: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.274200] option1 ttyUSB1: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.275283] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
[ 3513.275293] option 8-2:1.1: device disconnected
[ 3513.276187] option1 ttyUSB2: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.276195] option1 ttyUSB2: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.276201] option1 ttyUSB2: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.277279] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
[ 3513.277288] option 8-2:1.2: device disconnected
[ 3513.278191] option1 ttyUSB3: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.278198] option1 ttyUSB3: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.278204] option1 ttyUSB3: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.279295] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
[ 3513.279304] option 8-2:1.3: device disconnected
[ 3513.392049] usb 8-2: reset full-speed USB device number 3 using uhci_hcd
[ 3513.541252] usb-storage 8-2:1.0: USB Mass Storage device detected
[ 3513.541401] option 8-2:1.0: GSM modem (1-port) converter detected
[ 3513.542147] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB0
[ 3513.542166] usb-storage 8-2:1.1: USB Mass Storage device detected
[ 3513.545089] option 8-2:1.1: GSM modem (1-port) converter detected
[ 3513.545914] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB1
[ 3513.545944] usb-storage 8-2:1.2: USB Mass Storage device detected
[ 3513.547817] option 8-2:1.2: GSM modem (1-port) converter detected
[ 3513.547917] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB2
[ 3513.547935] usb-storage 8-2:1.3: USB Mass Storage device detected
[ 3513.551839] option 8-2:1.3: GSM modem (1-port) converter detected
[ 3513.552336] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB3
[ 3513.565088] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
[ 3513.565102] option 8-2:1.0: device disconnected
[ 3513.565160] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
[ 3513.565186] option 8-2:1.1: device disconnected
[ 3513.565243] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
[ 3513.565253] option 8-2:1.2: device disconnected
[ 3513.566185] option1 ttyUSB3: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.566192] option1 ttyUSB3: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.566198] option1 ttyUSB3: usb_wwan_indat_callback: resubmit read urb failed. (-2)
[ 3513.567303] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
[ 3513.567316] option 8-2:1.3: device disconnected
[ 3513.680112] usb 8-2: reset full-speed USB device number 3 using uhci_hcd
[ 3513.828659] usb-storage 8-2:1.0: USB Mass Storage device detected
[ 3513.840106] option 8-2:1.0: GSM modem (1-port) converter detected
[ 3513.842078] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB0
[ 3513.842120] usb-storage 8-2:1.1: USB Mass Storage device detected
[ 3513.848142] option 8-2:1.1: GSM modem (1-port) converter detected
[ 3513.850012] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB1
[ 3513.850042] usb-storage 8-2:1.2: USB Mass Storage device detected
[ 3513.855353] option 8-2:1.2: GSM modem (1-port) converter detected
[ 3513.857616] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB2
[ 3513.857651] usb-storage 8-2:1.3: USB Mass Storage device detected
[ 3513.861364] option 8-2:1.3: GSM modem (1-port) converter detected
[ 3513.861521] usb 8-2: GSM modem (1-port) converter now attached to ttyUSB3

On the basis of this I specified ttyUSB3 for the modem in smsd.conf. Here is my /etc/smsd.conf:

Global configuration

devices = modem1, modem2

loglevel = 5

logfiles

stats = /var/log/sms/stats
logfile = /var/log/sms/smsd.log

Default queue directory = /var/spool/sms

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

do not set report folder, let status report files saved in incoming

#report = /var/spool/sms/report

delaytime = 2
errorsleeptime = 10
blocktime = 180
autosplit = 3
#receive_before_send = yes

Queue configurations

[queues]
modem1 = /var/spool/sms/modem1
modem2 = /var/spool/sms/modem2

Modem configurations

Modem name: modem1

Modem type: Wavecom USB

[modem1]
#init = AT+CNMI=2,2,0,1,0;+CMEE=1
device = /dev/ttyUSB3
baudrate = 115200
incoming = yes
report = yes
queues = modem1

Modem name: modem2

Modem type: Wavecom USB

[modem2]
#init = AT+CNMI=2,2,0,1,0;+CMEE=1
device = /dev/ttyUSB1
baudrate = 115200
incoming = yes
report = yes
queues = modem2

Here is my /var/log/smsd.log. The modem seems to time out after two minutes.

2016-08-08 15:23:53,2, smsd: Smsd v3.1.15 started.
2016-08-08 15:23:53,2, smsd: Running as root:root.
2016-08-08 15:23:53,4, smsd: File mode creation mask: 022 (0644, rw-r–r--).
2016-08-08 15:23:53,5, smsd: Outgoing file checker has started. PID: 30489.
2016-08-08 15:23:53,5, modem1: Modem handler 0 has started. PID: 30491.
2016-08-08 15:23:53,5, modem2: Modem handler 1 has started. PID: 30492.
2016-08-08 15:23:53,5, modem1: Using check_memory_method 1: CPMS is used.
2016-08-08 15:23:53,5, modem2: Using check_memory_method 1: CPMS is used.
2016-08-08 15:25:54,3, modem2: Modem is not ready to answer commands (Timeouts: 0)
2016-08-08 15:25:55,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-08 15:27:58,3, modem2: Modem is not ready to answer commands (Timeouts: 0)
2016-08-08 15:27:58,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-08 15:30:02,3, modem2: Modem is not ready to answer commands (Timeouts: 0)
2016-08-08 15:30:02,3, modem1: Modem is not ready to answer commands (Timeouts: 0)
2016-08-08 15:32:05,3, modem2: Modem is not ready to answer commands (Timeouts: 0)
2016-08-08 15:32:05,3, modem1: Modem is not ready to answer commands (Timeouts: 0)

Just to make sure my PlaySMS is configured correctly (based on the above), here are my entries in the SMSC configuration:

Manage Gateway & SMSC
Gateway: smstools
SMSC name: modem1
Receiver number: 12063130004
Queue directory: /var/spool/sms/modem1

{Main} SMSTools Setting:
Default default_queue directory: /var/spool/sms

{Main} Route Outgoing SMS:
(no entries made yet)

{Main} Configuration:
Default SMSC: modem1

Please let me know if you can identify the problem. Thanks.