Hi Gang, I am having problems sending and receiving sms messages using a new falcom 850/1900 Dual Band Modem.
It's acting like it doesn't recognize the <ctrl-Z> character sequence to complete the message when it tries to send: AT+CMGS=somenumber messagetext<ctrl-Z> Config - fedora Core 6 - kannel 1.4.1 from built from source - libxml2-2.6.27-1.FC6 - modem attached to COM1 with rs-232 cable - both bearerbox and smsbox are running - modem is initialized on startup - smsbox forwards sms message to bearerbox - bearerbox begins talking to modem but then dies with a "GENERIC ERROR: error" right after setting the phone number - i am able to send sms messages using minicom and AT commands to this modem as attached - is the modem INIT string wrong somehow for this hardware? - on receiving messages i get the error "could not decode PDU to a message" Configs and Logs below. <---snip---> ---------------------------------- -------- .conf file -------------- ---------------------------------- #--------------------------------------------- # CORE # group = core admin-port = 13000 smsbox-port = 13001 admin-password = bar admin-deny-ip = "*.*.*.*" admin-allow-ip = "xxx.xxx.xxx.xxx*" log-file = "/tmp/kannel.log" log-level = 0 box-deny-ip = "*.*.*.*" box-allow-ip = "127.0.0.1;xxx.xxx.xxx.xxx*" access-log = "/tmp/access.log" store-file = "kannel.store" #--------------------------------------------- # SMSC CONNECTIONS # group = smsc smsc = at smsc-id = falcom850 modemtype = falcom device = /dev/ttyS0 speed = 9600 pin = 2345 group = modems id = falcom name = "Falcom" detect-string = "Falcom" no-pin = true no-smsc = true #--------------------------------------------- # SMSBOX SETUP # group = smsbox bearerbox-host = 127.0.0.1 sendsms-port = 13013 global-sender = 13013 sendsms-chars = "0123456789 +-" log-file = "/tmp/smsbox.log" log-level = 0 access-log = "/tmp/access.log" #--------------------------------------------- # SEND-SMS USERS # group = sendsms-user username = tester password = foobar #user-deny-ip = "" #user-allow-ip = "" #--------------------------------------------- # SERVICES # group = sms-service keyword = nop text = "You asked nothing and I did it!" group = sms-service keyword = default accepted-smsc = falcom850 get-url = http://xxx.xxx.xxx.xxx/xxx.php"; max-messages = 0 <---snip---> ------------------------------------- --------BEARERBOX LOG FILE---------- ------------------------------------- 2006-12-08 16:46:10 [4763] [0] INFO: Added logfile `/tmp/kannel.log' with level `0'. 2006-12-08 16:46:10 [4763] [0] INFO: Started access logfile `/tmp/access.log'. 2006-12-08 16:46:10 [4763] [0] INFO: HTTP: Opening server at port 13000. 2006-12-08 16:46:10 [4763] [0] DEBUG: Started thread 1 (gwlib/fdset.c:poller) 2006-12-08 16:46:10 [4763] [0] DEBUG: Started thread 2 (gwlib/http.c:server_thread) 2006-12-08 16:46:10 [4763] [0] DEBUG: Started thread 3 (gw/bb_http.c:httpadmin_run) 2006-12-08 16:46:10 [4763] [0] DEBUG: starting smsbox connection module 2006-12-08 16:46:10 [4763] [0] INFO: BOXC: 'smsbox-max-pending' not set, using default (100). 2006-12-08 16:46:10 [4763] [0] DEBUG: Started thread 4 (gw/bb_boxc.c:sms_to_smsboxes) 2006-12-08 16:46:10 [4763] [0] DEBUG: Started thread 5 (gw/bb_boxc.c:smsboxc_run) 2006-12-08 16:46:10 [4763] [0] INFO: Set SMS resend frequency to 60 seconds. 2006-12-08 16:46:10 [4763] [0] INFO: SMS resend retry set to unlimited. 2006-12-08 16:46:10 [4763] [0] INFO: DLR rerouting for smsc id <falcom850> disabled. 2006-12-08 16:46:10 [4763] [0] INFO: AT2[falcom850]: configuration shows modemtype <falcom> 2006-12-08 16:46:10 [4763] [0] DEBUG: AT2[falcom850]: Reading modem definitions from <smskannel.conf> 2006-12-08 16:46:10 [4763] [0] DEBUG: AT2[falcom850]: Found <1> modems in config 2006-12-08 16:46:10 [4763] [0] INFO: AT2[falcom850]: read modem definition for <Falcom> 2006-12-08 16:46:10 [4763] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run) maps to pid 4763. 2006-12-08 16:46:10 [4763] [0] DEBUG: Started thread 6 (gw/smsc/smsc_at.c:at2_device_thread) 2006-12-08 16:46:10 [4763] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run) maps to pid 4763. 2006-12-08 16:46:10 [4763] [0] DEBUG: Started thread 7 (gw/bb_smscconn.c:sms_router) 2006-12-08 16:46:10 [4763] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps to pid 4763. 2006-12-08 16:46:10 [4763] [0] INFO: ---------------------------------------- 2006-12-08 16:46:10 [4763] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread) maps to pid 4763. 2006-12-08 16:46:10 [4763] [7] DEBUG: Thread 7 (gw/bb_smscconn.c:sms_router) maps to pid 4763. 2006-12-08 16:46:10 [4763] [4] DEBUG: Thread 4 (gw/bb_boxc.c:sms_to_smsboxes) maps to pid 4763. 2006-12-08 16:46:10 [4763] [7] DEBUG: sms_router: time to sleep 30.00 secs. 2006-12-08 16:46:10 [4763] [0] INFO: Kannel bearerbox II version 1.4.1 starting 2006-12-08 16:46:10 [4763] [6] DEBUG: Thread 6 (gw/smsc/smsc_at.c:at2_device_thread) maps to pid 4763. 2006-12-08 16:46:10 [4763] [6] INFO: AT2[falcom850]: opening device 2006-12-08 16:46:10 [4763] [0] INFO: Loading store file `kannel.store' 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: device opened 2006-12-08 16:46:10 [4763] [0] INFO: Store-file size 0, starting to unpack 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: device opened 2006-12-08 16:46:10 [4763] [0] INFO: Retrieved 0 messages, non-acknowledged messages: 0 2006-12-08 16:46:10 [4763] [6] INFO: AT2[falcom850]: init device 2006-12-08 16:46:10 [4763] [6] INFO: AT2[falcom850]: speed set to 9600 2006-12-08 16:46:10 [4763] [0] DEBUG: Started thread 8 (gw/bb_store.c:store_dumper) 2006-12-08 16:46:10 [4763] [0] INFO: MAIN: Start-up done, entering mainloop 2006-12-08 16:46:10 [4763] [0] DEBUG: AT2[falcom850]: start called 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: --> ATZ^M 2006-12-08 16:46:10 [4763] [8] DEBUG: Thread 8 (gw/bb_store.c:store_dumper) maps to pid 4763. 2006-12-08 16:46:10 [4763] [7] DEBUG: sms_router: gwlist_len = 0 2006-12-08 16:46:10 [4763] [8] DEBUG: Dumping 0 messages to store 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: --> AT^M 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: <-- AT 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: --> AT&F^M 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: <-- AT&F 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:10 [4763] [6] DEBUG: AT2[falcom850]: --> ATE0^M 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- ATE0 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: --> AT+IFC=2,2^M 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: --> AT+CMGF=0^M 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: --> AT+CSMS=?^M 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- +CSMS: (0,1) 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:11 [4763] [6] INFO: AT2[falcom850]: Phase 2+ is supported 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: --> AT+CSMS=1^M 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- +CSMS: 1,1,1 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: --> AT+CNMI=1,2,0,1,0^M 2006-12-08 16:46:11 [4763] [6] DEBUG: AT2[falcom850]: <-- OK 2006-12-08 16:46:11 [4763] [6] INFO: AT2[falcom850]: AT SMSC successfully opened. 2006-12-08 16:46:18 [4763] [5] INFO: Client connected from <127.0.0.1> 2006-12-08 16:46:18 [4763] [5] DEBUG: Started thread 9 (gw/bb_boxc.c:function) 2006-12-08 16:46:18 [4763] [9] DEBUG: Thread 9 (gw/bb_boxc.c:function) maps to pid 4763. 2006-12-08 16:46:18 [4763] [9] DEBUG: Started thread 10 (gw/bb_boxc.c:boxc_sender) 2006-12-08 16:46:18 [4763] [10] DEBUG: Thread 10 (gw/bb_boxc.c:boxc_sender) maps to pid 4763. 2006-12-08 16:46:28 [4763] [2] DEBUG: HTTP: Creating HTTPClient for `10.0.1.45'. 2006-12-08 16:46:28 [4763] [2] DEBUG: HTTP: Created HTTPClient area 0x96736f0. 2006-12-08 16:46:28 [4763] [3] DEBUG: HTTP: Resetting HTTPClient for `10.0.1.45'. 2006-12-08 16:47:28 [4763] [1] DEBUG: Timeout for fd:33 appeares. 2006-12-08 16:47:28 [4763] [1] DEBUG: HTTP: Destroying HTTPClient area 0x96736f0. 2006-12-08 16:47:28 [4763] [1] DEBUG: HTTP: Destroying HTTPClient for `10.0.1.45'. 2006-12-08 16:48:16 [4763] [9] DEBUG: boxc_receiver: sms received 2006-12-08 16:48:16 [4763] [9] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: TP-Validity-Period: 24.0 hours 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> AT+CMGS=20^M 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: <-- > 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: send command status: 1 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> 11000A8114359072380000A707C737481816E701 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> ^Z 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: <-- > 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: <-- ERROR 2006-12-08 16:48:17 [4763] [6] ERROR: AT2[falcom850]: Generic error: ERROR 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: send command status: -1 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> AT+CMGS=20^M 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: <-- > 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: send command status: 1 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> 11000A8114359072380000A707C737481816E701 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> ^Z 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: <-- > 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: <-- ERROR 2006-12-08 16:48:17 [4763] [6] ERROR: AT2[falcom850]: Generic error: ERROR 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: send command status: -1 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> AT+CMGS=20^M 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: <-- > 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: send command status: 1 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> 11000A8114359072380000A707C737481816E701 2006-12-08 16:48:17 [4763] [6] DEBUG: AT2[falcom850]: --> ^Z 2006-12-08 16:48:18 [4763] [6] DEBUG: AT2[falcom850]: <-- > 2006-12-08 16:48:18 [4763] [6] DEBUG: AT2[falcom850]: <-- ERROR 2006-12-08 16:48:18 [4763] [6] ERROR: AT2[falcom850]: Generic error: ERROR 2006-12-08 16:48:18 [4763] [6] DEBUG: AT2[falcom850]: send command status: -1 2006-12-08 16:48:30 [4763] [8] DEBUG: Dumping 0 messages to store
