Operating system name and version: FreeBSD 7.2-RELEASE #4
Version of smsd: Version 3.1.14
Smsd installed from: ports
Name and model of a modem / phone: Motorola C380
Interface: USB

Hello, I did not receiving the SMS. Sending SMS is fine.

Log file:
2011-07-06 14:38:30,6, GSM0: Checking device for incoming SMS 2011-07-06 14:38:30,6, GSM0: Checking if modem is ready 2011-07-06 14:38:30,7, GSM0: -> AT 2011-07-06 14:38:30,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:30,7, GSM0: <- AT OK 2011-07-06 14:38:30,6, GSM0: Pre-initializing modem 2011-07-06 14:38:30,7, GSM0: -> ATE0+CMEE=1;+CREG=2 2011-07-06 14:38:30,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:31,7, GSM0: <- TE0+CMEE=1;+CREG=2 +CME ERROR: 25 (invalid characters in text string) 2011-07-06 14:38:31,3, GSM0: Modem did not accept the pre-init string 2011-07-06 14:38:31,6, GSM0: Initializing modem 2011-07-06 14:38:31,7, GSM0: -> ATZ 2011-07-06 14:38:31,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:33,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 14:38:33,7, GSM0: <- 2011-07-06 14:38:33,7, GSM0: -> ATZ 2011-07-06 14:38:33,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:34,7, GSM0: <- ATZ OK 2011-07-06 14:38:34,7, GSM0: -> AT+CPMS="IM","OM","IM" 2011-07-06 14:38:34,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:35,7, GSM0: <- AT+CPMS="IM","OM","IM" +CPMS: 2,254,0,254,2,254 OK 2011-07-06 14:38:35,7, GSM0: -> AT+CSQ 2011-07-06 14:38:35,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:35,7, GSM0: <- AT+CSQ +CSQ: 27,99 OK 2011-07-06 14:38:35,6, GSM0: Signal Strength Indicator: (27,99) -59 dBm (Excellent), Bit Error Rate: not known or not detectable 2011-07-06 14:38:35,6, GSM0: Selecting PDU mode 2011-07-06 14:38:35,7, GSM0: -> AT+CMGF=0 2011-07-06 14:38:35,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:36,7, GSM0: <- T+CMGF=0 OK 2011-07-06 14:38:36,6, GSM0: Checking memory size 2011-07-06 14:38:36,7, GSM0: -> AT+CPMS? 2011-07-06 14:38:36,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:36,7, GSM0: <- T+CPMS? +CPMS: "IM",2,254,"OM",0,254,"IM",2,254 OK 2011-07-06 14:38:36,6, GSM0: Used memory is 2 of 254 2011-07-06 14:38:36,6, GSM0: Trying to get stored message 0 2011-07-06 14:38:37,7, GSM0: -> AT+CMGR=0 2011-07-06 14:38:37,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:37,7, GSM0: <- T+CMGR=0 ERROR 2011-07-06 14:38:37,6, GSM0: Trying to get stored message 1 2011-07-06 14:38:37,7, GSM0: -> AT+CMGR=1 2011-07-06 14:38:37,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:38,7, GSM0: <- T+CMGR=1 ERROR 2011-07-06 14:38:38,6, GSM0: Trying to get stored message 2 2011-07-06 14:38:38,7, GSM0: -> AT+CMGR=2 2011-07-06 14:38:38,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:38:38,7, GSM0: <- T+CMGR=2 ERROR 2011-07-06 14:38:38,6, GSM0: Trying to get stored message 3 2011-07-06 14:38:39,7, GSM0: -> AT+CMGR=3 2011-07-06 14:38:39,7, GSM0: Command is sent, waiting for the answer ................................ 2011-07-06 14:44:52,6, GSM0: Trying to get stored message 253 2011-07-06 14:44:53,7, GSM0: -> AT+CMGR=253 2011-07-06 14:44:53,7, GSM0: Command is sent, waiting for the answer 2011-07-06 14:44:53,7, GSM0: <- T+CMGR=253 ERROR

