Sending unsuccessful. Error: Command timed out

[Rodrigo Montiel]

Hi all,

My environment:
------------------------------------------------
CentOS release 5.9 (Final)
Kernel: 2.6.18-348.1.1.el5
[address@hidden ~]# rpm -qa | grep gnokii
gnokii-smsd-0.6.30-1.el5
gnokii-0.6.30-1.el5
gnokii-smsd-mysql-0.6.30-1.el5
gnokii-devel-0.6.30-1.el5
[address@hidden ~]# lsusb 
Bus 001 Device 001: ID 0000:0000  
Bus 001 Device 002: ID 19d2:0156 ZTE WCDMA Technologies MSM 

gnokiirc configuration:
[global]
port = /dev/ttyUSB2
model = AT
initlength = default
connection = serial
use_locking = no
serial_baudrate = 19200
smsc_timeout = 10
[xgnokii]
allow_breakage = 0
[gnokiid]
bindir = /usr/sbin/
[logging]
debug = on
rlpdebug = off
xdebug = off
[fake_driver]
sms_inbox = /tmp/sms

gnokii sysconfig configuration:
SMSD_OPTS="--user smsd --db smsd --host localhost --module mysql --password test123"

------------------------------------------------

Im having some issues when trying to send massive SMS messages thru gnokii-smsd service.
Sometimes I realize the gnokii-smsd status is "subsystem locked" (running service gnokii-smsd status).

Logs are pointing to /var/log/gnokii-smsd with the following messages:

[address@hidden ~]# tail -f /var/log/gnokii-smsd
24 Oct 2013 10:52:16: Inserting sms from +5492945692192 successful.
24 Oct 2013 10:52:27: Sending to 3513262916 unsuccessful. Error: Command timed out.
24 Oct 2013 10:52:47: Sending to 3513262916 unsuccessful. Error: Unknown error - well better than nothing!!
24 Oct 2013 10:55:33: Sending to 3513443723 unsuccessful. Error: Command timed out.
24 Oct 2013 10:56:05: Duplicated sms from +5492945692192.
24 Oct 2013 10:56:16: Sending to 3513443723 unsuccessful. Error: Command timed out.
24 Oct 2013 10:56:27: Sending to 3513443723 unsuccessful. Error: Problem occurred internal to model specific code.
24 Oct 2013 10:57:19: Sending to 3513443723 unsuccessful. Error: Command timed out.
24 oct 2013 10:59:47: Sending to 3513443723 unsuccessful. Error: Command timed out.
24 oct 2013 11:00:20: Sending to 3513443723 unsuccessful. Error: Command timed out.

When this issue occurs, I usually restart the daemon but the same messages appear. Reboot of the machine is not a solution.
The only way to have it working again is to delete the row containing that number and restart the service:

mysql> select id,number,processed_date,insertdate,phone,processed,not_before,not_after from smsd.outbox where number=3513443723;
+-----+------------+---------------------+---------------------+-------+-----------+------------+-----------+
| id  | number     | processed_date      | insertdate          | phone | processed | not_before | not_after |
+-----+------------+---------------------+---------------------+-------+-----------+------------+-----------+
| 870 | 3513443723 | 0000-00-00 00:00:00 | 2013-10-24 10:41:52 |  NULL |         0 | 10:30:00   | 11:30:00  | 
+-----+------------+---------------------+---------------------+-------+-----------+------------+-----------+
1 row in set (0.00 sec)

mysql> delete from outbox where number='3513443723';
Query OK, 1 row affected (0.00 sec)

(new logs)
24 oct 2013 11:00:20: Sending to 3513443723 unsuccessful. Error: Command timed out.
24 Oct 2013 11:26:47: Sending to 3513608447 successful.
24 Oct 2013 11:27:12: Inserting sms from +5493513443723 successful.
24 Oct 2013 11:27:12: Inserting sms from +5493513443723 successful.
24 Oct 2013 11:27:12: Duplicated sms from +5492945692192.
24 Oct 2013 11:27:20: Sending to 3515170312 successful.
24 Oct 2013 11:27:52: Sending to 3515605643 successful.
24 Oct 2013 11:28:26: Sending to 3516358156 successful.

Really appreciate your valuable help on this,

Thanks,