Operating system name and version: FreeBSD 8.0-RELEASE-p4 amd64
Version of smsd: v3.1.14
Smsd installed from: FreeBSD Ports System
Name and model of a modem / phone: Teltonika G10 USB Modem
Interface: USB

I had a working setup with a Teltonika G10 USB Modem on my FreeBSD Server. But since ive updated to v3.1.14 smstools3 isnt working anymore (have had v3.1.5 installed up to v3.1.12 which was the last version which worked for me)
I've tried the SIM Card in an ordinary phone, and its working (can make calls and send sms). I've rebooted the server, and also did an reinstall of smstools3. But nothing helped for me.

smsd.log (just an extract vom the whole log. it continues with these "MODEM IS NOT REGISTERED, WAITING 1 SEC. BEFORE RETRYING X. TIME" messages)
2010-10-17 16:08:38,2, smsd: Smsd v3.1.14 started. 2010-10-17 16:08:38,2, smsd: Running as root:wheel. 2010-10-17 16:08:38,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.xVCsvh /tmp/smsd_data.aFQKsu 2010-10-17 16:08:38,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2010-10-17 16:08:38,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2010-10-17 16:08:38,5, smsd: Outgoing file checker has started. PID: 18084. 2010-10-17 16:08:38,7, smsd: All PID's: 18084,18085 2010-10-17 16:08:38,5, GSM1: Modem handler 0 has started. PID: 18085. Will only send messages. 2010-10-17 16:08:38,6, GSM1: Checking if modem is ready 2010-10-17 16:08:39,7, GSM1: -> AT 2010-10-17 16:08:39,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:39,7, GSM1: <- AT OK 2010-10-17 16:08:39,6, GSM1: Pre-initializing modem 2010-10-17 16:08:39,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2010-10-17 16:08:39,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:40,7, GSM1: <- ATE0+CMEE=1;+CREG=2 OK 2010-10-17 16:08:40,6, GSM1: Checking if modem needs PIN 2010-10-17 16:08:40,7, GSM1: -> AT+CPIN? 2010-10-17 16:08:40,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:40,7, GSM1: <- +CPIN: READY OK 2010-10-17 16:08:40,6, GSM1: Initializing modem 2010-10-17 16:08:40,7, GSM1: -> ATZ 2010-10-17 16:08:40,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:41,7, GSM1: <- OK 2010-10-17 16:08:41,7, GSM1: -> AT+CSQ 2010-10-17 16:08:41,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:41,7, GSM1: <- AT+CSQ +CSQ: 16,99 OK 2010-10-17 16:08:41,6, GSM1: Signal Strength Indicator: (16,99) -81 dBm (Good), Bit Error Rate: not known or not detectable 2010-10-17 16:08:41,6, GSM1: Checking if Modem is registered to the network 2010-10-17 16:08:41,7, GSM1: -> AT+CREG? 2010-10-17 16:08:41,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:42,7, GSM1: <- AT+CREG? +CREG: 2,1,"01D0","2732" OK 2010-10-17 16:08:42,5, GSM1: MODEM IS NOT REGISTERED, WAITING 1 SEC. BEFORE RETRYING 1. TIME 2010-10-17 16:08:43,7, GSM1: -> AT+CSQ 2010-10-17 16:08:43,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:43,7, GSM1: <- AT+CSQ +CSQ: 16,99 OK 2010-10-17 16:08:43,5, GSM1: Signal Strength Indicator: (16,99) -81 dBm (Good), Bit Error Rate: not known or not detectable 2010-10-17 16:08:43,7, GSM1: -> AT+CREG? 2010-10-17 16:08:43,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:44,7, GSM1: <- AT+CREG? +CREG: 2,1,"01D0","2732" OK 2010-10-17 16:08:44,5, GSM1: MODEM IS NOT REGISTERED, WAITING 1 SEC. BEFORE RETRYING 2. TIME 2010-10-17 16:08:45,7, GSM1: -> AT+CSQ 2010-10-17 16:08:45,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:45,7, GSM1: <- AT+CSQ +CSQ: 16,99 OK 2010-10-17 16:08:45,5, GSM1: Signal Strength Indicator: (16,99) -81 dBm (Good), Bit Error Rate: not known or not detectable 2010-10-17 16:08:45,7, GSM1: -> AT+CREG? 2010-10-17 16:08:45,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:46,7, GSM1: <- AT+CREG? +CREG: 2,1,"01D0","2732" OK 2010-10-17 16:08:46,5, GSM1: MODEM IS NOT REGISTERED, WAITING 1 SEC. BEFORE RETRYING 3. TIME 2010-10-17 16:08:47,7, GSM1: -> AT+CSQ 2010-10-17 16:08:47,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:08:47,7, GSM1: <- AT+CSQ +CSQ: 16,99 OK 2010-10-17 16:08:47,5, GSM1: Signal Strength Indicator: (16,99) -81 dBm (Good), Bit Error Rate: not known or not detectable

