pnaegele: Operating system name and version: OpenWRT 10.03.1-rc5
Version of smsd: 3.1.14
Smsd installed from: Binary
Name and model of a modem / phone: Huawei E1550 (Reported by lsusb as E620)
Interface: USB
Hello there,
smsd seems to lock up after sending an sms.
After restarting smsd, it gets to
2010-04-06 03:31:01,6, huawei: Checking if modem is ready
2010-04-06 03:31:02,7, huawei: -> A
and then just stops doing anything.
Reffer to the bottom for the whole startup log after restart.
#smsd.conf file
user = smsd
group = smsd
devices = huawei
PIDFILE=/var/spool/sms/smsd.pid
infofile = /var/spool/sms/smsd.running
logfile = /var/log/smstools/smsd.log
loglevel = 7
[huawei]
device = /dev/ttyUSB0
signal_quality_ber_ignore = yes
baudrate = 115200
memory_start=0
rtscts = yes
pin= xxxx
init= AT+CPMS="sm","ME",""
incoming = yes
incoming = high
Log after reboot:
2010-04-06 03:18:12,2, smsd: Smsd v3.1.14 started.
2010-04-06 03:18:12,2, smsd: Running as smsd:smsd.
2010-04-06 03:18:12,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.Kd2Z7d /tmp/smsd_data.aRal1V
2010-04-06 03:18:12,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0)
2010-04-06 03:18:12,4, smsd: File mode creation mask: 022 (0644, rw-r--r--).
2010-04-06 03:18:12,5, smsd: Outgoing file checker has started. PID: 1496.
2010-04-06 03:18:12,7, smsd: All PID's: 1496,1498
2010-04-06 03:18:12,5, huawei: Modem handler 0 has started. PID: 1498.
2010-04-06 03:18:12,3, huawei: Couldn't open serial port /dev/ttyUSB0, error: Permission denied, waiting 30 sec.
2010-04-06 03:18:42,5, huawei: Using check_memory_method 1: CPMS is used.
2010-04-06 03:18:42,6, huawei: Checking device for incoming SMS
2010-04-06 03:18:42,6, huawei: Checking if modem is ready
2010-04-06 03:18:43,7, huawei: -> AT
2010-04-06 03:18:43,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:43,7, huawei: <- OK
2010-04-06 03:18:43,6, huawei: Pre-initializing modem
2010-04-06 03:18:43,7, huawei: -> ATE0+CMEE=1;+CREG=2
2010-04-06 03:18:43,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:43,7, huawei: <- OK
2010-04-06 03:18:43,6, huawei: Checking if modem needs PIN
2010-04-06 03:18:44,7, huawei: -> AT+CPIN?
2010-04-06 03:18:44,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:44,7, huawei: <- +CPIN: READY OK
2010-04-06 03:18:44,6, huawei: Initializing modem
2010-04-06 03:18:44,7, huawei: -> AT+CPMS="sm","ME",""
2010-04-06 03:18:44,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:44,7, huawei: <- +CPMS: 0,20,0,23,0,20 OK
2010-04-06 03:18:45,7, huawei: -> AT+CSQ
2010-04-06 03:18:45,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:45,7, huawei: <- +CSQ: 8,99 OK
2010-04-06 03:18:45,6, huawei: Signal Strength Indicator: (8,99) -97 dBm (Marginal)
2010-04-06 03:18:45,6, huawei: Checking if Modem is registered to the network
2010-04-06 03:18:45,7, huawei: -> AT+CREG?
2010-04-06 03:18:45,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:45,7, huawei: <- +CREG: 2,1,5277,37BE OK
2010-04-06 03:18:45,6, huawei: Modem is registered to the network
2010-04-06 03:18:45,6, huawei: Location area code: 5277, Cell ID: 37BE
2010-04-06 03:18:46,7, huawei: -> AT+CSQ
2010-04-06 03:18:46,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:46,7, huawei: <- +CSQ: 8,99 OK
2010-04-06 03:18:46,6, huawei: Signal Strength Indicator: (8,99) -97 dBm (Marginal)
2010-04-06 03:18:46,6, huawei: Selecting PDU mode
2010-04-06 03:18:46,7, huawei: -> AT+CMGF=0
2010-04-06 03:18:46,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:46,7, huawei: <- OK
2010-04-06 03:18:43,6, huawei: Checking if modem needs PIN
2010-04-06 03:18:44,7, huawei: -> AT+CPIN?
2010-04-06 03:18:44,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:44,7, huawei: <- +CPIN: READY OK
2010-04-06 03:18:44,6, huawei: Initializing modem
2010-04-06 03:18:44,7, huawei: -> AT+CPMS="sm","ME",""
2010-04-06 03:18:44,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:44,7, huawei: <- +CPMS: 0,20,0,23,0,20 OK
2010-04-06 03:18:45,7, huawei: -> AT+CSQ
2010-04-06 03:18:45,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:45,7, huawei: <- +CSQ: 8,99 OK
2010-04-06 03:18:45,6, huawei: Signal Strength Indicator: (8,99) -97 dBm (Marginal)
2010-04-06 03:18:45,6, huawei: Checking if Modem is registered to the network
2010-04-06 03:18:45,7, huawei: -> AT+CREG?
2010-04-06 03:18:45,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:45,7, huawei: <- +CREG: 2,1,5277,37BE OK
2010-04-06 03:18:45,6, huawei: Modem is registered to the network
2010-04-06 03:18:45,6, huawei: Location area code: 5277, Cell ID: 37BE
2010-04-06 03:18:46,7, huawei: -> AT+CSQ
2010-04-06 03:18:46,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:46,7, huawei: <- +CSQ: 8,99 OK
2010-04-06 03:18:46,6, huawei: Signal Strength Indicator: (8,99) -97 dBm (Marginal)
2010-04-06 03:18:46,6, huawei: Selecting PDU mode
2010-04-06 03:18:46,7, huawei: -> AT+CMGF=0
2010-04-06 03:18:46,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:46,7, huawei: <- OK
2010-04-06 03:18:47,7, huawei: -> AT+CIMI
2010-04-06 03:18:47,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:47,7, huawei: <- xxxxxxxxxxxxx OK
2010-04-06 03:18:47,7, huawei: -> AT+CGSN
2010-04-06 03:18:47,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:47,7, huawei: <- xxxxxxxxx OK
2010-04-06 03:18:47,5, huawei: CGSN: xxxxxxxxxxxxxxx
2010-04-06 03:18:47,5, huawei: IMSI: xxxxxxxxxxxxxx
2010-04-06 03:18:47,6, huawei: Checking if reading of messages is supported
2010-04-06 03:18:48,7, huawei: -> AT+CPMS?
2010-04-06 03:18:48,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:48,7, huawei: <- +CPMS: "SM",0,20,"ME",0,23,"SM",0,20 OK
2010-04-06 03:18:48,6, huawei: Checking memory size
2010-04-06 03:18:48,7, huawei: -> AT+CPMS?
2010-04-06 03:18:48,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:18:48,7, huawei: <- +CPMS: "SM",0,20,"ME",0,23,"SM",0,20 OK
2010-04-06 03:18:48,6, huawei: Used memory is 0 of 20
2010-04-06 03:18:48,6, huawei: No SMS received
*snip*
2010-04-06 03:23:03,5, smsd: Moved file /var/spool/sms/outgoing/send_J5jdrq to /var/spool/sms/checked
2010-04-06 03:23:04,6, huawei: I have to send 1 short message for /var/spool/sms/checked/send_J5jdrq
2010-04-06 03:23:04,6, huawei: Sending SMS from to xxxxxxxxxxxxx
2010-04-06 03:23:04,6, huawei: Checking if modem is ready
2010-04-06 03:23:04,7, huawei: -> AT
2010-04-06 03:23:04,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:23:04,7, huawei: <- OK
2010-04-06 03:23:04,6, huawei: Pre-initializing modem
2010-04-06 03:23:05,7, huawei: -> ATE0+CMEE=1;+CREG=2
2010-04-06 03:23:05,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:23:05,7, huawei: <- OK
2010-04-06 03:23:05,6, huawei: Checking if modem needs PIN
2010-04-06 03:23:05,7, huawei: -> AT+CPIN?
2010-04-06 03:23:05,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:23:05,7, huawei: <- +CPIN: READY OK
2010-04-06 03:23:05,6, huawei: Initializing modem
2010-04-06 03:23:06,7, huawei: -> AT+CPMS="sm","ME",""
2010-04-06 03:23:06,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:23:06,7, huawei: <- +CPMS: 0,20,0,23,0,20 OK
2010-04-06 03:23:06,7, huawei: -> AT+CSQ
2010-04-06 03:23:06,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:23:06,7, huawei: <- +CSQ: 8,99 OK
2010-04-06 03:23:06,6, huawei: Signal Strength Indicator: (8,99) -97 dBm (Marginal)
2010-04-06 03:23:06,6, huawei: Checking if Modem is registered to the network
2010-04-06 03:23:07,7, huawei: -> AT+CREG?
2010-04-06 03:23:07,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:23:07,7, huawei: <- +CREG: 2,1,5277,37BE OK
2010-04-06 03:23:07,6, huawei: Modem is registered to the network
2010-04-06 03:23:07,6, huawei: Selecting PDU mode
2010-04-06 03:23:07,7, huawei: -> AT+CMGF=0
2010-04-06 03:23:07,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:23:07,7, huawei: <- OK
2010-04-06 03:23:08,7, huawei: -> AT+CMGS=23
2010-04-06 03:23:08,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:23:08,7, huawei: <- >
2010-04-06 03:23:08,7, huawei: -> xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx.
2010-04-06 03:23:08,7, huawei: Command is sent, waiting for the answer
2010-04-06 03:24:08,7, huawei: put_command expected (OK)|(ERROR), timeout occurred. 1.
2010-04-06 03:24:08,7, huawei: <-
2010-04-06 03:24:08,3, huawei: The modem answer was not OK:
2010-04-06 03:24:08,5, huawei: Waiting 10 sec. before retrying
2010-04-06 03:24:18,6, huawei: Checking if modem is ready
2010-04-06 03:24:19,7, huawei: -> AT
I suspect that the "Couldn't open serial port /dev/ttyUSB0, error: Permission denied, waiting 30 sec." line ist from slow device settling.
After restarting smsd, the following can be found in the log file:
2010-04-06 03:29:47,2, smsd: Smsd v3.1.14 started.
2010-04-06 03:29:47,2, smsd: Running as smsd:smsd.
2010-04-06 03:29:47,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.5GTPJX /tmp/smsd_data.gvkAE3
2010-04-06 03:29:47,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0)
2010-04-06 03:29:47,4, smsd: File mode creation mask: 022 (0644, rw-r--r--).
2010-04-06 03:29:47,5, smsd: Outgoing file checker has started. PID: 1861.
2010-04-06 03:29:47,7, smsd: All PID's: 1861,1862
2010-04-06 03:29:47,5, huawei: Modem handler 0 has started. PID: 1862.
2010-04-06 03:30:16,2, huawei: Modem handler 0 terminated. PID: 1498, was started 10-04-06 03:18:12.
2010-04-06 03:30:16,2, smsd: Smsd mainprocess terminated. PID 1496, was started 10-04-06 03:18:12.
2010-04-06 03:30:16,3, huawei: Couldn't open serial port /dev/ttyUSB0, error: Input/output error, waiting 30 sec.
2010-04-06 03:31:01,5, huawei: Using check_memory_method 1: CPMS is used.
2010-04-06 03:31:01,6, huawei: Checking device for incoming SMS
2010-04-06 03:31:01,6, huawei: Checking if modem is ready
2010-04-06 03:31:02,7, huawei: -> AT
Nothing happens from then on.
There are no error messages in the syslog.
|