SMS Server Tools 3
 Menu
Basic information:
Additional information:
Support:
Get SMS Server Tools 3:
Additional Options

 Sponsored links

 Search
Custom Search

 Visitor locations
 
 SMS Server Tools 3 Community
Welcome, Guest. The forum is currently read-only, but will open soon. Fri Aug 29, 2025 19:08
SMSTools3 Community » Search Bottom

Page:  1

Keywords:
Mode: All keywords (AND)
ljubex: Here are some results. With my current modem (ZTE, K3565-Z, vendor:product = 19d2:0063) single-step at+cmgs does NOT work. However, when I put small delay between command string and pdu string supply (without waiting for prompt) it actually works (delay is aproximately 1-10ms). So, maybe it would be a good idea to put this delay as a parameter (in milliseconds), to be used on end user's own risk? :)
ljubex: This really helped !! :) I mean: detect_unexpected_input = no Performances are now almost perfect, the ony remaining difference is waiting for prompt character. Anyway, here is my current config: devices = GSM1,GSM2 outgoing = /var/spool/sms/outgoing checked = /var/spool/sms/checked incoming = /var/spool/sms/incoming logfile = /var/log/smstools/smsd.log infofile = /var/run/smstools/smsd.working pidfile = /var/run/smstools/smsd.pid failed = /var/spool/sms/failed #sent = /var/spool/sms/sent stats = /var/log/smstools/smsd_stats loglevel = 7 logtime_ms = 1 keep_filename = no receive_before_send = no autosplit = 3 max_continuous_sending = 7200 [queues] GSM1 = /var/spool/sms/GSM1 GSM2 = /var/spool/sms/GSM2 [GSM1] device = /dev/ttyUSB8 init = at+cmms=2 incoming = no mode = new baudrate = 115200 report = no sending_disabled = no detect_unexpected_input = no queues = GSM1 [GSM2] device = /dev/ttyUSB18 init = at+cmms=2 incoming = no mode = new baudrate = 115200 report = no sending_disabled = no detect_unexpected_input = no queues = GSM2 Regarding the names, fast_mode is pretty much self explaining :) And prompt waiting ... maybe it's not necessary at all if it causes more trouble than benefit. I'll thest several cases by script and I'll show the results ;)
ljubex: Why it takes ~200ms between these two log points? 2011-03-10 11:03:54.472,6, GSM1: Sending SMS from to 38163440734 2011-03-10 11:03:54.672,7, GSM1: -> AT+CMMS=2;+CMGS=26 It's from point where smsd says "I'm about to send command" to "I've sent command", right?
ljubex: First scenario (cmms disabled completely): 2011-03-10 10:59:47.181,2, smsd: Smsd v3.1.14 started. 2011-03-10 10:59:47.181,2, smsd: Running as smsd:dialout. 2011-03-10 10:59:47.182,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.coHCbX /tmp/smsd_data.C7PE27 2011-03-10 10:59:47.184,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2011-03-10 10:59:47.185,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2011-03-10 10:59:47.185,6, smsd: Using logtime format "%Y-%m-%d %H:%M:%S.timems". It produces "2011-03-10 10:59:47.185". 2011-03-10 10:59:47.193,5, smsd: Outgoing file checker has started. PID: 12273. 2011-03-10 10:59:47.193,7, smsd: All PID's: 12273,12274,12275 2011-03-10 10:59:47.198,5, GSM1: Modem handler 0 has started. PID: 12274. Will only send messages. 2011-03-10 10:59:47.206,6, GSM1: Checking if modem is ready 2011-03-10 10:59:47.208,5, GSM2: Modem handler 1 has started. PID: 12275. Will only send messages. 2011-03-10 10:59:47.216,6, GSM2: Checking if modem is ready 2011-03-10 10:59:47.607,3, GSM1: Unexpected input: +CME ERROR: unknown 2011-03-10 10:59:47.607,7, GSM1: -> AT 2011-03-10 10:59:47.607,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:47.616,3, GSM2: Unexpected input: +CME ERROR: unknown 2011-03-10 10:59:47.616,7, GSM2: -> AT 2011-03-10 10:59:47.617,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:47.907,7, GSM1: <- OK 2011-03-10 10:59:47.908,6, GSM1: Pre-initializing modem 2011-03-10 10:59:47.917,7, GSM2: <- OK 2011-03-10 10:59:47.917,6, GSM2: Pre-initializing modem 2011-03-10 10:59:48.108,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-10 10:59:48.109,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:48.118,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-10 10:59:48.118,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:48.409,7, GSM1: <- OK 2011-03-10 10:59:48.409,6, GSM1: Checking if modem needs PIN 2011-03-10 10:59:48.418,7, GSM2: <- OK 2011-03-10 10:59:48.418,6, GSM2: Checking if modem needs PIN 2011-03-10 10:59:48.610,7, GSM1: -> AT+CPIN? 2011-03-10 10:59:48.610,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:48.618,7, GSM2: -> AT+CPIN? 2011-03-10 10:59:48.619,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:48.910,7, GSM1: <- +CPIN: READY OK 2011-03-10 10:59:48.919,7, GSM2: <- +CPIN: READY OK 2011-03-10 10:59:49.110,7, GSM1: -> AT+CSQ 2011-03-10 10:59:49.111,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:49.120,7, GSM2: -> AT+CSQ 2011-03-10 10:59:49.120,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:49.411,7, GSM1: <- +CSQ: 13,99 OK 2011-03-10 10:59:49.411,6, GSM1: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 10:59:49.411,6, GSM1: Checking if Modem is registered to the network 2011-03-10 10:59:49.420,7, GSM2: <- +CSQ: 12,99 OK 2011-03-10 10:59:49.420,6, GSM2: Signal Strength Indicator: (12,99) -89 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 10:59:49.420,6, GSM2: Checking if Modem is registered to the network 2011-03-10 10:59:49.612,7, GSM1: -> AT+CREG? 2011-03-10 10:59:49.612,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:49.621,7, GSM2: -> AT+CREG? 2011-03-10 10:59:49.621,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:49.912,7, GSM1: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-10 10:59:49.912,6, GSM1: Modem is registered to the network 2011-03-10 10:59:49.912,6, GSM1: Location area code: 4ee9, Cell ID: 4c83 2011-03-10 10:59:49.921,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-10 10:59:49.921,6, GSM2: Modem is registered to the network 2011-03-10 10:59:49.921,6, GSM2: Location area code: 4ee9, Cell ID: 4c83 2011-03-10 10:59:50.113,7, GSM1: -> AT+CSQ 2011-03-10 10:59:50.114,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:50.121,7, GSM2: -> AT+CSQ 2011-03-10 10:59:50.122,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:50.414,7, GSM1: <- +CSQ: 13,99 OK 2011-03-10 10:59:50.414,6, GSM1: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 10:59:50.414,6, GSM1: Selecting PDU mode 2011-03-10 10:59:50.422,7, GSM2: <- +CSQ: 12,99 OK 2011-03-10 10:59:50.422,6, GSM2: Signal Strength Indicator: (12,99) -89 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 10:59:50.422,6, GSM2: Selecting PDU mode 2011-03-10 10:59:50.614,7, GSM1: -> AT+CMGF=0 2011-03-10 10:59:50.615,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:50.622,7, GSM2: -> AT+CMGF=0 2011-03-10 10:59:50.623,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:50.915,7, GSM1: <- OK 2011-03-10 10:59:50.923,7, GSM2: <- OK 2011-03-10 10:59:51.116,7, GSM1: -> AT+CIMI 2011-03-10 10:59:51.117,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:51.123,7, GSM2: -> AT+CIMI 2011-03-10 10:59:51.124,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:51.417,7, GSM1: <- 220033000184106 OK 2011-03-10 10:59:51.424,7, GSM2: <- 220033000184412 OK 2011-03-10 10:59:51.618,7, GSM1: -> AT+CGSN 2011-03-10 10:59:51.618,7, GSM1: Command is sent, waiting for the answer 2011-03-10 10:59:51.624,7, GSM2: -> AT+CGSN 2011-03-10 10:59:51.625,7, GSM2: Command is sent, waiting for the answer 2011-03-10 10:59:51.918,7, GSM1: <- 353462043327001 OK 2011-03-10 10:59:51.918,5, GSM1: CGSN: 353462043327001 2011-03-10 10:59:51.918,5, GSM1: IMSI: 220033000184106 2011-03-10 10:59:51.918,5, GSM1: Waiting for messages to send... 2011-03-10 10:59:51.925,7, GSM2: <- 353462043304570 OK 2011-03-10 10:59:51.925,5, GSM2: CGSN: 353462043304570 2011-03-10 10:59:51.925,5, GSM2: IMSI: 220033000184412 2011-03-10 10:59:51.925,5, GSM2: Waiting for messages to send... 2011-03-10 11:00:12.035,7, GSM1: Took 0.0 seconds to get a file /var/spool/sms/GSM1/msgb_00.txt, lost 0 times, 3 SMS files and 0 LOCK files seen. 2011-03-10 11:00:12.035,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_00.txt 2011-03-10 11:00:12.035,6, GSM1: Sending SMS from to 38163440734 2011-03-10 11:00:12.035,6, GSM1: Checking if modem is ready 2011-03-10 11:00:12.083,7, GSM2: Took 0.1 seconds to get a file /var/spool/sms/GSM2/msgb_00.txt, lost 0 times, 3 SMS files and 0 LOCK files seen. 2011-03-10 11:00:12.083,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_00.txt 2011-03-10 11:00:12.083,6, GSM2: Sending SMS from to 38163440734 2011-03-10 11:00:12.083,6, GSM2: Checking if modem is ready 2011-03-10 11:00:12.235,7, GSM1: -> AT 2011-03-10 11:00:12.236,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:12.283,7, GSM2: -> AT 2011-03-10 11:00:12.284,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:12.536,7, GSM1: <- OK 2011-03-10 11:00:12.536,6, GSM1: Pre-initializing modem 2011-03-10 11:00:12.584,7, GSM2: <- OK 2011-03-10 11:00:12.584,6, GSM2: Pre-initializing modem 2011-03-10 11:00:12.737,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-10 11:00:12.738,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:12.785,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-10 11:00:12.785,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:13.038,7, GSM1: <- OK 2011-03-10 11:00:13.085,7, GSM2: <- OK 2011-03-10 11:00:13.239,7, GSM1: -> AT+CSQ 2011-03-10 11:00:13.239,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:13.285,7, GSM2: -> AT+CSQ 2011-03-10 11:00:13.286,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:13.539,7, GSM1: <- +CSQ: 13,99 OK 2011-03-10 11:00:13.539,6, GSM1: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 11:00:13.539,6, GSM1: Checking if Modem is registered to the network 2011-03-10 11:00:13.586,7, GSM2: <- +CSQ: 12,99 OK 2011-03-10 11:00:13.586,6, GSM2: Signal Strength Indicator: (12,99) -89 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 11:00:13.586,6, GSM2: Checking if Modem is registered to the network 2011-03-10 11:00:13.740,7, GSM1: -> AT+CREG? 2011-03-10 11:00:13.740,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:13.787,7, GSM2: -> AT+CREG? 2011-03-10 11:00:13.788,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:14.040,7, GSM1: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-10 11:00:14.040,6, GSM1: Modem is registered to the network 2011-03-10 11:00:14.040,6, GSM1: Selecting PDU mode 2011-03-10 11:00:14.088,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-10 11:00:14.088,6, GSM2: Modem is registered to the network 2011-03-10 11:00:14.088,6, GSM2: Selecting PDU mode 2011-03-10 11:00:14.241,7, GSM1: -> AT+CMGF=0 2011-03-10 11:00:14.242,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:14.289,7, GSM2: -> AT+CMGF=0 2011-03-10 11:00:14.289,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:14.542,7, GSM1: <- OK 2011-03-10 11:00:14.589,7, GSM2: <- OK 2011-03-10 11:00:14.742,7, GSM1: -> AT+CMMS=0 2011-03-10 11:00:14.743,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:14.789,7, GSM2: -> AT+CMMS=0 2011-03-10 11:00:14.790,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:15.043,7, GSM1: <- OK 2011-03-10 11:00:15.090,7, GSM2: <- OK 2011-03-10 11:00:15.243,7, GSM1: -> AT+CMGS=26 2011-03-10 11:00:15.244,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:15.290,7, GSM2: -> AT+CMGS=26 2011-03-10 11:00:15.291,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:15.544,7, GSM1: <- > 2011-03-10 11:00:15.591,7, GSM2: <- > 2011-03-10 11:00:15.744,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:00:15.745,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:15.791,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:00:15.792,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:17.794,7, GSM2: <- +CMGS: 0 OK 2011-03-10 11:00:17.794,5, GSM2: SMS sent, Message_id: 0, To: 38163440734, sending time 5 sec. 2011-03-10 11:00:17.794,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_00.txt 2011-03-10 11:00:17.847,7, GSM2: Took 0.0 seconds to get a file /var/spool/sms/GSM2/msgb_01.txt, lost 0 times, 2 SMS files and 0 LOCK files seen. 2011-03-10 11:00:17.847,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_01.txt 2011-03-10 11:00:17.847,6, GSM2: Sending SMS from to 38163440734 2011-03-10 11:00:18.047,7, GSM1: <- +CMGS: 0 OK 2011-03-10 11:00:18.047,5, GSM1: SMS sent, Message_id: 0, To: 38163440734, sending time 6 sec. 2011-03-10 11:00:18.047,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_00.txt 2011-03-10 11:00:18.047,7, GSM2: -> AT+CMGS=26 2011-03-10 11:00:18.049,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:18.103,7, GSM1: Took 0.0 seconds to get a file /var/spool/sms/GSM1/msgb_01.txt, lost 0 times, 2 SMS files and 0 LOCK files seen. 2011-03-10 11:00:18.103,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_01.txt 2011-03-10 11:00:18.103,6, GSM1: Sending SMS from to 38163440734 2011-03-10 11:00:18.303,7, GSM1: -> AT+CMGS=26 2011-03-10 11:00:18.304,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:18.349,7, GSM2: <- > 2011-03-10 11:00:18.549,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:00:18.550,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:18.604,7, GSM1: <- > 2011-03-10 11:00:18.804,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:00:18.805,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:20.651,7, GSM2: <- +CMGS: 1 OK 2011-03-10 11:00:20.651,5, GSM2: SMS sent, Message_id: 1, To: 38163440734, sending time 3 sec. 2011-03-10 11:00:20.652,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_01.txt 2011-03-10 11:00:20.711,7, GSM2: Took 0.0 seconds to get a file /var/spool/sms/GSM2/msgb_02.txt, lost 0 times, 1 SMS files and 0 LOCK files seen. 2011-03-10 11:00:20.711,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_02.txt 2011-03-10 11:00:20.711,6, GSM2: Sending SMS from to 38163440734 2011-03-10 11:00:20.911,7, GSM2: -> AT+CMGS=26 2011-03-10 11:00:20.911,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:21.212,7, GSM2: <- > 2011-03-10 11:00:21.412,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:00:21.412,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:00:23.108,7, GSM1: <- +CMGS: 1 OK 2011-03-10 11:00:23.108,5, GSM1: SMS sent, Message_id: 1, To: 38163440734, sending time 5 sec. 2011-03-10 11:00:23.109,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_01.txt 2011-03-10 11:00:23.159,7, GSM1: Took 0.0 seconds to get a file /var/spool/sms/GSM1/msgb_02.txt, lost 0 times, 1 SMS files and 0 LOCK files seen. 2011-03-10 11:00:23.159,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_02.txt 2011-03-10 11:00:23.159,6, GSM1: Sending SMS from to 38163440734 2011-03-10 11:00:23.359,7, GSM1: -> AT+CMGS=26 2011-03-10 11:00:23.359,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:23.516,7, GSM2: <- +CMGS: 2 OK 2011-03-10 11:00:23.516,5, GSM2: SMS sent, Message_id: 2, To: 38163440734, sending time 3 sec. 2011-03-10 11:00:23.516,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_02.txt 2011-03-10 11:00:23.660,7, GSM1: <- > 2011-03-10 11:00:23.863,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:00:23.863,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:00:25.965,7, GSM1: <- +CMGS: 2 OK 2011-03-10 11:00:25.965,5, GSM1: SMS sent, Message_id: 2, To: 38163440734, sending time 2 sec. 2011-03-10 11:00:25.965,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_02.txt 2011-03-10 11:01:02.954,2, smsd: Smsd mainprocess is awaiting the termination of all modem handlers. PID: 12273. 2011-03-10 11:01:02.954,2, GSM1: Modem handler 0 terminated. PID: 12274, was started 11-03-10 10:59:47. 2011-03-10 11:01:02.954,2, GSM2: Modem handler 1 terminated. PID: 12275, was started 11-03-10 10:59:47. 2011-03-10 11:01:02.955,2, smsd: Smsd mainprocess terminated. PID 12273, was started 11-03-10 10:59:47. Second scenario (cmms set every time): 2011-03-10 11:03:22.911,2, smsd: Smsd v3.1.14 started. 2011-03-10 11:03:22.911,2, smsd: Running as smsd:dialout. 2011-03-10 11:03:22.913,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.ftp127 /tmp/smsd_data.FPXZy8 2011-03-10 11:03:22.915,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2011-03-10 11:03:22.915,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2011-03-10 11:03:22.915,6, smsd: Using logtime format "%Y-%m-%d %H:%M:%S.timems". It produces "2011-03-10 11:03:22.915". 2011-03-10 11:03:22.916,5, GSM1: Modem handler 0 has started. PID: 14093. Will only send messages. 2011-03-10 11:03:22.916,5, smsd: Outgoing file checker has started. PID: 14092. 2011-03-10 11:03:22.916,7, smsd: All PID's: 14092,14093,14094 2011-03-10 11:03:22.916,5, GSM2: Modem handler 1 has started. PID: 14094. Will only send messages. 2011-03-10 11:03:22.923,6, GSM2: Checking if modem is ready 2011-03-10 11:03:22.923,6, GSM1: Checking if modem is ready 2011-03-10 11:03:23.123,7, GSM1: -> AT 2011-03-10 11:03:23.124,7, GSM2: -> AT 2011-03-10 11:03:23.124,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:23.124,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:23.424,7, GSM1: <- OK 2011-03-10 11:03:23.424,6, GSM1: Pre-initializing modem 2011-03-10 11:03:23.425,7, GSM2: <- OK 2011-03-10 11:03:23.425,6, GSM2: Pre-initializing modem 2011-03-10 11:03:23.625,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-10 11:03:23.625,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-10 11:03:23.625,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:23.625,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:23.925,7, GSM2: <- OK 2011-03-10 11:03:23.925,6, GSM2: Checking if modem needs PIN 2011-03-10 11:03:23.925,7, GSM1: <- OK 2011-03-10 11:03:23.926,6, GSM1: Checking if modem needs PIN 2011-03-10 11:03:24.126,7, GSM2: -> AT+CPIN? 2011-03-10 11:03:24.126,7, GSM1: -> AT+CPIN? 2011-03-10 11:03:24.127,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:24.127,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:24.427,7, GSM1: <- +CPIN: READY OK 2011-03-10 11:03:24.428,7, GSM2: <- +CPIN: READY OK 2011-03-10 11:03:24.628,7, GSM1: -> AT+CSQ 2011-03-10 11:03:24.629,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:24.632,7, GSM2: -> AT+CSQ 2011-03-10 11:03:24.633,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:24.929,7, GSM1: <- +CSQ: 13,99 OK 2011-03-10 11:03:24.929,6, GSM1: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 11:03:24.929,6, GSM1: Checking if Modem is registered to the network 2011-03-10 11:03:24.933,7, GSM2: <- +CSQ: 12,99 OK 2011-03-10 11:03:24.933,6, GSM2: Signal Strength Indicator: (12,99) -89 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 11:03:24.933,6, GSM2: Checking if Modem is registered to the network 2011-03-10 11:03:25.130,7, GSM1: -> AT+CREG? 2011-03-10 11:03:25.130,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:25.133,7, GSM2: -> AT+CREG? 2011-03-10 11:03:25.134,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:25.430,7, GSM1: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-10 11:03:25.430,6, GSM1: Modem is registered to the network 2011-03-10 11:03:25.430,6, GSM1: Location area code: 4ee9, Cell ID: 4c83 2011-03-10 11:03:25.434,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-10 11:03:25.434,6, GSM2: Modem is registered to the network 2011-03-10 11:03:25.434,6, GSM2: Location area code: 4ee9, Cell ID: 4c83 2011-03-10 11:03:25.630,7, GSM1: -> AT+CSQ 2011-03-10 11:03:25.631,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:25.634,7, GSM2: -> AT+CSQ 2011-03-10 11:03:25.635,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:25.931,7, GSM1: <- +CSQ: 13,99 OK 2011-03-10 11:03:25.931,6, GSM1: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 11:03:25.931,6, GSM1: Selecting PDU mode 2011-03-10 11:03:25.935,7, GSM2: <- +CSQ: 12,99 OK 2011-03-10 11:03:25.935,6, GSM2: Signal Strength Indicator: (12,99) -89 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 11:03:25.935,6, GSM2: Selecting PDU mode 2011-03-10 11:03:26.132,7, GSM1: -> AT+CMGF=0 2011-03-10 11:03:26.132,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:26.136,7, GSM2: -> AT+CMGF=0 2011-03-10 11:03:26.136,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:26.432,7, GSM1: <- OK 2011-03-10 11:03:26.436,7, GSM2: <- OK 2011-03-10 11:03:26.633,7, GSM1: -> AT+CIMI 2011-03-10 11:03:26.633,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:26.636,7, GSM2: -> AT+CIMI 2011-03-10 11:03:26.637,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:26.933,7, GSM1: <- 220033000184106 OK 2011-03-10 11:03:26.937,7, GSM2: <- 220033000184412 OK 2011-03-10 11:03:27.134,7, GSM1: -> AT+CGSN 2011-03-10 11:03:27.134,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:27.137,7, GSM2: -> AT+CGSN 2011-03-10 11:03:27.138,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:27.434,7, GSM1: <- 353462043327001 OK 2011-03-10 11:03:27.434,5, GSM1: CGSN: 353462043327001 2011-03-10 11:03:27.434,5, GSM1: IMSI: 220033000184106 2011-03-10 11:03:27.434,5, GSM1: Waiting for messages to send... 2011-03-10 11:03:27.438,7, GSM2: <- 353462043304570 OK 2011-03-10 11:03:27.438,5, GSM2: CGSN: 353462043304570 2011-03-10 11:03:27.438,5, GSM2: IMSI: 220033000184412 2011-03-10 11:03:27.438,5, GSM2: Waiting for messages to send... 2011-03-10 11:03:47.524,7, GSM1: Took 0.0 seconds to get a file /var/spool/sms/GSM1/msgb_00.txt, lost 0 times, 3 SMS files and 0 LOCK files seen. 2011-03-10 11:03:47.524,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_00.txt 2011-03-10 11:03:47.524,6, GSM1: Sending SMS from to 38163440734 2011-03-10 11:03:47.524,6, GSM1: Checking if modem is ready 2011-03-10 11:03:47.560,7, GSM2: Took 0.0 seconds to get a file /var/spool/sms/GSM2/msgb_00.txt, lost 0 times, 3 SMS files and 0 LOCK files seen. 2011-03-10 11:03:47.560,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_00.txt 2011-03-10 11:03:47.560,6, GSM2: Sending SMS from to 38163440734 2011-03-10 11:03:47.560,6, GSM2: Checking if modem is ready 2011-03-10 11:03:47.724,7, GSM1: -> AT 2011-03-10 11:03:47.725,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:47.760,7, GSM2: -> AT 2011-03-10 11:03:47.761,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:48.027,7, GSM1: <- OK 2011-03-10 11:03:48.027,6, GSM1: Pre-initializing modem 2011-03-10 11:03:48.061,7, GSM2: <- OK 2011-03-10 11:03:48.061,6, GSM2: Pre-initializing modem 2011-03-10 11:03:48.227,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-10 11:03:48.228,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:48.261,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-10 11:03:48.262,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:48.528,7, GSM1: <- OK 2011-03-10 11:03:48.562,7, GSM2: <- OK 2011-03-10 11:03:48.728,7, GSM1: -> AT+CSQ 2011-03-10 11:03:48.729,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:48.762,7, GSM2: -> AT+CSQ 2011-03-10 11:03:48.763,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:49.029,7, GSM1: <- +CSQ: 13,99 OK 2011-03-10 11:03:49.029,6, GSM1: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 11:03:49.029,6, GSM1: Checking if Modem is registered to the network 2011-03-10 11:03:49.063,7, GSM2: <- +CSQ: 12,99 OK 2011-03-10 11:03:49.063,6, GSM2: Signal Strength Indicator: (12,99) -89 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-10 11:03:49.063,6, GSM2: Checking if Modem is registered to the network 2011-03-10 11:03:49.229,7, GSM1: -> AT+CREG? 2011-03-10 11:03:49.230,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:49.263,7, GSM2: -> AT+CREG? 2011-03-10 11:03:49.264,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:49.530,7, GSM1: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-10 11:03:49.530,6, GSM1: Modem is registered to the network 2011-03-10 11:03:49.530,6, GSM1: Selecting PDU mode 2011-03-10 11:03:49.564,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-10 11:03:49.564,6, GSM2: Modem is registered to the network 2011-03-10 11:03:49.564,6, GSM2: Selecting PDU mode 2011-03-10 11:03:49.730,7, GSM1: -> AT+CMGF=0 2011-03-10 11:03:49.731,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:49.764,7, GSM2: -> AT+CMGF=0 2011-03-10 11:03:49.765,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:50.031,7, GSM1: <- OK 2011-03-10 11:03:50.065,7, GSM2: <- OK 2011-03-10 11:03:50.231,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-10 11:03:50.232,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:50.265,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-10 11:03:50.266,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:50.532,7, GSM1: <- > 2011-03-10 11:03:50.566,7, GSM2: <- > 2011-03-10 11:03:50.733,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:03:50.733,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:50.766,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:03:50.767,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:52.768,7, GSM2: <- +CMGS: 3 OK 2011-03-10 11:03:52.768,5, GSM2: SMS sent, Message_id: 3, To: 38163440734, sending time 5 sec. 2011-03-10 11:03:52.769,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_00.txt 2011-03-10 11:03:52.819,7, GSM2: Took 0.0 seconds to get a file /var/spool/sms/GSM2/msgb_01.txt, lost 0 times, 2 SMS files and 0 LOCK files seen. 2011-03-10 11:03:52.819,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_01.txt 2011-03-10 11:03:52.819,6, GSM2: Sending SMS from to 38163440734 2011-03-10 11:03:52.835,7, GSM1: <- +CMGS: 3 OK 2011-03-10 11:03:52.835,5, GSM1: SMS sent, Message_id: 3, To: 38163440734, sending time 5 sec. 2011-03-10 11:03:52.835,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_00.txt 2011-03-10 11:03:52.891,7, GSM1: Took 0.0 seconds to get a file /var/spool/sms/GSM1/msgb_01.txt, lost 0 times, 2 SMS files and 0 LOCK files seen. 2011-03-10 11:03:52.891,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_01.txt 2011-03-10 11:03:52.891,6, GSM1: Sending SMS from to 38163440734 2011-03-10 11:03:53.019,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-10 11:03:53.020,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:53.091,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-10 11:03:53.092,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:53.320,7, GSM2: <- > 2011-03-10 11:03:53.392,7, GSM1: <- > 2011-03-10 11:03:53.520,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:03:53.521,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:53.592,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:03:53.593,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:54.321,7, GSM2: <- +CMGS: 4 OK 2011-03-10 11:03:54.321,5, GSM2: SMS sent, Message_id: 4, To: 38163440734, sending time 2 sec. 2011-03-10 11:03:54.322,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_01.txt 2011-03-10 11:03:54.371,7, GSM2: Took 0.0 seconds to get a file /var/spool/sms/GSM2/msgb_02.txt, lost 0 times, 1 SMS files and 0 LOCK files seen. 2011-03-10 11:03:54.371,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_02.txt 2011-03-10 11:03:54.371,6, GSM2: Sending SMS from to 38163440734 2011-03-10 11:03:54.394,7, GSM1: <- +CMGS: 4 OK 2011-03-10 11:03:54.394,5, GSM1: SMS sent, Message_id: 4, To: 38163440734, sending time 2 sec. 2011-03-10 11:03:54.394,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_01.txt 2011-03-10 11:03:54.472,7, GSM1: Took 0.0 seconds to get a file /var/spool/sms/GSM1/msgb_02.txt, lost 0 times, 1 SMS files and 0 LOCK files seen. 2011-03-10 11:03:54.472,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_02.txt 2011-03-10 11:03:54.472,6, GSM1: Sending SMS from to 38163440734 2011-03-10 11:03:54.571,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-10 11:03:54.572,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:54.672,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-10 11:03:54.673,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:54.872,7, GSM2: <- > 2011-03-10 11:03:54.973,7, GSM1: <- > 2011-03-10 11:03:55.072,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:03:55.073,7, GSM2: Command is sent, waiting for the answer 2011-03-10 11:03:55.173,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-10 11:03:55.174,7, GSM1: Command is sent, waiting for the answer 2011-03-10 11:03:55.873,7, GSM2: <- +CMGS: 5 OK 2011-03-10 11:03:55.873,5, GSM2: SMS sent, Message_id: 5, To: 38163440734, sending time 1 sec. 2011-03-10 11:03:55.874,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_02.txt 2011-03-10 11:03:55.974,7, GSM1: <- +CMGS: 5 OK 2011-03-10 11:03:55.974,5, GSM1: SMS sent, Message_id: 5, To: 38163440734, sending time 1 sec. 2011-03-10 11:03:55.975,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_02.txt 2011-03-10 11:04:17.063,2, smsd: Smsd mainprocess is awaiting the termination of all modem handlers. PID: 14092. 2011-03-10 11:04:17.063,2, GSM1: Modem handler 0 terminated. PID: 14093, was started 11-03-10 11:03:22. 2011-03-10 11:04:17.063,2, GSM2: Modem handler 1 terminated. PID: 14094, was started 11-03-10 11:03:22. 2011-03-10 11:04:17.064,2, smsd: Smsd mainprocess terminated. PID 14092, was started 11-03-10 11:03:22. The difference is pretty obvious even by naked eye :) And regarding cmgs, take a look into this manual for ZTE modem family: http://download.maritex.com.pl/pdfs/wi/MG2636at.pdf Here, and on many other manuals I saw cmgs documented as: AT+CMGS=<length><CR> <pdu><Ctrl-Z/ESC> So, only important thing is <CR> in a string. I believe that ">" is sent unsyncronously from modem side, and input buffer of a modem is not affected. But of course this looks like strongly modem-dependent issue. I have several different types of modems with me, when I perform tests with ">" on all of them I'll come up with information. Meanwhile ... is there a way to store a bunch of pdu's on modem (in order to save time), and later to trigger sending, message by message? I mean not by cmgw but with some burst-like command?
ljubex: Ah sorry, now I saw a typo in post :) It's ok in my smsd.conf ;) I'll have test results soon, and yes, there is a line: trust_spool = no so I'll test without it.
ljubex: In many at command references I saw that at+cmgs is documented as "single step" command, meaning that <CR> character and PDU are supplied together with at+cmgs. That would save 300ms in this particular case. So, 500ms of message preparation would be the last remaining difference in performances I guess.
ljubex: Okay, so as far as I can see, there are two main reasons for difference in speed: - First is this 0.5s acquiring of next message from spool - And second one is two step scenario: smsd is waiting for ">" before submitting pdp string: in my scenario message is transfered once and sending is triggered with no reason to wait for modem once again
ljubex: And here is the log according to latest instructions: 2011-03-09 11:24:42.359,2, smsd: Smsd v3.1.14 started. 2011-03-09 11:24:42.359,2, smsd: Running as smsd:dialout. 2011-03-09 11:24:42.360,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.Y7tAmw /tmp/smsd_data.M5IqD1 2011-03-09 11:24:42.362,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2011-03-09 11:24:42.362,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2011-03-09 11:24:42.362,6, smsd: Using logtime format "%Y-%m-%d %H:%M:%S.timems". It produces "2011-03-09 11:24:42.362". 2011-03-09 11:24:42.363,5, smsd: Outgoing file checker has started. PID: 20496. 2011-03-09 11:24:42.363,7, smsd: All PID's: 20496,20497,20498 2011-03-09 11:24:42.363,5, GSM1: Modem handler 0 has started. PID: 20497. Will only send messages. 2011-03-09 11:24:42.364,5, GSM2: Modem handler 1 has started. PID: 20498. Will only send messages. 2011-03-09 11:24:42.371,6, GSM1: Checking if modem is ready 2011-03-09 11:24:42.371,6, GSM2: Checking if modem is ready 2011-03-09 11:24:42.571,7, GSM2: -> AT 2011-03-09 11:24:42.571,7, GSM1: -> AT 2011-03-09 11:24:42.572,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:42.572,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:42.872,7, GSM1: <- OK 2011-03-09 11:24:42.872,6, GSM1: Pre-initializing modem 2011-03-09 11:24:42.872,7, GSM2: <- OK 2011-03-09 11:24:42.872,6, GSM2: Pre-initializing modem 2011-03-09 11:24:43.073,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-09 11:24:43.073,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-09 11:24:43.073,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:43.073,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:43.373,7, GSM1: <- OK 2011-03-09 11:24:43.373,6, GSM1: Checking if modem needs PIN 2011-03-09 11:24:43.373,7, GSM2: <- OK 2011-03-09 11:24:43.374,6, GSM2: Checking if modem needs PIN 2011-03-09 11:24:43.574,7, GSM1: -> AT+CPIN? 2011-03-09 11:24:43.574,7, GSM2: -> AT+CPIN? 2011-03-09 11:24:43.574,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:43.574,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:43.874,7, GSM1: <- +CPIN: READY OK 2011-03-09 11:24:43.874,7, GSM2: <- +CPIN: READY OK 2011-03-09 11:24:44.075,7, GSM1: -> AT+CSQ 2011-03-09 11:24:44.075,7, GSM2: -> AT+CSQ 2011-03-09 11:24:44.075,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:44.075,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:44.375,7, GSM1: <- +CSQ: 14,99 OK 2011-03-09 11:24:44.375,6, GSM1: Signal Strength Indicator: (14,99) -85 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-09 11:24:44.375,6, GSM1: Checking if Modem is registered to the network 2011-03-09 11:24:44.376,7, GSM2: <- +CSQ: 10,99 OK 2011-03-09 11:24:44.376,6, GSM2: Signal Strength Indicator: (10,99) -93 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-09 11:24:44.376,6, GSM2: Checking if Modem is registered to the network 2011-03-09 11:24:44.576,7, GSM1: -> AT+CREG? 2011-03-09 11:24:44.576,7, GSM2: -> AT+CREG? 2011-03-09 11:24:44.576,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:44.577,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:44.876,7, GSM1: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-09 11:24:44.876,6, GSM1: Modem is registered to the network 2011-03-09 11:24:44.876,6, GSM1: Location area code: 4ee9, Cell ID: 4c83 2011-03-09 11:24:44.877,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-09 11:24:44.877,6, GSM2: Modem is registered to the network 2011-03-09 11:24:44.877,6, GSM2: Location area code: 4ee9, Cell ID: 4c83 2011-03-09 11:24:45.077,7, GSM1: -> AT+CSQ 2011-03-09 11:24:45.077,7, GSM2: -> AT+CSQ 2011-03-09 11:24:45.078,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:45.078,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:45.378,7, GSM1: <- +CSQ: 14,99 OK 2011-03-09 11:24:45.378,6, GSM1: Signal Strength Indicator: (14,99) -85 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-09 11:24:45.378,6, GSM1: Selecting PDU mode 2011-03-09 11:24:45.378,7, GSM2: <- +CSQ: 10,99 OK 2011-03-09 11:24:45.379,6, GSM2: Signal Strength Indicator: (10,99) -93 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-09 11:24:45.379,6, GSM2: Selecting PDU mode 2011-03-09 11:24:45.579,7, GSM1: -> AT+CMGF=0 2011-03-09 11:24:45.579,7, GSM2: -> AT+CMGF=0 2011-03-09 11:24:45.580,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:45.580,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:45.880,7, GSM1: <- OK 2011-03-09 11:24:45.880,7, GSM2: <- OK 2011-03-09 11:24:46.080,7, GSM1: -> AT+CIMI 2011-03-09 11:24:46.081,7, GSM2: -> AT+CIMI 2011-03-09 11:24:46.081,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:46.081,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:46.381,7, GSM2: <- 220033000184874 OK 2011-03-09 11:24:46.381,7, GSM1: <- 220033000184720 OK 2011-03-09 11:24:46.581,7, GSM1: -> AT+CGSN 2011-03-09 11:24:46.582,7, GSM2: -> AT+CGSN 2011-03-09 11:24:46.582,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:46.582,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:46.882,7, GSM2: <- 353462043290175 OK 2011-03-09 11:24:46.882,5, GSM2: CGSN: 353462043290175 2011-03-09 11:24:46.882,5, GSM2: IMSI: 220033000184874 2011-03-09 11:24:46.882,5, GSM2: Waiting for messages to send... 2011-03-09 11:24:46.883,7, GSM1: <- 353462043321517 OK 2011-03-09 11:24:46.883,5, GSM1: CGSN: 353462043321517 2011-03-09 11:24:46.883,5, GSM1: IMSI: 220033000184720 2011-03-09 11:24:46.883,5, GSM1: Waiting for messages to send... 2011-03-09 11:24:57.443,7, GSM1: Took 0.5 seconds to get a file /var/spool/sms/GSM1/msgb_00.txt, lost 0 times, 5 SMS files and 0 LOCK files seen. 2011-03-09 11:24:57.443,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_00.txt 2011-03-09 11:24:57.443,6, GSM1: Sending SMS from to 38163440734 2011-03-09 11:24:57.443,6, GSM1: Checking if modem is ready 2011-03-09 11:24:57.491,7, GSM2: Took 0.5 seconds to get a file /var/spool/sms/GSM2/msgb_00.txt, lost 0 times, 5 SMS files and 0 LOCK files seen. 2011-03-09 11:24:57.491,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_00.txt 2011-03-09 11:24:57.491,6, GSM2: Sending SMS from to 38163440734 2011-03-09 11:24:57.491,6, GSM2: Checking if modem is ready 2011-03-09 11:24:57.643,7, GSM1: -> AT 2011-03-09 11:24:57.644,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:57.691,7, GSM2: -> AT 2011-03-09 11:24:57.692,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:57.845,7, GSM1: <- OK 2011-03-09 11:24:57.845,6, GSM1: Pre-initializing modem 2011-03-09 11:24:57.893,7, GSM2: <- OK 2011-03-09 11:24:57.893,6, GSM2: Pre-initializing modem 2011-03-09 11:24:58.045,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-09 11:24:58.045,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:58.093,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-09 11:24:58.093,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:58.346,7, GSM1: <- OK 2011-03-09 11:24:58.394,7, GSM2: <- OK 2011-03-09 11:24:58.546,7, GSM1: -> AT+CSQ 2011-03-09 11:24:58.546,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:58.594,7, GSM2: -> AT+CSQ 2011-03-09 11:24:58.594,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:58.847,7, GSM1: <- +CSQ: 14,99 OK 2011-03-09 11:24:58.847,6, GSM1: Signal Strength Indicator: (14,99) -85 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-09 11:24:58.847,6, GSM1: Checking if Modem is registered to the network 2011-03-09 11:24:58.895,7, GSM2: <- +CSQ: 10,99 OK 2011-03-09 11:24:58.895,6, GSM2: Signal Strength Indicator: (10,99) -93 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-09 11:24:58.895,6, GSM2: Checking if Modem is registered to the network 2011-03-09 11:24:59.047,7, GSM1: -> AT+CREG? 2011-03-09 11:24:59.047,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:59.095,7, GSM2: -> AT+CREG? 2011-03-09 11:24:59.095,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:59.348,7, GSM1: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-09 11:24:59.348,6, GSM1: Modem is registered to the network 2011-03-09 11:24:59.348,6, GSM1: Selecting PDU mode 2011-03-09 11:24:59.396,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-09 11:24:59.396,6, GSM2: Modem is registered to the network 2011-03-09 11:24:59.396,6, GSM2: Selecting PDU mode 2011-03-09 11:24:59.548,7, GSM1: -> AT+CMGF=0 2011-03-09 11:24:59.548,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:24:59.596,7, GSM2: -> AT+CMGF=0 2011-03-09 11:24:59.596,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:24:59.849,7, GSM1: <- OK 2011-03-09 11:24:59.897,7, GSM2: <- OK 2011-03-09 11:25:00.049,7, GSM1: -> AT+CMMS=2 2011-03-09 11:25:00.049,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:00.097,7, GSM2: -> AT+CMMS=2 2011-03-09 11:25:00.097,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:00.350,7, GSM1: <- OK 2011-03-09 11:25:00.398,7, GSM2: <- OK 2011-03-09 11:25:00.550,7, GSM1: -> AT+CMGS=26 2011-03-09 11:25:00.550,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:00.598,7, GSM2: -> AT+CMGS=26 2011-03-09 11:25:00.598,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:00.851,7, GSM1: <- > 2011-03-09 11:25:00.898,7, GSM2: <- > 2011-03-09 11:25:01.051,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:01.051,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:01.099,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:01.099,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:03.053,7, GSM1: <- +CMGS: 30 OK 2011-03-09 11:25:03.053,5, GSM1: SMS sent, Message_id: 30, To: 38163440734, sending time 6 sec. 2011-03-09 11:25:03.054,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_00.txt 2011-03-09 11:25:03.201,7, GSM2: <- +CMGS: 1 OK 2011-03-09 11:25:03.201,5, GSM2: SMS sent, Message_id: 1, To: 38163440734, sending time 6 sec. 2011-03-09 11:25:03.201,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_00.txt 2011-03-09 11:25:03.627,7, GSM1: Took 0.5 seconds to get a file /var/spool/sms/GSM1/msgb_01.txt, lost 0 times, 4 SMS files and 0 LOCK files seen. 2011-03-09 11:25:03.627,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_01.txt 2011-03-09 11:25:03.627,6, GSM1: Sending SMS from to 38163440734 2011-03-09 11:25:03.751,7, GSM2: Took 0.5 seconds to get a file /var/spool/sms/GSM2/msgb_01.txt, lost 0 times, 4 SMS files and 0 LOCK files seen. 2011-03-09 11:25:03.751,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_01.txt 2011-03-09 11:25:03.751,6, GSM2: Sending SMS from to 38163440734 2011-03-09 11:25:03.827,7, GSM1: -> AT+CMGS=26 2011-03-09 11:25:03.828,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:03.951,7, GSM2: -> AT+CMGS=26 2011-03-09 11:25:03.952,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:04.128,7, GSM1: <- > 2011-03-09 11:25:04.252,7, GSM2: <- > 2011-03-09 11:25:04.329,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:04.329,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:04.453,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:04.453,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:05.130,7, GSM1: <- +CMGS: 31 OK 2011-03-09 11:25:05.130,5, GSM1: SMS sent, Message_id: 31, To: 38163440734, sending time 2 sec. 2011-03-09 11:25:05.130,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_01.txt 2011-03-09 11:25:05.254,7, GSM2: <- +CMGS: 2 OK 2011-03-09 11:25:05.254,5, GSM2: SMS sent, Message_id: 2, To: 38163440734, sending time 2 sec. 2011-03-09 11:25:05.254,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_01.txt 2011-03-09 11:25:05.700,7, GSM1: Took 0.5 seconds to get a file /var/spool/sms/GSM1/msgb_02.txt, lost 0 times, 3 SMS files and 0 LOCK files seen. 2011-03-09 11:25:05.700,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_02.txt 2011-03-09 11:25:05.700,6, GSM1: Sending SMS from to 38163440734 2011-03-09 11:25:05.799,7, GSM2: Took 0.5 seconds to get a file /var/spool/sms/GSM2/msgb_02.txt, lost 0 times, 3 SMS files and 0 LOCK files seen. 2011-03-09 11:25:05.799,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_02.txt 2011-03-09 11:25:05.799,6, GSM2: Sending SMS from to 38163440734 2011-03-09 11:25:05.900,7, GSM1: -> AT+CMGS=26 2011-03-09 11:25:05.901,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:05.999,7, GSM2: -> AT+CMGS=26 2011-03-09 11:25:06.000,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:06.201,7, GSM1: <- > 2011-03-09 11:25:06.300,7, GSM2: <- > 2011-03-09 11:25:06.401,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:06.402,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:06.501,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:06.501,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:07.204,7, GSM1: <- +CMGS: 32 OK 2011-03-09 11:25:07.204,5, GSM1: SMS sent, Message_id: 32, To: 38163440734, sending time 2 sec. 2011-03-09 11:25:07.204,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_02.txt 2011-03-09 11:25:07.302,7, GSM2: <- +CMGS: 3 OK 2011-03-09 11:25:07.302,5, GSM2: SMS sent, Message_id: 3, To: 38163440734, sending time 2 sec. 2011-03-09 11:25:07.302,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_02.txt 2011-03-09 11:25:07.755,7, GSM1: Took 0.5 seconds to get a file /var/spool/sms/GSM1/msgb_03.txt, lost 0 times, 2 SMS files and 0 LOCK files seen. 2011-03-09 11:25:07.755,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_03.txt 2011-03-09 11:25:07.755,6, GSM1: Sending SMS from to 38163440734 2011-03-09 11:25:07.851,7, GSM2: Took 0.5 seconds to get a file /var/spool/sms/GSM2/msgb_03.txt, lost 0 times, 2 SMS files and 0 LOCK files seen. 2011-03-09 11:25:07.851,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_03.txt 2011-03-09 11:25:07.851,6, GSM2: Sending SMS from to 38163440734 2011-03-09 11:25:07.955,7, GSM1: -> AT+CMGS=26 2011-03-09 11:25:07.956,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:08.051,7, GSM2: -> AT+CMGS=26 2011-03-09 11:25:08.052,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:08.256,7, GSM1: <- > 2011-03-09 11:25:08.352,7, GSM2: <- > 2011-03-09 11:25:08.457,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:08.457,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:08.552,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:08.553,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:09.258,7, GSM1: <- +CMGS: 33 OK 2011-03-09 11:25:09.258,5, GSM1: SMS sent, Message_id: 33, To: 38163440734, sending time 2 sec. 2011-03-09 11:25:09.258,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_03.txt 2011-03-09 11:25:09.353,7, GSM2: <- +CMGS: 4 OK 2011-03-09 11:25:09.354,5, GSM2: SMS sent, Message_id: 4, To: 38163440734, sending time 2 sec. 2011-03-09 11:25:09.354,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_03.txt 2011-03-09 11:25:09.820,7, GSM1: Took 0.5 seconds to get a file /var/spool/sms/GSM1/msgb_04.txt, lost 0 times, 1 SMS files and 0 LOCK files seen. 2011-03-09 11:25:09.820,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_04.txt 2011-03-09 11:25:09.820,6, GSM1: Sending SMS from to 38163440734 2011-03-09 11:25:09.904,7, GSM2: Took 0.5 seconds to get a file /var/spool/sms/GSM2/msgb_04.txt, lost 0 times, 1 SMS files and 0 LOCK files seen. 2011-03-09 11:25:09.904,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_04.txt 2011-03-09 11:25:09.904,6, GSM2: Sending SMS from to 38163440734 2011-03-09 11:25:10.020,7, GSM1: -> AT+CMGS=26 2011-03-09 11:25:10.021,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:10.104,7, GSM2: -> AT+CMGS=26 2011-03-09 11:25:10.105,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:10.321,7, GSM1: <- > 2011-03-09 11:25:10.405,7, GSM2: <- > 2011-03-09 11:25:10.521,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:10.522,7, GSM1: Command is sent, waiting for the answer 2011-03-09 11:25:10.605,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-09 11:25:10.606,7, GSM2: Command is sent, waiting for the answer 2011-03-09 11:25:11.322,7, GSM1: <- +CMGS: 34 OK 2011-03-09 11:25:11.323,5, GSM1: SMS sent, Message_id: 34, To: 38163440734, sending time 2 sec. 2011-03-09 11:25:11.323,6, GSM1: Deleted file /var/spool/sms/GSM1/msgb_04.txt 2011-03-09 11:25:11.607,7, GSM2: <- +CMGS: 5 OK 2011-03-09 11:25:11.607,5, GSM2: SMS sent, Message_id: 5, To: 38163440734, sending time 2 sec. 2011-03-09 11:25:11.607,6, GSM2: Deleted file /var/spool/sms/GSM2/msgb_04.txt 2011-03-09 11:25:20.559,2, smsd: Smsd mainprocess is awaiting the termination of all modem handlers. PID: 20496. 2011-03-09 11:25:20.559,2, GSM1: Modem handler 0 terminated. PID: 20497, was started 11-03-09 11:24:42. 2011-03-09 11:25:20.559,2, GSM2: Modem handler 1 terminated. PID: 20498, was started 11-03-09 11:24:42. 2011-03-09 11:25:20.560,2, smsd: Smsd mainprocess terminated. PID 20496, was started 11-03-09 11:24:42.
ljubex: I'm on my way ,,,, I've just enabled miliseconds log, few minutes ago :) And regarding cmms/cmss: Script is using at+cmss (send mesage from storage) because there was manualy stored pdu on the modem, so it simply invokes mesage sending from storage. And at+cmms=2 (more messages to send) is invoked manually before sending. Also, when it is set to 2, there is no need to trigger it every time: only first message takes normal time, all of the following ones are going fast.
ljubex: Hi again, I would say that with both patches the results are quite the same (new log is included). But reported time of sending is 1s, it actually fits performances provided by at+cmms. But it remains unresolved where smsd spends it's "spare time", because resulting time of sending is a little bit longer (2 seconds per SMS, maybe more). 2011-03-08 22:46:26,2, smsd: Smsd v3.1.14 started. 2011-03-08 22:46:26,2, smsd: Running as smsd:dialout. 2011-03-08 22:46:26,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.LMviwV /tmp/smsd_data.ilSsHa 2011-03-08 22:46:26,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2011-03-08 22:46:26,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2011-03-08 22:46:26,5, smsd: Outgoing file checker has started. PID: 12920. 2011-03-08 22:46:26,7, smsd: All PID's: 12920,12921,12922 2011-03-08 22:46:26,5, GSM1: Modem handler 0 has started. PID: 12921. Will only send messages. 2011-03-08 22:46:26,5, GSM2: Modem handler 1 has started. PID: 12922. Will only send messages. 2011-03-08 22:46:26,6, GSM1: Checking if modem is ready 2011-03-08 22:46:26,6, GSM2: Checking if modem is ready 2011-03-08 22:46:27,7, GSM1: -> AT 2011-03-08 22:46:27,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:27,7, GSM2: -> AT 2011-03-08 22:46:27,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:27,7, GSM1: <- OK 2011-03-08 22:46:27,6, GSM1: Pre-initializing modem 2011-03-08 22:46:27,7, GSM2: <- OK 2011-03-08 22:46:27,6, GSM2: Pre-initializing modem 2011-03-08 22:46:27,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-08 22:46:27,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:27,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-08 22:46:27,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:27,7, GSM1: <- OK 2011-03-08 22:46:27,6, GSM1: Checking if modem needs PIN 2011-03-08 22:46:27,7, GSM2: <- OK 2011-03-08 22:46:27,6, GSM2: Checking if modem needs PIN 2011-03-08 22:46:28,7, GSM1: -> AT+CPIN? 2011-03-08 22:46:28,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:28,7, GSM2: -> AT+CPIN? 2011-03-08 22:46:28,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:28,7, GSM1: <- +CPIN: READY OK 2011-03-08 22:46:28,6, GSM1: Initializing modem 2011-03-08 22:46:28,7, GSM2: <- +CPIN: READY OK 2011-03-08 22:46:28,6, GSM2: Initializing modem 2011-03-08 22:46:28,7, GSM1: -> at+cmms=2 2011-03-08 22:46:28,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:28,7, GSM2: -> at+cmms=2 2011-03-08 22:46:28,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:28,7, GSM1: <- OK 2011-03-08 22:46:28,7, GSM2: <- OK 2011-03-08 22:46:29,7, GSM1: -> AT+CSQ 2011-03-08 22:46:29,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:29,7, GSM2: -> AT+CSQ 2011-03-08 22:46:29,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:29,7, GSM1: <- +CSQ: 10,99 OK 2011-03-08 22:46:29,6, GSM1: Signal Strength Indicator: (10,99) -93 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 22:46:29,6, GSM1: Checking if Modem is registered to the network 2011-03-08 22:46:29,7, GSM2: <- +CSQ: 15,99 OK 2011-03-08 22:46:29,6, GSM2: Signal Strength Indicator: (15,99) -83 dBm (Good), Bit Error Rate: not known or not detectable 2011-03-08 22:46:29,6, GSM2: Checking if Modem is registered to the network 2011-03-08 22:46:29,7, GSM1: -> AT+CREG? 2011-03-08 22:46:29,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:29,7, GSM2: -> AT+CREG? 2011-03-08 22:46:29,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:29,7, GSM1: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 22:46:29,6, GSM1: Modem is registered to the network 2011-03-08 22:46:29,6, GSM1: Location area code: 4ee9, Cell ID: 4c83 2011-03-08 22:46:29,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 22:46:29,6, GSM2: Modem is registered to the network 2011-03-08 22:46:29,6, GSM2: Location area code: 4ee9, Cell ID: 4c83 2011-03-08 22:46:30,7, GSM1: -> AT+CSQ 2011-03-08 22:46:30,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:30,7, GSM2: -> AT+CSQ 2011-03-08 22:46:30,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:30,7, GSM1: <- +CSQ: 10,99 OK 2011-03-08 22:46:30,6, GSM1: Signal Strength Indicator: (10,99) -93 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 22:46:30,6, GSM1: Selecting PDU mode 2011-03-08 22:46:30,7, GSM2: <- +CSQ: 15,99 OK 2011-03-08 22:46:30,6, GSM2: Signal Strength Indicator: (15,99) -83 dBm (Good), Bit Error Rate: not known or not detectable 2011-03-08 22:46:30,6, GSM2: Selecting PDU mode 2011-03-08 22:46:30,7, GSM1: -> AT+CMGF=0 2011-03-08 22:46:30,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:30,7, GSM2: -> AT+CMGF=0 2011-03-08 22:46:30,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:30,7, GSM1: <- OK 2011-03-08 22:46:30,7, GSM2: <- OK 2011-03-08 22:46:31,7, GSM1: -> AT+CIMI 2011-03-08 22:46:31,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:31,7, GSM2: -> AT+CIMI 2011-03-08 22:46:31,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:31,7, GSM1: <- 220033000184498 OK 2011-03-08 22:46:31,7, GSM2: <- 220033000184044 OK 2011-03-08 22:46:31,7, GSM1: -> AT+CGSN 2011-03-08 22:46:31,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:31,7, GSM2: -> AT+CGSN 2011-03-08 22:46:31,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:31,7, GSM1: <- 353462043343925 OK 2011-03-08 22:46:31,5, GSM1: CGSN: 353462043343925 2011-03-08 22:46:31,5, GSM1: IMSI: 220033000184498 2011-03-08 22:46:31,5, GSM1: Waiting for messages to send... 2011-03-08 22:46:31,7, GSM2: <- 353462043321723 OK 2011-03-08 22:46:31,5, GSM2: CGSN: 353462043321723 2011-03-08 22:46:31,5, GSM2: IMSI: 220033000184044 2011-03-08 22:46:31,5, GSM2: Waiting for messages to send... 2011-03-08 22:46:42,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_00.txt 2011-03-08 22:46:42,6, GSM1: Sending SMS from to 38163440734 2011-03-08 22:46:42,6, GSM1: Checking if modem is ready 2011-03-08 22:46:42,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_00.txt 2011-03-08 22:46:42,6, GSM2: Sending SMS from to 38163440734 2011-03-08 22:46:42,6, GSM2: Checking if modem is ready 2011-03-08 22:46:42,7, GSM1: -> AT 2011-03-08 22:46:42,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:42,7, GSM2: -> AT 2011-03-08 22:46:42,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:42,7, GSM1: <- OK 2011-03-08 22:46:42,6, GSM1: Pre-initializing modem 2011-03-08 22:46:42,7, GSM2: <- OK 2011-03-08 22:46:42,6, GSM2: Pre-initializing modem 2011-03-08 22:46:43,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-08 22:46:43,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:43,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-08 22:46:43,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:43,7, GSM1: <- OK 2011-03-08 22:46:43,6, GSM1: Initializing modem 2011-03-08 22:46:43,7, GSM2: <- OK 2011-03-08 22:46:43,6, GSM2: Initializing modem 2011-03-08 22:46:43,7, GSM1: -> at+cmms=2 2011-03-08 22:46:43,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:43,7, GSM2: -> at+cmms=2 2011-03-08 22:46:43,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:43,7, GSM1: <- OK 2011-03-08 22:46:43,7, GSM2: <- OK 2011-03-08 22:46:44,7, GSM1: -> AT+CSQ 2011-03-08 22:46:44,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:44,7, GSM2: -> AT+CSQ 2011-03-08 22:46:44,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:44,7, GSM1: <- +CSQ: 10,99 OK 2011-03-08 22:46:44,6, GSM1: Signal Strength Indicator: (10,99) -93 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 22:46:44,6, GSM1: Checking if Modem is registered to the network 2011-03-08 22:46:44,7, GSM2: <- +CSQ: 15,99 OK 2011-03-08 22:46:44,6, GSM2: Signal Strength Indicator: (15,99) -83 dBm (Good), Bit Error Rate: not known or not detectable 2011-03-08 22:46:44,6, GSM2: Checking if Modem is registered to the network 2011-03-08 22:46:44,7, GSM1: -> AT+CREG? 2011-03-08 22:46:44,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:44,7, GSM2: -> AT+CREG? 2011-03-08 22:46:44,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:44,7, GSM1: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 22:46:44,6, GSM1: Modem is registered to the network 2011-03-08 22:46:44,6, GSM1: Selecting PDU mode 2011-03-08 22:46:44,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 22:46:44,6, GSM2: Modem is registered to the network 2011-03-08 22:46:44,6, GSM2: Selecting PDU mode 2011-03-08 22:46:45,7, GSM1: -> AT+CMGF=0 2011-03-08 22:46:45,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:45,7, GSM2: -> AT+CMGF=0 2011-03-08 22:46:45,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:45,7, GSM1: <- OK 2011-03-08 22:46:45,7, GSM2: <- OK 2011-03-08 22:46:45,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:45,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:45,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:45,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:45,7, GSM1: <- > 2011-03-08 22:46:45,7, GSM2: <- > 2011-03-08 22:46:46,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:46,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:46,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:46,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:48,7, GSM1: <- +CMGS: 8 OK 2011-03-08 22:46:48,5, GSM1: SMS sent, Message_id: 8, To: 38163440734, sending time 6 sec. 2011-03-08 22:46:48,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_00.txt to /var/spool/sms/sent/msgb_00.txt 2011-03-08 22:46:48,7, GSM2: <- +CMGS: 8 OK 2011-03-08 22:46:48,5, GSM2: SMS sent, Message_id: 8, To: 38163440734, sending time 6 sec. 2011-03-08 22:46:48,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_00.txt to /var/spool/sms/sent/msgb_00.txt 2011-03-08 22:46:48,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_01.txt 2011-03-08 22:46:48,6, GSM1: Sending SMS from to 38163440734 2011-03-08 22:46:49,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_01.txt 2011-03-08 22:46:49,6, GSM2: Sending SMS from to 38163440734 2011-03-08 22:46:49,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:49,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:49,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:49,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:49,7, GSM1: <- > 2011-03-08 22:46:49,7, GSM2: <- > 2011-03-08 22:46:49,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:49,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:49,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:49,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:50,7, GSM1: <- +CMGS: 9 OK 2011-03-08 22:46:50,5, GSM1: SMS sent, Message_id: 9, To: 38163440734, sending time 2 sec. 2011-03-08 22:46:50,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_01.txt to /var/spool/sms/sent/msgb_01.txt 2011-03-08 22:46:50,7, GSM2: <- +CMGS: 9 OK 2011-03-08 22:46:50,5, GSM2: SMS sent, Message_id: 9, To: 38163440734, sending time 1 sec. 2011-03-08 22:46:50,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_01.txt to /var/spool/sms/sent/msgb_01.txt 2011-03-08 22:46:51,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_02.txt 2011-03-08 22:46:51,6, GSM1: Sending SMS from to 38163440734 2011-03-08 22:46:51,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_02.txt 2011-03-08 22:46:51,6, GSM2: Sending SMS from to 38163440734 2011-03-08 22:46:51,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:51,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:51,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:51,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:51,7, GSM1: <- > 2011-03-08 22:46:51,7, GSM2: <- > 2011-03-08 22:46:51,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:51,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:51,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:51,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:52,7, GSM1: <- +CMGS: 10 OK 2011-03-08 22:46:52,5, GSM1: SMS sent, Message_id: 10, To: 38163440734, sending time 1 sec. 2011-03-08 22:46:52,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_02.txt to /var/spool/sms/sent/msgb_02.txt 2011-03-08 22:46:52,7, GSM2: <- +CMGS: 10 OK 2011-03-08 22:46:52,5, GSM2: SMS sent, Message_id: 10, To: 38163440734, sending time 1 sec. 2011-03-08 22:46:52,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_02.txt to /var/spool/sms/sent/msgb_02.txt 2011-03-08 22:46:53,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_03.txt 2011-03-08 22:46:53,6, GSM1: Sending SMS from to 38163440734 2011-03-08 22:46:53,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_03.txt 2011-03-08 22:46:53,6, GSM2: Sending SMS from to 38163440734 2011-03-08 22:46:53,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:53,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:53,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:53,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:53,7, GSM1: <- > 2011-03-08 22:46:53,7, GSM2: <- > 2011-03-08 22:46:53,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:53,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:54,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:54,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:54,7, GSM1: <- +CMGS: 11 OK 2011-03-08 22:46:54,5, GSM1: SMS sent, Message_id: 11, To: 38163440734, sending time 1 sec. 2011-03-08 22:46:54,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_03.txt to /var/spool/sms/sent/msgb_03.txt 2011-03-08 22:46:54,7, GSM2: <- +CMGS: 11 OK 2011-03-08 22:46:54,5, GSM2: SMS sent, Message_id: 11, To: 38163440734, sending time 1 sec. 2011-03-08 22:46:54,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_03.txt to /var/spool/sms/sent/msgb_03.txt 2011-03-08 22:46:55,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_04.txt 2011-03-08 22:46:55,6, GSM1: Sending SMS from to 38163440734 2011-03-08 22:46:55,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_04.txt 2011-03-08 22:46:55,6, GSM2: Sending SMS from to 38163440734 2011-03-08 22:46:55,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:55,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:55,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 22:46:55,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:55,7, GSM1: <- > 2011-03-08 22:46:56,7, GSM2: <- > 2011-03-08 22:46:56,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:56,7, GSM1: Command is sent, waiting for the answer 2011-03-08 22:46:56,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 22:46:56,7, GSM2: Command is sent, waiting for the answer 2011-03-08 22:46:56,7, GSM1: <- +CMGS: 12 OK 2011-03-08 22:46:56,5, GSM1: SMS sent, Message_id: 12, To: 38163440734, sending time 1 sec. 2011-03-08 22:46:56,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_04.txt to /var/spool/sms/sent/msgb_04.txt 2011-03-08 22:46:57,7, GSM2: <- +CMGS: 12 OK 2011-03-08 22:46:57,5, GSM2: SMS sent, Message_id: 12, To: 38163440734, sending time 2 sec. 2011-03-08 22:46:57,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_04.txt to /var/spool/sms/sent/msgb_04.txt 2011-03-08 22:47:05,2, smsd: Smsd mainprocess is awaiting the termination of all modem handlers. PID: 12920. 2011-03-08 22:47:05,2, GSM1: Modem handler 0 terminated. PID: 12921, was started 11-03-08 22:46:26. 2011-03-08 22:47:05,2, GSM2: Modem handler 1 terminated. PID: 12922, was started 11-03-08 22:46:26. 2011-03-08 22:47:05,2, smsd: Smsd mainprocess terminated. PID 12920, was started 11-03-08 22:46:26.
ljubex: Ah I now saw new reply from you :) When I said "patched" I meant on first one, not second one .... Be online back in a few hours and then I'll let you know if second one helped ;)
ljubex: Hi, here are logs and comparation. First, here is log of pathced smsd: 2011-03-08 13:40:25,2, smsd: Smsd v3.1.14 started. 2011-03-08 13:40:25,2, smsd: Running as smsd:dialout. 2011-03-08 13:40:25,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.aZQKEq /tmp/smsd_data.TxrzUb 2011-03-08 13:40:25,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2011-03-08 13:40:25,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2011-03-08 13:40:25,5, smsd: Outgoing file checker has started. PID: 29746. 2011-03-08 13:40:25,7, smsd: All PID's: 29746,29747,29748 2011-03-08 13:40:25,5, GSM1: Modem handler 0 has started. PID: 29747. Will only send messages. 2011-03-08 13:40:25,5, GSM2: Modem handler 1 has started. PID: 29748. Will only send messages. 2011-03-08 13:40:25,6, GSM1: Checking if modem is ready 2011-03-08 13:40:25,6, GSM2: Checking if modem is ready 2011-03-08 13:40:26,7, GSM1: -> AT 2011-03-08 13:40:26,7, GSM2: -> AT 2011-03-08 13:40:26,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:26,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:26,7, GSM1: <- OK 2011-03-08 13:40:26,7, GSM2: <- OK 2011-03-08 13:40:26,6, GSM2: Pre-initializing modem 2011-03-08 13:40:26,6, GSM1: Pre-initializing modem 2011-03-08 13:40:26,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-08 13:40:26,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-08 13:40:26,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:26,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:26,7, GSM1: <- OK 2011-03-08 13:40:26,6, GSM1: Checking if modem needs PIN 2011-03-08 13:40:26,7, GSM2: <- OK 2011-03-08 13:40:26,6, GSM2: Checking if modem needs PIN 2011-03-08 13:40:27,7, GSM1: -> AT+CPIN? 2011-03-08 13:40:27,7, GSM2: -> AT+CPIN? 2011-03-08 13:40:27,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:27,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:27,7, GSM1: <- +CPIN: READY OK 2011-03-08 13:40:27,6, GSM1: Initializing modem 2011-03-08 13:40:27,7, GSM2: <- +CPIN: READY OK 2011-03-08 13:40:27,6, GSM2: Initializing modem 2011-03-08 13:40:27,7, GSM1: -> at+cmms=2 2011-03-08 13:40:27,7, GSM2: -> at+cmms=2 2011-03-08 13:40:27,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:27,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:27,7, GSM2: <- OK 2011-03-08 13:40:27,7, GSM1: <- OK 2011-03-08 13:40:28,7, GSM1: -> AT+CSQ 2011-03-08 13:40:28,7, GSM2: -> AT+CSQ 2011-03-08 13:40:28,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:28,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:28,7, GSM1: <- +CSQ: 21,99 OK 2011-03-08 13:40:28,7, GSM2: <- +CSQ: 11,99 OK 2011-03-08 13:40:28,6, GSM2: Signal Strength Indicator: (11,99) -91 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 13:40:28,6, GSM2: Checking if Modem is registered to the network 2011-03-08 13:40:28,6, GSM1: Signal Strength Indicator: (21,99) -71 dBm (Excellent), Bit Error Rate: not known or not detectable 2011-03-08 13:40:28,6, GSM1: Checking if Modem is registered to the network 2011-03-08 13:40:28,7, GSM2: -> AT+CREG? 2011-03-08 13:40:28,7, GSM1: -> AT+CREG? 2011-03-08 13:40:28,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:28,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:28,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 13:40:28,6, GSM2: Modem is registered to the network 2011-03-08 13:40:28,6, GSM2: Location area code: 4ee9, Cell ID: 4c83 2011-03-08 13:40:28,7, GSM1: <- +CREG: 2,1,020,c66c OK 2011-03-08 13:40:28,6, GSM1: Modem is registered to the network 2011-03-08 13:40:28,6, GSM1: Location area code: 020, Cell ID: c66c 2011-03-08 13:40:29,7, GSM2: -> AT+CSQ 2011-03-08 13:40:29,7, GSM1: -> AT+CSQ 2011-03-08 13:40:29,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:29,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:29,7, GSM2: <- +CSQ: 11,99 OK 2011-03-08 13:40:29,7, GSM1: <- +CSQ: 21,99 OK 2011-03-08 13:40:29,6, GSM1: Signal Strength Indicator: (21,99) -71 dBm (Excellent), Bit Error Rate: not known or not detectable 2011-03-08 13:40:29,6, GSM2: Signal Strength Indicator: (11,99) -91 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 13:40:29,6, GSM1: Selecting PDU mode 2011-03-08 13:40:29,6, GSM2: Selecting PDU mode 2011-03-08 13:40:29,7, GSM1: -> AT+CMGF=0 2011-03-08 13:40:29,7, GSM2: -> AT+CMGF=0 2011-03-08 13:40:29,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:29,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:29,7, GSM1: <- OK 2011-03-08 13:40:29,7, GSM2: <- OK 2011-03-08 13:40:30,7, GSM2: -> AT+CIMI 2011-03-08 13:40:30,7, GSM1: -> AT+CIMI 2011-03-08 13:40:30,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:30,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:30,7, GSM1: <- 220051141085250 OK 2011-03-08 13:40:30,7, GSM2: <- 220033000184720 OK 2011-03-08 13:40:30,7, GSM1: -> AT+CGSN 2011-03-08 13:40:30,7, GSM2: -> AT+CGSN 2011-03-08 13:40:30,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:30,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:30,7, GSM2: <- 353462043321517 OK 2011-03-08 13:40:30,5, GSM2: CGSN: 353462043321517 2011-03-08 13:40:30,5, GSM2: IMSI: 220033000184720 2011-03-08 13:40:30,5, GSM2: Waiting for messages to send... 2011-03-08 13:40:30,7, GSM1: <- 353462043304430 OK 2011-03-08 13:40:30,5, GSM1: CGSN: 353462043304430 2011-03-08 13:40:30,5, GSM1: IMSI: 220051141085250 2011-03-08 13:40:30,5, GSM1: Waiting for messages to send... 2011-03-08 13:40:41,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_00.txt 2011-03-08 13:40:41,6, GSM1: Sending SMS from to 38163440734 2011-03-08 13:40:41,6, GSM1: Checking if modem is ready 2011-03-08 13:40:41,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_00.txt 2011-03-08 13:40:41,6, GSM2: Sending SMS from to 38163440734 2011-03-08 13:40:41,6, GSM2: Checking if modem is ready 2011-03-08 13:40:41,7, GSM1: -> AT 2011-03-08 13:40:41,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:41,7, GSM2: -> AT 2011-03-08 13:40:41,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:42,7, GSM1: <- OK 2011-03-08 13:40:42,6, GSM1: Pre-initializing modem 2011-03-08 13:40:42,7, GSM2: <- OK 2011-03-08 13:40:42,6, GSM2: Pre-initializing modem 2011-03-08 13:40:42,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-08 13:40:42,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:42,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-08 13:40:42,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:42,7, GSM1: <- OK 2011-03-08 13:40:42,6, GSM1: Initializing modem 2011-03-08 13:40:42,7, GSM2: <- OK 2011-03-08 13:40:42,6, GSM2: Initializing modem 2011-03-08 13:40:42,7, GSM1: -> at+cmms=2 2011-03-08 13:40:42,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:42,7, GSM2: -> at+cmms=2 2011-03-08 13:40:42,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:43,7, GSM1: <- OK 2011-03-08 13:40:43,7, GSM2: <- OK 2011-03-08 13:40:43,7, GSM1: -> AT+CSQ 2011-03-08 13:40:43,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:43,7, GSM2: -> AT+CSQ 2011-03-08 13:40:43,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:43,7, GSM1: <- +CSQ: 21,99 OK 2011-03-08 13:40:43,6, GSM1: Signal Strength Indicator: (21,99) -71 dBm (Excellent), Bit Error Rate: not known or not detectable 2011-03-08 13:40:43,6, GSM1: Checking if Modem is registered to the network 2011-03-08 13:40:43,7, GSM2: <- +CSQ: 11,99 OK 2011-03-08 13:40:43,6, GSM2: Signal Strength Indicator: (11,99) -91 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 13:40:43,6, GSM2: Checking if Modem is registered to the network 2011-03-08 13:40:43,7, GSM1: -> AT+CREG? 2011-03-08 13:40:43,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:43,7, GSM2: -> AT+CREG? 2011-03-08 13:40:43,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:44,7, GSM1: <- +CREG: 2,1,020,c66c OK 2011-03-08 13:40:44,6, GSM1: Modem is registered to the network 2011-03-08 13:40:44,6, GSM1: Selecting PDU mode 2011-03-08 13:40:44,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 13:40:44,6, GSM2: Modem is registered to the network 2011-03-08 13:40:44,6, GSM2: Selecting PDU mode 2011-03-08 13:40:44,7, GSM1: -> AT+CMGF=0 2011-03-08 13:40:44,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:44,7, GSM2: -> AT+CMGF=0 2011-03-08 13:40:44,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:44,7, GSM1: <- OK 2011-03-08 13:40:44,7, GSM2: <- OK 2011-03-08 13:40:44,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:44,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:44,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:44,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:45,7, GSM1: <- > 2011-03-08 13:40:45,7, GSM2: <- > 2011-03-08 13:40:45,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:45,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:45,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:45,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:47,7, GSM2: <- +CMGS: 8 OK 2011-03-08 13:40:47,5, GSM2: SMS sent, Message_id: 8, To: 38163440734, sending time 6 sec. 2011-03-08 13:40:47,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_00.txt to /var/spool/sms/sent/msgb_00.txt 2011-03-08 13:40:47,7, GSM1: <- +CMGS: 228 OK 2011-03-08 13:40:47,5, GSM1: SMS sent, Message_id: 228, To: 38163440734, sending time 6 sec. 2011-03-08 13:40:47,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_00.txt to /var/spool/sms/sent/msgb_00.txt 2011-03-08 13:40:48,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_01.txt 2011-03-08 13:40:48,6, GSM2: Sending SMS from to 38163440734 2011-03-08 13:40:48,6, GSM2: Checking if Modem is registered to the network 2011-03-08 13:40:48,7, GSM2: -> AT+CREG? 2011-03-08 13:40:48,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:48,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_01.txt 2011-03-08 13:40:48,6, GSM1: Sending SMS from to 38163440734 2011-03-08 13:40:48,6, GSM1: Checking if Modem is registered to the network 2011-03-08 13:40:48,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 13:40:48,6, GSM2: Modem is registered to the network 2011-03-08 13:40:48,7, GSM1: -> AT+CREG? 2011-03-08 13:40:48,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:48,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:48,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:48,7, GSM1: <- +CREG: 2,1,020,c66c OK 2011-03-08 13:40:48,6, GSM1: Modem is registered to the network 2011-03-08 13:40:49,7, GSM2: <- > 2011-03-08 13:40:49,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:49,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:49,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:49,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:49,7, GSM1: <- > 2011-03-08 13:40:49,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:49,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:50,7, GSM2: <- +CMGS: 9 OK 2011-03-08 13:40:50,5, GSM2: SMS sent, Message_id: 9, To: 38163440734, sending time 2 sec. 2011-03-08 13:40:50,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_01.txt to /var/spool/sms/sent/msgb_01.txt 2011-03-08 13:40:50,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_02.txt 2011-03-08 13:40:50,6, GSM2: Sending SMS from to 38163440734 2011-03-08 13:40:50,6, GSM2: Checking if Modem is registered to the network 2011-03-08 13:40:50,7, GSM1: <- +CMGS: 229 OK 2011-03-08 13:40:50,5, GSM1: SMS sent, Message_id: 229, To: 38163440734, sending time 2 sec. 2011-03-08 13:40:50,7, GSM2: -> AT+CREG? 2011-03-08 13:40:50,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:50,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_01.txt to /var/spool/sms/sent/msgb_01.txt 2011-03-08 13:40:51,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 13:40:51,6, GSM2: Modem is registered to the network 2011-03-08 13:40:51,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:51,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:51,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_02.txt 2011-03-08 13:40:51,6, GSM1: Sending SMS from to 38163440734 2011-03-08 13:40:51,6, GSM1: Checking if Modem is registered to the network 2011-03-08 13:40:51,7, GSM1: -> AT+CREG? 2011-03-08 13:40:51,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:51,7, GSM2: <- > 2011-03-08 13:40:51,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:51,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:52,7, GSM1: <- +CREG: 2,1,020,c66c OK 2011-03-08 13:40:52,6, GSM1: Modem is registered to the network 2011-03-08 13:40:52,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:52,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:52,7, GSM1: <- > 2011-03-08 13:40:52,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:52,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:52,7, GSM2: <- +CMGS: 10 OK 2011-03-08 13:40:52,5, GSM2: SMS sent, Message_id: 10, To: 38163440734, sending time 2 sec. 2011-03-08 13:40:52,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_02.txt to /var/spool/sms/sent/msgb_02.txt 2011-03-08 13:40:53,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_03.txt 2011-03-08 13:40:53,6, GSM2: Sending SMS from to 38163440734 2011-03-08 13:40:53,6, GSM2: Checking if Modem is registered to the network 2011-03-08 13:40:53,7, GSM2: -> AT+CREG? 2011-03-08 13:40:53,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:53,7, GSM1: <- +CMGS: 230 OK 2011-03-08 13:40:53,5, GSM1: SMS sent, Message_id: 230, To: 38163440734, sending time 2 sec. 2011-03-08 13:40:53,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 13:40:53,6, GSM2: Modem is registered to the network 2011-03-08 13:40:53,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_02.txt to /var/spool/sms/sent/msgb_02.txt 2011-03-08 13:40:54,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:54,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:54,7, GSM2: <- > 2011-03-08 13:40:54,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_03.txt 2011-03-08 13:40:54,6, GSM1: Sending SMS from to 38163440734 2011-03-08 13:40:54,6, GSM1: Checking if Modem is registered to the network 2011-03-08 13:40:54,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:54,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:54,7, GSM1: -> AT+CREG? 2011-03-08 13:40:54,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:54,7, GSM1: <- +CREG: 2,1,020,c66c OK 2011-03-08 13:40:54,6, GSM1: Modem is registered to the network 2011-03-08 13:40:55,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:55,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:55,7, GSM2: <- +CMGS: 11 OK 2011-03-08 13:40:55,5, GSM2: SMS sent, Message_id: 11, To: 38163440734, sending time 2 sec. 2011-03-08 13:40:55,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_03.txt to /var/spool/sms/sent/msgb_03.txt 2011-03-08 13:40:55,7, GSM1: <- > 2011-03-08 13:40:55,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:55,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:56,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_04.txt 2011-03-08 13:40:56,6, GSM2: Sending SMS from to 38163440734 2011-03-08 13:40:56,6, GSM2: Checking if Modem is registered to the network 2011-03-08 13:40:56,7, GSM2: -> AT+CREG? 2011-03-08 13:40:56,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:56,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 13:40:56,6, GSM2: Modem is registered to the network 2011-03-08 13:40:56,7, GSM2: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:56,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:56,7, GSM1: <- +CMGS: 231 OK 2011-03-08 13:40:56,5, GSM1: SMS sent, Message_id: 231, To: 38163440734, sending time 2 sec. 2011-03-08 13:40:56,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_03.txt to /var/spool/sms/sent/msgb_03.txt 2011-03-08 13:40:57,7, GSM2: <- > 2011-03-08 13:40:57,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:57,7, GSM2: Command is sent, waiting for the answer 2011-03-08 13:40:57,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_04.txt 2011-03-08 13:40:57,6, GSM1: Sending SMS from to 38163440734 2011-03-08 13:40:57,6, GSM1: Checking if Modem is registered to the network 2011-03-08 13:40:57,7, GSM1: -> AT+CREG? 2011-03-08 13:40:57,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:58,7, GSM1: <- +CREG: 2,1,020,c66c OK 2011-03-08 13:40:58,6, GSM1: Modem is registered to the network 2011-03-08 13:40:58,7, GSM2: <- +CMGS: 12 OK 2011-03-08 13:40:58,5, GSM2: SMS sent, Message_id: 12, To: 38163440734, sending time 2 sec. 2011-03-08 13:40:58,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_04.txt to /var/spool/sms/sent/msgb_04.txt 2011-03-08 13:40:58,7, GSM1: -> AT+CMMS=2;+CMGS=26 2011-03-08 13:40:58,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:58,7, GSM1: <- > 2011-03-08 13:40:58,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07. 2011-03-08 13:40:58,7, GSM1: Command is sent, waiting for the answer 2011-03-08 13:40:59,7, GSM1: <- +CMGS: 232 OK 2011-03-08 13:40:59,5, GSM1: SMS sent, Message_id: 232, To: 38163440734, sending time 2 sec. 2011-03-08 13:40:59,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_04.txt to /var/spool/sms/sent/msgb_04.txt 2011-03-08 13:41:46,2, smsd: Smsd mainprocess is awaiting the termination of all modem handlers. PID: 29746. 2011-03-08 13:41:46,2, GSM1: Modem handler 0 terminated. PID: 29747, was started 11-03-08 13:40:25. 2011-03-08 13:41:46,2, GSM2: Modem handler 1 terminated. PID: 29748, was started 11-03-08 13:40:25. 2011-03-08 13:41:46,2, smsd: Smsd mainprocess terminated. PID 29746, was started 11-03-08 13:40:25. Now, here is a script I'm testing cmms with: #!/usr/bin/perl use strict; use Time::HiRes qw(usleep nanosleep gettimeofday); use Device::SerialPort; my $port = Device::SerialPort->new("/dev/ttyUSB13"); $port->databits(8); $port->baudrate(115200); $port->parity("none"); $port->handshake("none"); $port->stopbits(1); $port->write_settings; for my $i (0..9) { print gettimeofday () . "\n"; $port->write("at+cmss=0\r\n"); usleep(100000); while (!($port->input =~ /OK/)) { usleep(100000); } } $port->close; undef $port; So as you can see the message is stored into memory and it is simply resent every time. I tried with text and pdu message, the results are completely the same (regarding the speed). Here are timestamps: 1299589975.14219 1299589977.14597 1299589978.04707 1299589978.94828 1299589979.94945 1299589980.85079 1299589981.75191 1299589982.65305 1299589983.5542 1299589984.65575 The script is quite unstable and unuseful, I just made it for test as you can see :)
ljubex: Hmmm, still same performances :( Is it possible that spool engine takes to much time to feed the modem? (didn't read the source actually, just coming up with the idea ....)
ljubex: Deal ! :) I'm waiting for further instructions, now going to grab the source code ....
ljubex: Hi Keke, thanx for fast reply ! I guess that problem is actually more profound than I thought. The speed is always normal, never really gets to fast mode: I only measured the speed of emptying outgoing directory. So, didn't realize that all messages are moved to checked or some other queue and than being processed. Anyway, I upgraded to 3.1.14. The speed I'm trying to get is definitely achieved with this modem by hand: I've made simple script that just triggers at command for sms send, waits for OK and so on ... Am I wrong or this starts to look like feature request? Simply what has to be done is to invoke at+cmss=2 seconds before sending is started. I put keep_open = no (hoping that init string will be sent to modem in apropriate time, but this didn't help as well). Here is a log of following configuration: - Two modems - Two separate queues, each modem serving one queue - Copied 3 messages in each queue at (almost) same time 2011-03-08 12:05:39,2, smsd: Smsd v3.1.14 started. 2011-03-08 12:05:39,2, smsd: Running as smsd:dialout. 2011-03-08 12:05:39,7, smsd: Running startup_check (shell): /var/spool/sms/incoming/smsd_script.RT8Q3y /tmp/smsd_data.D8MCbR 2011-03-08 12:05:39,7, smsd: Done: startup_check (shell), execution time 0 sec., status: 0 (0) 2011-03-08 12:05:39,4, smsd: File mode creation mask: 022 (0644, rw-r--r--). 2011-03-08 12:05:39,5, smsd: Outgoing file checker has started. PID: 26604. 2011-03-08 12:05:39,7, smsd: All PID's: 26604,26605,26606 2011-03-08 12:05:39,5, GSM1: Modem handler 0 has started. PID: 26605. Will only send messages. 2011-03-08 12:05:39,5, GSM2: Modem handler 1 has started. PID: 26606. Will only send messages. 2011-03-08 12:05:39,6, GSM1: Checking if modem is ready 2011-03-08 12:05:39,6, GSM2: Checking if modem is ready 2011-03-08 12:05:40,7, GSM1: -> AT 2011-03-08 12:05:40,7, GSM2: -> AT 2011-03-08 12:05:40,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:40,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:40,7, GSM2: <- OK 2011-03-08 12:05:40,7, GSM1: <- OK 2011-03-08 12:05:40,6, GSM1: Pre-initializing modem 2011-03-08 12:05:40,6, GSM2: Pre-initializing modem 2011-03-08 12:05:40,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-08 12:05:40,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-08 12:05:40,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:40,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:40,7, GSM2: <- OK 2011-03-08 12:05:40,6, GSM2: Checking if modem needs PIN 2011-03-08 12:05:40,7, GSM1: <- OK 2011-03-08 12:05:40,6, GSM1: Checking if modem needs PIN 2011-03-08 12:05:41,7, GSM2: -> AT+CPIN? 2011-03-08 12:05:41,7, GSM1: -> AT+CPIN? 2011-03-08 12:05:41,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:41,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:41,7, GSM2: <- +CPIN: READY OK 2011-03-08 12:05:41,7, GSM1: <- +CPIN: READY OK 2011-03-08 12:05:41,6, GSM1: Initializing modem 2011-03-08 12:05:41,6, GSM2: Initializing modem 2011-03-08 12:05:41,7, GSM1: -> at+cmms=2 2011-03-08 12:05:41,7, GSM2: -> at+cmms=2 2011-03-08 12:05:41,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:41,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:41,7, GSM1: <- OK 2011-03-08 12:05:41,7, GSM2: <- OK 2011-03-08 12:05:42,7, GSM2: -> AT+CSQ 2011-03-08 12:05:42,7, GSM1: -> AT+CSQ 2011-03-08 12:05:42,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:42,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:42,7, GSM1: <- +CSQ: 21,99 OK 2011-03-08 12:05:42,7, GSM2: <- +CSQ: 13,99 OK 2011-03-08 12:05:42,6, GSM2: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 12:05:42,6, GSM2: Checking if Modem is registered to the network 2011-03-08 12:05:42,6, GSM1: Signal Strength Indicator: (21,99) -71 dBm (Excellent), Bit Error Rate: not known or not detectable 2011-03-08 12:05:42,6, GSM1: Checking if Modem is registered to the network 2011-03-08 12:05:42,7, GSM2: -> AT+CREG? 2011-03-08 12:05:42,7, GSM1: -> AT+CREG? 2011-03-08 12:05:42,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:42,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:42,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 12:05:42,7, GSM1: <- +CREG: 2,1,020,031c OK 2011-03-08 12:05:42,6, GSM1: Modem is registered to the network 2011-03-08 12:05:42,6, GSM1: Location area code: 020, Cell ID: 031c 2011-03-08 12:05:42,6, GSM2: Modem is registered to the network 2011-03-08 12:05:42,6, GSM2: Location area code: 4ee9, Cell ID: 4c83 2011-03-08 12:05:43,7, GSM1: -> AT+CSQ 2011-03-08 12:05:43,7, GSM2: -> AT+CSQ 2011-03-08 12:05:43,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:43,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:43,7, GSM2: <- +CSQ: 13,99 OK 2011-03-08 12:05:43,6, GSM2: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 12:05:43,7, GSM1: <- +CSQ: 21,99 OK 2011-03-08 12:05:43,6, GSM1: Signal Strength Indicator: (21,99) -71 dBm (Excellent), Bit Error Rate: not known or not detectable 2011-03-08 12:05:43,6, GSM1: Selecting PDU mode 2011-03-08 12:05:43,6, GSM2: Selecting PDU mode 2011-03-08 12:05:43,7, GSM2: -> AT+CMGF=0 2011-03-08 12:05:43,7, GSM1: -> AT+CMGF=0 2011-03-08 12:05:43,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:43,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:43,7, GSM1: <- OK 2011-03-08 12:05:43,7, GSM2: <- OK 2011-03-08 12:05:44,7, GSM1: -> AT+CIMI 2011-03-08 12:05:44,7, GSM2: -> AT+CIMI 2011-03-08 12:05:44,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:44,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:44,7, GSM2: <- 220033000184720 OK 2011-03-08 12:05:44,7, GSM1: <- 220051141085250 OK 2011-03-08 12:05:44,7, GSM2: -> AT+CGSN 2011-03-08 12:05:44,7, GSM1: -> AT+CGSN 2011-03-08 12:05:44,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:05:44,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:05:44,7, GSM1: <- 353462043304430 OK 2011-03-08 12:05:44,5, GSM1: CGSN: 353462043304430 2011-03-08 12:05:44,5, GSM1: IMSI: 220051141085250 2011-03-08 12:05:44,5, GSM1: Waiting for messages to send... 2011-03-08 12:05:44,7, GSM2: <- 353462043321517 OK 2011-03-08 12:05:44,5, GSM2: CGSN: 353462043321517 2011-03-08 12:05:44,5, GSM2: IMSI: 220033000184720 2011-03-08 12:05:44,5, GSM2: Waiting for messages to send... 2011-03-08 12:06:25,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_00.txt 2011-03-08 12:06:25,6, GSM2: Sending SMS from to 38163440734 2011-03-08 12:06:25,6, GSM2: Checking if modem is ready 2011-03-08 12:06:25,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_00.txt 2011-03-08 12:06:25,6, GSM1: Sending SMS from to 38163440734 2011-03-08 12:06:25,6, GSM1: Checking if modem is ready 2011-03-08 12:06:25,7, GSM2: -> AT 2011-03-08 12:06:25,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:25,7, GSM1: -> AT 2011-03-08 12:06:25,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:26,7, GSM2: <- OK 2011-03-08 12:06:26,6, GSM2: Pre-initializing modem 2011-03-08 12:06:26,7, GSM1: <- OK 2011-03-08 12:06:26,6, GSM1: Pre-initializing modem 2011-03-08 12:06:26,7, GSM2: -> ATE0+CMEE=1;+CREG=2 2011-03-08 12:06:26,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:26,7, GSM1: -> ATE0+CMEE=1;+CREG=2 2011-03-08 12:06:26,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:26,7, GSM2: <- OK 2011-03-08 12:06:26,6, GSM2: Initializing modem 2011-03-08 12:06:26,7, GSM1: <- OK 2011-03-08 12:06:26,6, GSM1: Initializing modem 2011-03-08 12:06:26,7, GSM2: -> at+cmms=2 2011-03-08 12:06:26,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:26,7, GSM1: -> at+cmms=2 2011-03-08 12:06:26,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:27,7, GSM2: <- OK 2011-03-08 12:06:27,7, GSM1: <- OK 2011-03-08 12:06:27,7, GSM2: -> AT+CSQ 2011-03-08 12:06:27,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:27,7, GSM1: -> AT+CSQ 2011-03-08 12:06:27,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:27,7, GSM2: <- +CSQ: 13,99 OK 2011-03-08 12:06:27,6, GSM2: Signal Strength Indicator: (13,99) -87 dBm (Workable), Bit Error Rate: not known or not detectable 2011-03-08 12:06:27,6, GSM2: Checking if Modem is registered to the network 2011-03-08 12:06:27,7, GSM1: <- +CSQ: 21,99 OK 2011-03-08 12:06:27,6, GSM1: Signal Strength Indicator: (21,99) -71 dBm (Excellent), Bit Error Rate: not known or not detectable 2011-03-08 12:06:27,6, GSM1: Checking if Modem is registered to the network 2011-03-08 12:06:27,7, GSM2: -> AT+CREG? 2011-03-08 12:06:27,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:27,7, GSM1: -> AT+CREG? 2011-03-08 12:06:27,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:28,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 12:06:28,6, GSM2: Modem is registered to the network 2011-03-08 12:06:28,6, GSM2: Selecting PDU mode 2011-03-08 12:06:28,7, GSM1: <- +CREG: 2,1,020,031c OK 2011-03-08 12:06:28,6, GSM1: Modem is registered to the network 2011-03-08 12:06:28,6, GSM1: Selecting PDU mode 2011-03-08 12:06:28,7, GSM2: -> AT+CMGF=0 2011-03-08 12:06:28,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:28,7, GSM1: -> AT+CMGF=0 2011-03-08 12:06:28,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:28,7, GSM2: <- OK 2011-03-08 12:06:28,7, GSM1: <- OK 2011-03-08 12:06:28,7, GSM2: -> AT+CMGS=26 2011-03-08 12:06:28,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:28,7, GSM1: -> AT+CMGS=26 2011-03-08 12:06:28,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:29,7, GSM2: <- > 2011-03-08 12:06:29,7, GSM1: <- > 2011-03-08 12:06:29,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 12:06:29,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:29,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 12:06:29,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:31,7, GSM2: <- +CMGS: 0 OK 2011-03-08 12:06:31,5, GSM2: SMS sent, Message_id: 0, To: 38163440734, sending time 6 sec. 2011-03-08 12:06:31,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_00.txt to /var/spool/sms/sent/msgb_00.txt 2011-03-08 12:06:31,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_01.txt 2011-03-08 12:06:31,6, GSM2: Sending SMS from to 38163440734 2011-03-08 12:06:31,6, GSM2: Checking if Modem is registered to the network 2011-03-08 12:06:31,7, GSM1: <- +CMGS: 209 OK 2011-03-08 12:06:31,5, GSM1: SMS sent, Message_id: 209, To: 38163440734, sending time 6 sec. 2011-03-08 12:06:31,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_00.txt to /var/spool/sms/sent/msgb_00.txt 2011-03-08 12:06:31,7, GSM2: -> AT+CREG? 2011-03-08 12:06:31,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:32,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 12:06:32,6, GSM2: Modem is registered to the network 2011-03-08 12:06:32,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_01.txt 2011-03-08 12:06:32,6, GSM1: Sending SMS from to 38163440734 2011-03-08 12:06:32,6, GSM1: Checking if Modem is registered to the network 2011-03-08 12:06:32,7, GSM2: -> AT+CMGS=26 2011-03-08 12:06:32,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:32,7, GSM1: -> AT+CREG? 2011-03-08 12:06:32,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:32,7, GSM2: <- > 2011-03-08 12:06:32,7, GSM1: <- +CREG: 2,1,020,031c OK 2011-03-08 12:06:32,6, GSM1: Modem is registered to the network 2011-03-08 12:06:32,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 12:06:32,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:33,7, GSM1: -> AT+CMGS=26 2011-03-08 12:06:33,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:33,7, GSM1: <- > 2011-03-08 12:06:33,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 12:06:33,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:33,7, GSM2: <- +CMGS: 1 OK 2011-03-08 12:06:33,5, GSM2: SMS sent, Message_id: 1, To: 38163440734, sending time 2 sec. 2011-03-08 12:06:33,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_01.txt to /var/spool/sms/sent/msgb_01.txt 2011-03-08 12:06:34,6, GSM2: I have to send 1 short message for /var/spool/sms/GSM2/msgb_02.txt 2011-03-08 12:06:34,6, GSM2: Sending SMS from to 38163440734 2011-03-08 12:06:34,6, GSM2: Checking if Modem is registered to the network 2011-03-08 12:06:34,7, GSM2: -> AT+CREG? 2011-03-08 12:06:34,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:34,7, GSM1: <- +CMGS: 210 OK 2011-03-08 12:06:34,5, GSM1: SMS sent, Message_id: 210, To: 38163440734, sending time 2 sec. 2011-03-08 12:06:34,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_01.txt to /var/spool/sms/sent/msgb_01.txt 2011-03-08 12:06:34,7, GSM2: <- +CREG: 2,1,4ee9,4c83 OK 2011-03-08 12:06:34,6, GSM2: Modem is registered to the network 2011-03-08 12:06:35,7, GSM2: -> AT+CMGS=26 2011-03-08 12:06:35,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:35,6, GSM1: I have to send 1 short message for /var/spool/sms/GSM1/msgb_02.txt 2011-03-08 12:06:35,6, GSM1: Sending SMS from to 38163440734 2011-03-08 12:06:35,6, GSM1: Checking if Modem is registered to the network 2011-03-08 12:06:35,7, GSM2: <- > 2011-03-08 12:06:35,7, GSM1: -> AT+CREG? 2011-03-08 12:06:35,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:35,7, GSM2: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 12:06:35,7, GSM2: Command is sent, waiting for the answer 2011-03-08 12:06:35,7, GSM1: <- +CREG: 2,1,020,031c OK 2011-03-08 12:06:35,6, GSM1: Modem is registered to the network 2011-03-08 12:06:36,7, GSM1: -> AT+CMGS=26 2011-03-08 12:06:36,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:36,7, GSM1: <- > 2011-03-08 12:06:36,7, GSM2: <- +CMGS: 2 OK 2011-03-08 12:06:36,5, GSM2: SMS sent, Message_id: 2, To: 38163440734, sending time 2 sec. 2011-03-08 12:06:36,6, GSM2: Moved file /var/spool/sms/GSM2/msgb_02.txt to /var/spool/sms/sent/msgb_02.txt 2011-03-08 12:06:36,7, GSM1: -> 0011000B918361430437F40000FF0DD0B79E2C0FDB59207A794E07 2011-03-08 12:06:36,7, GSM1: Command is sent, waiting for the answer 2011-03-08 12:06:37,7, GSM1: <- +CMGS: 211 OK 2011-03-08 12:06:37,5, GSM1: SMS sent, Message_id: 211, To: 38163440734, sending time 2 sec. 2011-03-08 12:06:37,6, GSM1: Moved file /var/spool/sms/GSM1/msgb_02.txt to /var/spool/sms/sent/msgb_02.txt 2011-03-08 12:07:00,2, smsd: Smsd mainprocess is awaiting the termination of all modem handlers. PID: 26604. 2011-03-08 12:07:00,2, GSM1: Modem handler 0 terminated. PID: 26605, was started 11-03-08 12:05:39. 2011-03-08 12:07:00,2, GSM2: Modem handler 1 terminated. PID: 26606, was started 11-03-08 12:05:39. 2011-03-08 12:07:00,2, smsd: Smsd mainprocess terminated. PID 26604, was started 11-03-08 12:05:39.
ljubex: Operating system name and version: Debian GNU/Linux squeeze/sid (2.6.32-3-686) Version of smsd: 3.1.11 Smsd installed from: package repository Name and model of a modem / phone: ZTE K3565-Z Interface: USB Hi everybody, I'm trying to send a bunch of SMS over several usbserial modems. I'm trying to send all sms in fast sms sending mode so I've put at+cmss=2 as init string. When I use one modem at a time and use global spool (/var/spool/sms/outgoing) everything goes well and it takes aproximately 0.8 - 0.9s per SMS, which is ok. But when I try to send over two modems where I have a queue for each of them - the speed goes normal (not fast) and it takes about 2.5s per SMS (which is intolerably slow in my case). When I start two separated smsd instances, each having separate config and pid file, then it goes fast again. So is there a chance to make sending over queues to go in fast speed mode? Here is my global config file: devices = GSM1,GSM2 outgoing = /var/spool/sms/outgoing checked = /var/spool/sms/checked incoming = /var/spool/sms/incoming logfile = /var/log/smstools/smsd.log infofile = /var/run/smstools/smsd.working pidfile = /var/run/smstools/smsd.pid failed = /var/spool/sms/failed sent = /var/spool/sms/sent stats = /var/log/smstools/smsd_stats receive_before_send = no autosplit = 3 max_continuous_sending = 7200 [queues] GSM1 = /var/spool/sms/GSM1 GSM2 = /var/spool/sms/GSM2 [GSM1] device = /dev/ttyUSB3 init = at+cmms=2 incoming = no mode = new baudrate = 115200 rtscts = yes cs_convert = yes report = no sending_disabled = no queues = GSM1 [GSM2] device = /dev/ttyUSB8 init = at+cmms=2 incoming = no mode = new baudrate = 115200 rtscts = yes cs_convert = yes report = no sending_disabled = no queues = GSM2 Also, is there a chance to force multiple modems to serve one queue? (hundreds of modems). Any help will be appreciated :) Best regards, Ljubomir

Page:  1

SMSTools3 Community » Search Top

 
Time in this board is UTC.  

Privacy Policy   SMS Server Tools 3 Copyright © Keijo Kasvi.