smsd.conf:
# Example smsd.conf. Read the manual for a description devices = GSM1 hangup_incoming_call = yes logfile = /var/log/smsd/smsd.log loglevel = 7 [GSM1] init = ATZ device = /dev/ttyU0 incoming = no cs_convert = yes pin = 5606 report = no ## weitere Befehle zur Ausgabe von Signalstaerke und Betreiber rtscts = no regular_run_interval = 60 regular_run_cmd = AT+CREG?;+CSQ;+COPS? regular_run_statfile = /usr/local/etc/sms-stat

Anyone could help me with this? Tell me if you need more information :/

Remove the setting init = ATZ. It puts "echo ON", and this causes problem when a status of registration is checked.

I think that your modem does not require ATZ, but if it does, you have two alternative choices:

- Use init = ATZ and init2 = ATE0. This sets "echo" back to OFF.

- Use init = ATZ and loglevel_lac_ci = 8. This causes that changes of Cell ID are not monitored even when using loglevel 7.

Thanks keke for your very fast answer.

You were right, i have had to remove the "init= ATZ" string in my smsd config-file!

smsd.log:
2010-10-17 16:57:29,2, smsd: Smsd v3.1.14 started. 2010-10-17 16:57:29,2, smsd: Running as root:wheel. 2010-10-17 16:57:29,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.DLRwvI /tmp/smsd_data.Ymw18f 2010-10-17 16:57:29,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2010-10-17 16:57:29,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2010-10-17 16:57:29,5, smsd: Outgoing file checker has started. PID: 72495. 2010-10-17 16:57:29,7, smsd: All PID's: 72495,72496 2010-10-17 16:57:29,5, GSM1: Modem handler 0 has started. PID: 72496. Will only send messages. 2010-10-17 16:57:29,6, GSM1: Checking if modem is ready 2010-10-17 16:57:29,7, GSM1: -> AT 2010-10-17 16:57:29,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:30,7, GSM1: <- OK 2010-10-17 16:57:30,6, GSM1: Pre-initializing modem 2010-10-17 16:57:30,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2010-10-17 16:57:30,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:30,7, GSM1: <- OK 2010-10-17 16:57:30,6, GSM1: Checking if modem needs PIN 2010-10-17 16:57:30,7, GSM1: -> AT+CPIN? 2010-10-17 16:57:30,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:31,7, GSM1: <- +CPIN: READY OK 2010-10-17 16:57:31,7, GSM1: -> AT+CSQ 2010-10-17 16:57:31,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:31,7, GSM1: <- +CSQ: 17,99 OK 2010-10-17 16:57:31,6, GSM1: Signal Strength Indicator: (17,99) -79 dBm (Good) 2010-10-17 16:57:31,6, GSM1: Checking if Modem is registered to the network 2010-10-17 16:57:31,7, GSM1: -> AT+CREG? 2010-10-17 16:57:31,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:32,7, GSM1: <- +CREG: 2,1,"01D0","2732" OK 2010-10-17 16:57:32,6, GSM1: Modem is registered to the network 2010-10-17 16:57:32,6, GSM1: Location area code: 01D0, Cell ID: 2732 2010-10-17 16:57:32,7, GSM1: -> AT+CSQ 2010-10-17 16:57:32,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:32,7, GSM1: <- +CSQ: 17,99 OK 2010-10-17 16:57:32,6, GSM1: Signal Strength Indicator: (17,99) -79 dBm (Good) 2010-10-17 16:57:32,6, GSM1: Selecting PDU mode 2010-10-17 16:57:32,7, GSM1: -> AT+CMGF=0 2010-10-17 16:57:32,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:33,7, GSM1: <- OK 2010-10-17 16:57:33,7, GSM1: -> AT+CIMI 2010-10-17 16:57:33,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:33,7, GSM1: <- 262032731839xxx OK 2010-10-17 16:57:33,7, GSM1: -> AT+CGSN 2010-10-17 16:57:33,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:34,7, GSM1: <- 353976014101xxx OK 2010-10-17 16:57:34,5, GSM1: CGSN: 353976014101xxx 2010-10-17 16:57:34,5, GSM1: IMSI: 262032731839xxx 2010-10-17 16:57:34,5, GSM1: Waiting for messages to send... 2010-10-17 16:57:34,7, GSM1: -> AT+CREG?;+CSQ;+COPS? 2010-10-17 16:57:34,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:57:34,7, GSM1: <- +CREG: 2,1,"01D0","2732" +CSQ: 17,99 +COPS: 0,0,"E-Plus" OK 2010-10-17 16:57:34,5, GSM1: CMD: AT+CREG?;+CSQ;+COPS?: +CREG: 2,1,"01D0","2732" +CSQ: 17,99 +COPS: 0,0,"E-Plus" OK 2010-10-17 16:58:34,7, GSM1: -> AT+CREG?;+CSQ;+COPS? 2010-10-17 16:58:34,7, GSM1: Command is sent, waiting for the answer 2010-10-17 16:58:34,7, GSM1: <- +CREG: 2,1,"01D0","2732" +CSQ: 16,99 +COPS: 0,0,"E-Plus" OK 2010-10-17 16:58:34,5, GSM1: CMD: AT+CREG?;+CSQ;+COPS?: +CREG: 2,1,"01D0","2732" +CSQ: 16,99 +COPS: 0,0,"E-Plus" OK