Settings:
devices = GSM0 logfile = /var/log/smsd.log loglevel = 7 USER = uucp GROUP = dialer PIDFILE= /var/run/smsd/smsd.pid INFOFILE= /var/run/smsd/smsd.working delaytime_mainprocess = 5 [GSM0] device = /dev/cuaU0 baudrate = 115200 rtscts = no init = ATZ init2 = AT+CPMS="IM","OM","IM" incoming = high report = yes send_delay = 5 read_timeout = 1 pin = ignore check_network = no #max_continuous_sending = 5 #secondary_memory_max = 10 check_memory_method = 1 memory_start = 0 primary_memory = IM secondary_memory = OM

Thanks. (c) Google tranlsation

First, you have disabled handshaking (rtscts), why is that? Usually handshaking should be used, and if it is not used, lower baudrate is often required. You could try with 9600 or 19200. In many cases there is "A" letter missing from the answer, and it does not look very good.

Remove init = ATZ setting. It causes that "echo" is set to "ON", which usually should not be done.

Pre-initialization gets an error. To see if it is caused by CREG=2, use the modem setting loglevel_lac_ci = 8.

It seems that indexes of messages in your phone are not numbers starting from zero or one. In that case you should try a modem setting check_memory_method = 31 which gets list of indexes.

Also, use the modem setting report_device_details = yes and show those details from the log (all between ## Start of device details and ## End of device details).

Great work! SMS received normally, but...

Details without "init = ATZ":
2011-07-06 18:08:36,2, smsd: Smsd v3.1.14 started. 2011-07-06 18:08:36,2, smsd: Running as uucp:uucp. 2011-07-06 18:08:36,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.w77tMo /tmp/smsd_data.YGz6IP 2011-07-06 18:08:36,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2011-07-06 18:08:36,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2011-07-06 18:08:36,5, smsd: Outgoing file checker has started. PID: 64242. 2011-07-06 18:08:36,7, smsd: All PID's: 64242,64243 2011-07-06 18:08:36,5, GSM0: Modem handler 0 has started. PID: 64243. 2011-07-06 18:08:36,5, GSM0: Using check_memory_method 31: CMGL is used and messages are taken from the list. 2011-07-06 18:08:36,5, GSM0: Using read_timeout 1 seconds. 2011-07-06 18:08:36,6, GSM0: Checking device for incoming SMS 2011-07-06 18:08:36,6, GSM0: Checking if modem is ready 2011-07-06 18:08:36,7, GSM0: -> AT 2011-07-06 18:08:36,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:37,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:37,7, GSM0: <- 2011-07-06 18:08:38,7, GSM0: -> ^Z 2011-07-06 18:08:38,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:39,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 2. 2011-07-06 18:08:39,7, GSM0: <- 2011-07-06 18:08:39,7, GSM0: -> AT 2011-07-06 18:08:39,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:39,7, GSM0: <- OK 2011-07-06 18:08:39,6, GSM0: Pre-initializing modem 2011-07-06 18:08:39,7, GSM0: -> ATE0+CMEE=1 2011-07-06 18:08:40,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:42,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:42,7, GSM0: <- 2011-07-06 18:08:42,3, GSM0: Modem did not accept the pre-init string 2011-07-06 18:08:42,6, GSM0: Initializing modem 2011-07-06 18:08:42,7, GSM0: -> AT+CPMS="IM" 2011-07-06 18:08:42,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:42,7, GSM0: <- +CPMS: 0,254 OK 2011-07-06 18:08:42,7, GSM0: -> AT+CSQ 2011-07-06 18:08:42,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:44,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:44,7, GSM0: <- 2011-07-06 18:08:45,7, GSM0: -> AT+CSQ 2011-07-06 18:08:45,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:45,7, GSM0: <- +CSQ: 27,99 OK 2011-07-06 18:08:45,6, GSM0: Signal Strength Indicator: (27,99) -59 dBm (Excellent), Bit Error Rate: not known or not detecta 2011-07-06 18:08:45,6, GSM0: Selecting PDU mode 2011-07-06 18:08:45,7, GSM0: -> AT+CMGF=0 2011-07-06 18:08:45,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:46,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:46,7, GSM0: <- 2011-07-06 18:08:47,7, GSM0: -> AT+CMGF=0 2011-07-06 18:08:47,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:47,7, GSM0: <- OK 2011-07-06 18:08:47,7, GSM0: -> AT+CIMI 2011-07-06 18:08:47,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:48,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:48,7, GSM0: <- 2011-07-06 18:08:49,7, GSM0: -> AT+CGSN 2011-07-06 18:08:49,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:49,7, GSM0: <- OK 2011-07-06 18:08:49,5, GSM0: CGSN: 2011-07-06 18:08:49,5, GSM0: IMSI: 2011-07-06 18:08:49,6, GSM0: Checking if reading of messages is supported 2011-07-06 18:08:49,7, GSM0: -> AT+CPMS? 2011-07-06 18:08:49,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:08:50,7, GSM0: <- IM",0,254 OK 2011-07-06 18:08:50,7, GSM0: ## Start of device details 2011-07-06 18:08:50,7, GSM0: # Manufacturer identification: 2011-07-06 18:08:50,7, GSM0: -> AT+CGMI 2011-07-06 18:08:50,7, GSM0: <- 2000" OK 2011-07-06 18:08:50,7, GSM0: # Model identification: 2011-07-06 18:08:50,7, GSM0: -> AT+CGMM 2011-07-06 18:08:51,7, GSM0: <- 1900","MODEL=C380" OK 2011-07-06 18:08:51,7, GSM0: # Revision identification: 2011-07-06 18:08:51,7, GSM0: -> AT+CGMR 2011-07-06 18:08:52,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:52,7, GSM0: <- K 2011-07-06 18:08:52,7, GSM0: # New message indications, list of supported modes: 2011-07-06 18:08:53,7, GSM0: -> AT+CNMI=? 2011-07-06 18:08:53,7, GSM0: <- OK 2011-07-06 18:08:53,7, GSM0: # New message indications, current settings: 2011-07-06 18:08:53,7, GSM0: -> AT+CNMI? 2011-07-06 18:08:54,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:54,7, GSM0: <- 2011-07-06 18:08:54,7, GSM0: # Preferred message storage, list of supported mem's: 2011-07-06 18:08:54,7, GSM0: -> AT+CPMS=? 2011-07-06 18:08:55,7, GSM0: <- +CPMS: ("MT","IM","OM","BM","DM"),("OM","DM"),("IM") OK 2011-07-06 18:08:55,7, GSM0: # Phonebook storage, available mem's: 2011-07-06 18:08:55,7, GSM0: -> AT+CPBS=? 2011-07-06 18:08:55,7, GSM0: <- +CPBS: ("ME","SM","MT","ON","DC","MC","RC","AD","QD","FD") OK 2011-07-06 18:08:55,7, GSM0: # List messages, list of supported stat's: 2011-07-06 18:08:56,7, GSM0: -> AT+CMGL=? 2011-07-06 18:08:57,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:57,7, GSM0: <- 2011-07-06 18:08:57,7, GSM0: # Delete message, list of supported values: 2011-07-06 18:08:57,7, GSM0: -> AT+CMGD=? 2011-07-06 18:08:57,7, GSM0: <- +CME ERROR: 4 (operation not supported) 2011-07-06 18:08:57,7, GSM0: # Phone activity status, list of supported stat's: 2011-07-06 18:08:57,7, GSM0: -> AT+CPAS=? 2011-07-06 18:08:59,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:08:59,7, GSM0: <- 2011-07-06 18:08:59,7, GSM0: # TE character set, list of supported charset's: 2011-07-06 18:08:59,7, GSM0: -> AT+CSCS=? 2011-07-06 18:08:59,7, GSM0: <- +CSCS: ("8859-1","ASCII","GSM","UCS2","UTF8") OK 2011-07-06 18:08:59,7, GSM0: # TE character set, current setting: 2011-07-06 18:08:59,7, GSM0: -> AT+CSCS? 2011-07-06 18:09:00,7, GSM0: <- +CSCS: "ASCII" OK 2011-07-06 18:09:00,7, GSM0: ## End of device details 2011-07-06 18:09:00,6, GSM0: Checking memory size 2011-07-06 18:09:00,7, GSM0: -> AT+CMGL=4 2011-07-06 18:09:00,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:09:10,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:09:10,7, GSM0: <- 2011-07-06 18:09:10,6, GSM0: Used memory is 0 2011-07-06 18:09:10,6, GSM0: No SMS received

With "init = ATZ"
2011-07-06 18:11:40,2, smsd: Smsd v3.1.14 started. 2011-07-06 18:11:40,2, smsd: Running as uucp:uucp. 2011-07-06 18:11:40,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.orJRdU /tmp/smsd_data.3mtAQj 2011-07-06 18:11:40,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2011-07-06 18:11:40,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2011-07-06 18:11:40,5, smsd: Outgoing file checker has started. PID: 64737. 2011-07-06 18:11:40,7, smsd: All PID's: 64737,64738 2011-07-06 18:11:40,5, GSM0: Modem handler 0 has started. PID: 64738. 2011-07-06 18:11:40,5, GSM0: Using check_memory_method 31: CMGL is used and messages are taken from the list. 2011-07-06 18:11:40,5, GSM0: Using read_timeout 1 seconds. 2011-07-06 18:11:40,6, GSM0: Checking device for incoming SMS 2011-07-06 18:11:40,6, GSM0: Checking if modem is ready 2011-07-06 18:11:40,7, GSM0: -> AT 2011-07-06 18:11:41,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:41,7, GSM0: <- OK 2011-07-06 18:11:41,6, GSM0: Pre-initializing modem 2011-07-06 18:11:41,7, GSM0: -> ATE0+CMEE=1 2011-07-06 18:11:41,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:43,7, GSM0: put_command expected (OK)|(ERROR), timeout occurred. 1. 2011-07-06 18:11:43,7, GSM0: <- 2011-07-06 18:11:43,3, GSM0: Modem did not accept the pre-init string 2011-07-06 18:11:43,6, GSM0: Initializing modem 2011-07-06 18:11:43,7, GSM0: -> ATZ 2011-07-06 18:11:43,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:44,7, GSM0: <- OK 2011-07-06 18:11:44,7, GSM0: -> AT+CSQ 2011-07-06 18:11:44,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:44,7, GSM0: <- T+CSQ +CSQ: 27,99 OK 2011-07-06 18:11:44,6, GSM0: Signal Strength Indicator: (27,99) -59 dBm (Excellent), Bit Error Rate: not known or not detecta 2011-07-06 18:11:44,6, GSM0: Selecting PDU mode 2011-07-06 18:11:44,7, GSM0: -> AT+CMGF=0 2011-07-06 18:11:45,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:45,7, GSM0: <- AT+CMGF=0 OK 2011-07-06 18:11:45,7, GSM0: -> AT+CIMI 2011-07-06 18:11:45,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:45,7, GSM0: <- AT+CIMI +CIMI: 250202200314468 OK 2011-07-06 18:11:46,7, GSM0: -> AT+CGSN 2011-07-06 18:11:46,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:46,7, GSM0: <- T+CGSN +CGSN: IMEI353113004731612 OK 2011-07-06 18:11:46,5, GSM0: CGSN: 353113004731612 2011-07-06 18:11:46,5, GSM0: IMSI: 250202200314468 2011-07-06 18:11:46,6, GSM0: Checking if reading of messages is supported 2011-07-06 18:11:46,7, GSM0: -> AT+CPMS? 2011-07-06 18:11:46,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:47,7, GSM0: <- AT+CPMS? +CPMS: "IM",0,254,"OM",0,254,"IM",0,254 OK 2011-07-06 18:11:47,7, GSM0: ## Start of device details 2011-07-06 18:11:47,7, GSM0: # Manufacturer identification: 2011-07-06 18:11:47,7, GSM0: -> AT+CGMI 2011-07-06 18:11:47,7, GSM0: <- AT+CGMI +CGMI: "Motorola CE, Copyright 2000" OK 2011-07-06 18:11:47,7, GSM0: # Model identification: 2011-07-06 18:11:47,7, GSM0: -> AT+CGMM 2011-07-06 18:11:48,7, GSM0: <- T+CGMM +CGMM: "GSM900","GSM1800","GSM1900","MODEL=C380" OK 2011-07-06 18:11:48,7, GSM0: # Revision identification: 2011-07-06 18:11:48,7, GSM0: -> AT+CGMR 2011-07-06 18:11:48,7, GSM0: <- AT+CGMR +CGMR: "R365_G_0B.D2.2FR" OK 2011-07-06 18:11:48,7, GSM0: # New message indications, list of supported modes: 2011-07-06 18:11:49,7, GSM0: -> AT+CNMI=? 2011-07-06 18:11:49,7, GSM0: <- T+CNMI=? +CNMI: (0,3),(0-2),(0),(0),(0) OK 2011-07-06 18:11:49,7, GSM0: # New message indications, current settings: 2011-07-06 18:11:49,7, GSM0: -> AT+CNMI? 2011-07-06 18:11:50,7, GSM0: <- AT+CNMI? +CNMI: 0,0,0,0,0 OK 2011-07-06 18:11:50,7, GSM0: # Preferred message storage, list of supported mem's: 2011-07-06 18:11:50,7, GSM0: -> AT+CPMS=? 2011-07-06 18:11:50,7, GSM0: <- T+CPMS=? +CPMS: ("MT","IM","OM","BM","DM"),("OM","DM"),("IM") OK 2011-07-06 18:11:50,7, GSM0: # Phonebook storage, available mem's: 2011-07-06 18:11:51,7, GSM0: -> AT+CPBS=? 2011-07-06 18:11:51,7, GSM0: <- AT+CPBS=? +CPBS: ("ME","SM","MT","ON","DC","MC","RC","AD","QD","FD") OK 2011-07-06 18:11:51,7, GSM0: # List messages, list of supported stat's: 2011-07-06 18:11:51,7, GSM0: -> AT+CMGL=? 2011-07-06 18:11:52,7, GSM0: <- AT+CMGL=? +CMGL: (0,1,2,3,4) OK 2011-07-06 18:11:52,7, GSM0: # Delete message, list of supported values: 2011-07-06 18:11:52,7, GSM0: -> AT+CMGD=? 2011-07-06 18:11:52,7, GSM0: <- AT+CMGD=? ERROR 2011-07-06 18:11:52,7, GSM0: # Phone activity status, list of supported stat's: 2011-07-06 18:11:53,7, GSM0: -> AT+CPAS=? 2011-07-06 18:11:53,7, GSM0: <- AT+CPAS=? +CPAS: (0,2-4) OK 2011-07-06 18:11:53,7, GSM0: # TE character set, list of supported charset's: 2011-07-06 18:11:53,7, GSM0: -> AT+CSCS=? 2011-07-06 18:11:54,7, GSM0: <- AT+CSCS=? +CSCS: ("8859-1","ASCII","GSM","UCS2","UTF8") OK 2011-07-06 18:11:54,7, GSM0: # TE character set, current setting: 2011-07-06 18:11:54,7, GSM0: -> AT+CSCS? 2011-07-06 18:11:55,7, GSM0: <- T+CSCS? +CSCS: "ASCII" OK 2011-07-06 18:11:55,7, GSM0: ## End of device details 2011-07-06 18:11:55,6, GSM0: Checking memory size 2011-07-06 18:11:55,7, GSM0: -> AT+CMGL=4 2011-07-06 18:11:55,7, GSM0: Command is sent, waiting for the answer 2011-07-06 18:11:55,7, GSM0: <- AT+CMGL=4 OK 2011-07-06 18:11:55,6, GSM0: Used memory is 0 2011-07-06 18:11:55,6, GSM0: No SMS received

Settings:
devices = GSM0 logfile = /var/log/smsd.log loglevel = 7 USER = uucp GROUP = dialer PIDFILE= /var/run/smsd/smsd.pid INFOFILE= /var/run/smsd/smsd.working [GSM0] device = /dev/cuaU0 baudrate = 115200 rtscts = yes init = ATZ #init = AT+CPMS="IM" loglevel_lac_ci = 8 report_device_details = yes incoming = yes report = yes send_delay = 5 read_timeout = 1 pin = ignore check_network = no check_memory_method = 31

Without "init = ATZ" SMS not received


(c) My translate

Your device is bad, if it really needs ATZ every time when communication is started. But it looks bad anyway, as it still cuts characters from echoed answers, even when ATZ was used.

You could try with the following modem settings:

pre_init = no
init = ATZ
init2 = ATE0