At first I was very happy about that, but i tried to send a short message to test it, and ... it failed, with an error i cannot interpret.
Log output (i have put in some "x" on the phonenumber for privacy ):

2010-10-17 17:00:41,5, smsd: Moved file /var/spool/sms/outgoing/send_fNQlmL to /var/spool/sms/checked 2010-10-17 17:00:43,6, GSM1: I have to send 1 short message for /var/spool/sms/checked/send_fNQlmL 2010-10-17 17:00:43,6, GSM1: Sending SMS from to 4916097699xxx 2010-10-17 17:00:43,6, GSM1: Checking if modem is ready 2010-10-17 17:00:43,7, GSM1: -> AT 2010-10-17 17:00:43,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:00:43,7, GSM1: <- OK 2010-10-17 17:00:43,6, GSM1: Pre-initializing modem 2010-10-17 17:00:43,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2010-10-17 17:00:43,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:00:44,7, GSM1: <- OK 2010-10-17 17:00:44,6, GSM1: Checking if modem needs PIN 2010-10-17 17:00:44,7, GSM1: -> AT+CPIN? 2010-10-17 17:00:44,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:00:44,7, GSM1: <- +CPIN: READY OK 2010-10-17 17:00:45,7, GSM1: -> AT+CSQ 2010-10-17 17:00:45,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:00:45,7, GSM1: <- +CSQ: 17,99 OK 2010-10-17 17:00:45,6, GSM1: Signal Strength Indicator: (17,99) -79 dBm (Good) 2010-10-17 17:00:45,6, GSM1: Checking if Modem is registered to the network 2010-10-17 17:00:45,7, GSM1: -> AT+CREG? 2010-10-17 17:00:45,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:00:45,7, GSM1: <- +CREG: 2,1,"01D0","2732" OK 2010-10-17 17:00:45,6, GSM1: Modem is registered to the network 2010-10-17 17:00:45,6, GSM1: Selecting PDU mode 2010-10-17 17:00:46,7, GSM1: -> AT+CMGF=0 2010-10-17 17:00:46,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:00:46,7, GSM1: <- OK 2010-10-17 17:00:46,7, GSM1: -> AT+CMGS=36 2010-10-17 17:00:46,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:00:46,7, GSM1: <- > 2010-10-17 17:00:47,7, GSM1: -> 0011000D91946190679xxxxx000FF18C539885EA7BB40C539E85C46D343A06C1A0E4F9743^Z 2010-10-17 17:00:47,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:00:50,7, GSM1: <- +CME ERROR: 100 > +CME ERROR: 100 > +CME ERROR: 100 > +CMS ERROR: 303 > +CMS ERROR: 303 > +CMS ERROR: 303 > +CME ERROR: 100 > +CME ERROR: 100 > +CMS ERROR: 303 > +CMS$ 2010-10-17 17:00:50,3, GSM1: The modem answer was not OK: +CME ERROR: 100> +CME ERROR: 100> +CME ERROR: 100> +CMS ERROR: 303> +CMS ERROR: 303> +CMS ERROR: 303> +CME ERROR: 100> +CME ERROR: 100> +CMS ERROR: 3$ 2010-10-17 17:00:50,5, GSM1: Waiting 10 sec. before retrying 2010-10-17 17:01:00,6, GSM1: Checking if modem is ready 2010-10-17 17:01:05,2, smsd: Smsd mainprocess is awaiting the termination of all modem handlers. PID: 72495. 2010-10-17 17:01:05,3, GSM1: Unexpected input: R: 303 > +CMS ERROR: 303 > +CME ERROR: 100 > +CME ERROR: 100 > +CMS ERROR: 303 > +CMS ERROR: 303 > +CMS ERROR: 303 > +CME ERROR: 100 > +CME ERROR: 100 >$ 2010-10-17 17:01:05,7, GSM1: -> AT 2010-10-17 17:01:05,7, GSM1: Command is sent, waiting for the answer 2010-10-17 17:01:05,7, GSM1: put_command expected (OK)|(ERROR), timeout occurred. 1. 2010-10-17 17:01:05,7, GSM1: <- 2010-10-17 17:01:05,4, GSM1: Sending SMS to 4916097699xxx failed, trying time 22 sec. Retries: 0. 2010-10-17 17:01:05,6, GSM1: Deleted file /var/spool/sms/checked/send_fNQlmL 2010-10-17 17:01:05,2, GSM1: Modem handler 0 terminated. PID: 72496, was started 10-10-17 16:57:29. 2010-10-17 17:01:05,2, smsd: Smsd mainprocess terminated. PID 72495, was started 10-10-17 16:57:29.

So, what now?

Very strange... First everything is ok, but after the PDU is sent, the modem goes wild. CME 100 means "unknown" and CMS 303 means "operation not supported". But it does not make any sense that those errors are repeated and there is also prompt (>) characters repeated. It looks like the modem crashed.

Why did you initially use that ATZ command in the initialization? Perhaps it still has something to do with this issue?

After 3.1.12 there are no any remarkable changes which may cause this kind of a problem. Checking for status of registration is different, but should not cause this. In the smsd.conf you could try with loglevel_lac_ci = 8, if it makes any difference. If the problem still remains, keep that settings and put init = ATZ back and retry.

I got the whole config from an FAQ on a german webshop, where i have bought this modem. So i did not think just a second about this, took the config and modified it, that this will be working under FreeBSD.

Hm, because you said "it looks like the modem crashed" i went to the server and unplugged the modem und plugged it in again. Then i started smsd and now it is working again! So smsd is starting correctly + SMS could be successfully been sent. Seems to be "fixed" for me. I dont know why this config has been working for me for versions of smstools older than v3.1.14.

My smsd.conf looks like this now:
# Example smsd.conf. Read the manual for a description devices = GSM1 hangup_incoming_call = yes logfile = /var/log/smsd/smsd.log loglevel = 7 [GSM1] device = /dev/ttyU0 incoming = no cs_convert = yes pin = 5605 report = no ## fuer Nagios, weitere Befehle zur Ausgabe von Signalstaerke und Betreiber rtscts = no regular_run_interval = 60 regular_run_cmd = AT+CREG?;+CSQ;+COPS? regular_run_statfile = /usr/local/etc/sms-stat signal_quality_ber_ignore = yes

Thank you very very much for help! Keep up the good work!