Hi,

Sorry for replying in portions. It seems that you have a configuration issue
that prevents correct modem initialization:

In group smsc, modemType should match id in group modems, not name. Name is
text used only in logging.

BR,
Nikos
----- Original Message -----
From: "Nikos Balkanas" <nbalkanas@gmai...>
To: "Nikos Balkanas" <nbalkanas@gmai...>; "Wilhelm Ellmann | Fontera"
<wilhelm@font...>; <users@kann...>
Sent: Thursday, September 17, 2009 1:35 AM
Subject: Re: Trouble setting up GSM Modem


> Also try adding AT+CMEE = 1 or 2 in your init-string to get more detailed
> info from your modem.
>
> BR,
> Nikos
> ----- Original Message -----
> From: "Nikos Balkanas" <nbalkanas@gmai...>
> To: "Wilhelm Ellmann | Fontera" <wilhelm@font...>; <users@kann...>
> Sent: Wednesday, September 16, 2009 11:45 PM
> Subject: Re: Trouble setting up GSM Modem
>
>
>> Hi,
>>
>> There seems to be a problem right after you send the PDU (TPDU 27
>> octets):
>>
>> 0011000B917228283085F70000A70ED272BC5E9ED341C6709A5D2603
>>
>> Does anyone know how to read it?
>>
>> BR,
>> Nikos
>>
>>
>> ----- Original Message -----
>> From: "Wilhelm Ellmann | Fontera" <wilhelm@font...>
>> To: <users@kann...>
>> Sent: Wednesday, September 16, 2009 5:56 PM
>> Subject: Trouble setting up GSM Modem
>>
>>
>>> Hi,
>>>
>>> I seem be having a trouble setting up my GSM modem to work correctly. My
>>> goal is not to send messages through the modem I only want receive
>>> messages and push them on to my script to be processed.
>>>
>>> There are two problems that I am having. The first is that once I have
>>> received a message it looks like to me that the server tries to
>>> acknowledge that it has received the message but runs into a error. This
>>> causes a problem as the message is stored in the store file and the
>>> server continually tries to re-acknowledge.
>>>
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[EDGARSLONG]: send command
>>> status: 1
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[EDGARSLONG]: -->
>>> 0011000B917228283085F70000A70ED272BC5E9ED341C6709A5D2603
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[EDGARSLONG]: --> ^Z
>>> 2009-09-16 15:19:02 [22753] [6] DEBUG: AT2[EDGARSLONG]: <-- >
>>> 2009-09-16 15:19:02 [22753] [6] DEBUG: AT2[EDGARSLONG]: <-- ERROR
>>> 2009-09-16 15:19:02 [22753] [6] ERROR: AT2[EDGARSLONG]: Generic error:
>>> ERROR
>>> 2009-09-16 15:19:02 [22753] [6] DEBUG: AT2[EDGARSLONG]: send command
>>> status: -1
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: handling message
>>> (0x8f35200 vs 0x8f35200)
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: re-queing SMS not-yet-to-be
>>> resent
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: handling message
>>> (0x8f35200 vs 0x8f35200)
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: re-queing SMS not-yet-to-be
>>> resent
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: time to sleep 30.00
>>> secs.
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: gwlist_len = 1
>>>
>>> From what I can find on the internet is that it is a problem with my
>>> init-string in modems config file. Is this correct and how can I find
>>> the correct values that is supposed to be entered in here.
>>>
>>>
>>> My second problem that I am having on receiving the message the sms box
>>> is telling me this ( the snippet below ) and not forwarding the message
>>> on. I am stumped as I am using a similar config on my other services
>>>
>>> 2009-09-16 15:18:55 [22758] [4] DEBUG: match found: ^[ ]*()[ ]*
>>> 2009-09-16 15:18:55 [22758] [4] WARNING: No translation found for <This
>>> is a good test> from <+2782*****87> to <2773*****30>
>>> 2009-09-16 15:18:55 [22758] [4] ERROR: request failed
>>> 2009-09-16 15:18:55 [22758] [4] DEBUG: message length 14, sending 1
>>> messages
>>> 2009-09-16 15:18:55 [22758] [0] DEBUG: Got ACK (0) of
>>> 455e08bb-6081-4820-b600-5fcbe6a8aa90
>>> 2009-09-16 15:18:55 [22758] [0] DEBUG: No client - multi-send or ACK to
>>> pull-reply
>>>
>>> I have attached my config file and logs and would appreciate any help.
>>>
>>> --
>>>
>>> Kind Regards,
>>>
>>> Wilhelm Ellmann
>>>
>>>
>>
>>
>> --------------------------------------------------------------------------------
>>
>>
>>> .
>>> 2009-09-16 15:18:47 [22758] [0] INFO: Added logfile
>>> `/var/log/kannel/smsbox.log' with level `0'.
>>> 2009-09-16 15:18:47 [22758] [0] INFO: Logging accesses to
>>> '/var/log/kannel/smsbox.access'.
>>> 2009-09-16 15:18:47 [22758] [0] INFO: Started access logfile
>>> `/var/log/kannel/smsbox.access'.
>>> 2009-09-16 15:18:47 [22758] [0] INFO: HTTP: Opening server at port
>>> 13013.
>>> 2009-09-16 15:18:47 [22758] [0] DEBUG: Started thread 1
>>> (gwlib/fdset.c:poller)
>>> 2009-09-16 15:18:47 [22758] [0] DEBUG: Started thread 2
>>> (gwlib/http.c:server_thread)
>>> 2009-09-16 15:18:47 [22758] [0] INFO: Set up send sms service at port
>>> 13013
>>> 2009-09-16 15:18:47 [22758] [0] DEBUG: Started thread 3
>>> (gw/smsbox.c:sendsms_thread)
>>> 2009-09-16 15:18:47 [22758] [0]
>>> DEBUG: ----------------------------------------------
>>> 2009-09-16 15:18:47 [22758] [0] DEBUG: Kannel smsbox version 1.4.2
>>> starting
>>> 2009-09-16 15:18:47 [22758] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
>>> maps to pid 22758.
>>> 2009-09-16 15:18:47 [22758] [2] DEBUG: Thread 2
>>> (gwlib/http.c:server_thread) maps to pid 22758.
>>> 2009-09-16 15:18:47 [22758] [3] DEBUG: Thread 3
>>> (gw/smsbox.c:sendsms_thread) maps to pid 22758.
>>> 2009-09-16 15:18:47 [22758] [0] DEBUG: Started thread 4
>>> (gw/smsbox.c:obey_request_thread)
>>> 2009-09-16 15:18:47 [22758] [0] DEBUG: Started thread 5
>>> (gw/smsbox.c:url_result_thread)
>>> 2009-09-16 15:18:47 [22758] [0] DEBUG: Started thread 6
>>> (gw/smsbox.c:http_queue_thread)
>>> 2009-09-16 15:18:47 [22758] [0] INFO: Connected to bearerbox at
>>> 127.0.0.1 port 13001.
>>> 2009-09-16 15:18:47 [22758] [0] DEBUG: Started thread 7
>>> (gw/heartbeat.c:heartbeat_thread)
>>> 2009-09-16 15:18:47 [22758] [4] DEBUG: Thread 4
>>> (gw/smsbox.c:obey_request_thread) maps to pid 22758.
>>> 2009-09-16 15:18:47 [22758] [5] DEBUG: Thread 5
>>> (gw/smsbox.c:url_result_thread) maps to pid 22758.
>>> 2009-09-16 15:18:47 [22758] [6] DEBUG: Thread 6
>>> (gw/smsbox.c:http_queue_thread) maps to pid 22758.
>>> 2009-09-16 15:18:47 [22758] [7] DEBUG: Thread 7
>>> (gw/heartbeat.c:heartbeat_thread) maps to pid 22758.
>>> 2009-09-16 15:18:55 [22758] [4] DEBUG: match found: ^[ ]*()[ ]*
>>> 2009-09-16 15:18:55 [22758] [4] WARNING: No translation found for <This
>>> is a good test> from <+2782*****87> to <2773*****30>
>>> 2009-09-16 15:18:55 [22758] [4] ERROR: request failed
>>> 2009-09-16 15:18:55 [22758] [4] DEBUG: message length 14, sending 1
>>> messages
>>> 2009-09-16 15:18:55 [22758] [0] DEBUG: Got ACK (0) of
>>> 455e08bb-6081-4820-b600-5fcbe6a8aa90
>>> 2009-09-16 15:18:55 [22758] [0] DEBUG: No client - multi-send or ACK to
>>> pull-reply
>>>
>>
>>
>> --------------------------------------------------------------------------------
>>
>>
>>> .
>>> 2009-09-16 15:18:47 Log begins
>>> 2009-09-16 15:18:55 Receive SMS [SMSC:EDGARSLONG] [SVC:] [ACT:] [BINF:]
>>> [FID:] [from:+27828203587] [to:27730920930] [flags:-1:0:-1:0:-1]
>>> [msg:19:This is a good test] [udh:0:]
>>> 2009-09-16 15:19:28 Log ends
>>>
>>
>>
>> --------------------------------------------------------------------------------
>>
>>
>>> .
>>> 2009-09-16 15:18:47 [22753] [0] INFO: Added logfile
>>> `/var/log/kannel/bearerbox.log' with level `0'.
>>> 2009-09-16 15:18:47 [22753] [0] INFO: Started access logfile
>>> `/var/log/kannel/bearerbox.access'.
>>> 2009-09-16 15:18:47 [22753] [0] INFO: HTTP: Opening server at port
>>> 13000.
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 1
>>> (gwlib/fdset.c:poller)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 2
>>> (gwlib/http.c:server_thread)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 3
>>> (gw/bb_http.c:httpadmin_run)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: starting smsbox connection module
>>> 2009-09-16 15:18:47 [22753] [0] INFO: BOXC: 'smsbox-max-pending' not
>>> set, using default (100).
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 4
>>> (gw/bb_boxc.c:sms_to_smsboxes)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 5
>>> (gw/bb_boxc.c:smsboxc_run)
>>> 2009-09-16 15:18:47 [22753] [0] INFO: Set SMS resend frequency to 60
>>> seconds.
>>> 2009-09-16 15:18:47 [22753] [0] INFO: SMS resend retry set to unlimited.
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: smsbox MO concatenated message
>>> handling enabled
>>> 2009-09-16 15:18:47 [22753] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
>>> maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [2] DEBUG: Thread 2
>>> (gwlib/http.c:server_thread) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [3] DEBUG: Thread 3
>>> (gw/bb_http.c:httpadmin_run) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [4] DEBUG: Thread 4
>>> (gw/bb_boxc.c:sms_to_smsboxes) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [5] DEBUG: Thread 5
>>> (gw/bb_boxc.c:smsboxc_run) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [0] INFO: DLR rerouting for smsc id
>>> <TESTLONG> disabled.
>>> 2009-09-16 15:18:47 [22753] [0] INFO: AT2[TESTLONG]: configuration shows
>>> modemtype <TestModem>
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: AT2[TESTLONG]: Reading modem
>>> definitions from </etc/kannel.conf>
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: AT2[TESTLONG]: Found <1> modems
>>> in config
>>> 2009-09-16 15:18:47 [22753] [0] INFO: AT2[TESTLONG]: modemtype not
>>> found, revert to autodetect
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 6
>>> (gw/smsc/smsc_at.c:at2_device_thread)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 7
>>> (gw/bb_smscconn.c:sms_router)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: starting UDP sender/receiver
>>> module
>>> 2009-09-16 15:18:47 [22753] [0] INFO: Adding interface *
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: udpc_create: Bound to UDP
>>> <0.0.0.0:9200>
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 8
>>> (gw/bb_udp.c:udp_receiver)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 9
>>> (gw/bb_udp.c:udp_sender)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: udpc_create: Bound to UDP
>>> <0.0.0.0:9201>
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 10
>>> (gw/bb_udp.c:udp_receiver)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 11
>>> (gw/bb_udp.c:udp_sender)
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: starting wapbox connection module
>>> 2009-09-16 15:18:47 [22753] [0] ERROR: Missing wapbox-port variable,
>>> cannot start WAP
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: starting WDP router
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 12
>>> (gw/bearerbox.c:wdp_router)
>>> 2009-09-16 15:18:47 [22753] [0]
>>> INFO: ----------------------------------------
>>> 2009-09-16 15:18:47 [22753] [0] INFO: Kannel bearerbox II version 1.4.2
>>> starting
>>> 2009-09-16 15:18:47 [22753] [0] INFO: Loading store file
>>> `/var/log/kannel/kannel.store'
>>> 2009-09-16 15:18:47 [22753] [0] INFO: Store-file size 0, starting to
>>> unpack
>>> 2009-09-16 15:18:47 [22753] [0] INFO: Retrieved 0 messages,
>>> non-acknowledged messages: 0
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: Started thread 13
>>> (gw/bb_store_file.c:store_dumper)
>>> 2009-09-16 15:18:47 [22753] [0] INFO: MAIN: Start-up done, entering
>>> mainloop
>>> 2009-09-16 15:18:47 [22753] [0] DEBUG: AT2[TESTLONG]: start called
>>> 2009-09-16 15:18:47 [22753] [6] DEBUG: Thread 6
>>> (gw/smsc/smsc_at.c:at2_device_thread) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [6] DEBUG: AT2[TESTLONG]: detecting modem
>>> type
>>> 2009-09-16 15:18:47 [22753] [6] INFO: AT2[TESTLONG]: opening device
>>> 2009-09-16 15:18:47 [22753] [13] DEBUG: Thread 13
>>> (gw/bb_store_file.c:store_dumper) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [13] DEBUG: Dumping 0 messages to store
>>> 2009-09-16 15:18:47 [22753] [7] DEBUG: Thread 7
>>> (gw/bb_smscconn.c:sms_router) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [8] DEBUG: Thread 8
>>> (gw/bb_udp.c:udp_receiver) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [9] DEBUG: Thread 9 (gw/bb_udp.c:udp_sender)
>>> maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [10] DEBUG: Thread 10
>>> (gw/bb_udp.c:udp_receiver) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [11] DEBUG: Thread 11
>>> (gw/bb_udp.c:udp_sender) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [12] DEBUG: Thread 12
>>> (gw/bearerbox.c:wdp_router) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [12] DEBUG: udp_die: removing producers from
>>> udp-lists
>>> 2009-09-16 15:18:47 [22753] [12] DEBUG: Thread 12
>>> (gw/bearerbox.c:wdp_router) terminates.
>>> 2009-09-16 15:18:47 [22753] [5] INFO: Client connected from <127.0.0.1>
>>> 2009-09-16 15:18:47 [22753] [5] DEBUG: Started thread 14
>>> (gw/bb_boxc.c:function)
>>> 2009-09-16 15:18:47 [22753] [14] DEBUG: Thread 14
>>> (gw/bb_boxc.c:function) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [14] DEBUG: Started thread 15
>>> (gw/bb_boxc.c:boxc_sender)
>>> 2009-09-16 15:18:47 [22753] [15] DEBUG: Thread 15
>>> (gw/bb_boxc.c:boxc_sender) maps to pid 22753.
>>> 2009-09-16 15:18:47 [22753] [6] DEBUG: AT2[TESTLONG]: device opened.
>>> Telnet mode = 0
>>> 2009-09-16 15:18:48 [22753] [6] DEBUG: AT2[TESTLONG]: device opened
>>> 2009-09-16 15:18:48 [22753] [6] INFO: AT2[TESTLONG]: speed set to 57600
>>> 2009-09-16 15:18:48 [22753] [6] DEBUG: AT2[TESTLONG]: --> ^M
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT^M
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- AT
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT&F^M
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- AT&F
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: --> ATE0^M
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- ATE0
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: --> ATI^M
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- MO200 GPRS
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: found string
>>> <MO200>, using modem definition <TestModem>
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT+CSMS=?^M
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- +CSMS: (0)
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:50 [22753] [6] INFO: AT2[TESTLONG]: Closing device
>>> 2009-09-16 15:18:50 [22753] [6] INFO: AT2[TESTLONG]: opening device
>>> 2009-09-16 15:18:50 [22753] [6] DEBUG: AT2[TESTLONG]: device opened.
>>> Telnet mode = 0
>>> 2009-09-16 15:18:51 [22753] [6] DEBUG: AT2[TESTLONG]: device opened
>>> 2009-09-16 15:18:51 [22753] [6] INFO: AT2[TESTLONG]: Logging in
>>> 2009-09-16 15:18:52 [22753] [6] INFO: AT2[TESTLONG]: init device
>>> 2009-09-16 15:18:52 [22753] [6] INFO: AT2[TESTLONG]: speed set to 57600
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: --> ATZ^M
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT^M
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- AT
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT&F^M
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- AT&F
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: --> ATE0^M
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- ATE0
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT+CMGF=0^M
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT+CSMS=?^M
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- +CSMS: (0)
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: -->
>>> AT+CNMI=2,0,0,1,0^M
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:52 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT+CPMS="SM"^M
>>> 2009-09-16 15:18:53 [22753] [6] DEBUG: AT2[TESTLONG]: <-- +CPMS:
>>> 1,25,0,100,1,125
>>> 2009-09-16 15:18:53 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:53 [22753] [6] INFO: AT2[TESTLONG]: AT SMSC
>>> successfully opened.
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT+CPMS?^M
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: <-- +CPMS:
>>> "SM",1,25,"ME",0,100,"MT",1,125
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: 1 messages waiting
>>> in memory
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT+CMGR=1^M
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: <-- +CMGR: 0,,36
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: <--  
>>> 06917228195329240B917228283085F70000909061512103801354747A0E4ACF4161D0F9FD2683E8E5391D
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: received message
>>> from SMSC: +2782913592
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: Numeric sender
>>> (international) <+2782*****87>
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: User data length
>>> read as (19)
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: Udh decoding done
>>> len=19 udhi=0 udhlen=0 udh=''
>>> 2009-09-16 15:18:55 [22753] [15] DEBUG: send_msg: sending msg to box:
>>> <127.0.0.1>
>>> 2009-09-16 15:18:55 [22753] [15] DEBUG: boxc_sender: sent message to
>>> <127.0.0.1>
>>> 2009-09-16 15:18:55 [22753] [14] DEBUG: boxc_receiver: sms received
>>> 2009-09-16 15:18:55 [22753] [14] DEBUG: send_msg: sending msg to box:
>>> <127.0.0.1>
>>> 2009-09-16 15:18:55 [22753] [14] DEBUG: boxc_receiver: got ack
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:55 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT+CMGD=1^M
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[TESTLONG]: <-- OK
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[TESTLONG]:
>>> TP-Validity-Period: 24.0 hours
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[TESTLONG]: --> AT+CMGS=27^M
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[TESTLONG]: <-- >
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[TESTLONG]: send command
>>> status: 1
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[TESTLONG]: -->
>>> 0011000B917228283085F70000A70ED272BC5E9ED341C6709A5D2603
>>> 2009-09-16 15:18:56 [22753] [6] DEBUG: AT2[TESTLONG]: --> ^Z
>>> 2009-09-16 15:19:02 [22753] [6] DEBUG: AT2[TESTLONG]: <-- >
>>> 2009-09-16 15:19:02 [22753] [6] DEBUG: AT2[TESTLONG]: <-- ERROR
>>> 2009-09-16 15:19:02 [22753] [6] ERROR: AT2[TESTLONG]: Generic error:
>>> ERROR
>>> 2009-09-16 15:19:02 [22753] [6] DEBUG: AT2[TESTLONG]: send command
>>> status: -1
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: handling message
>>> (0x8f35200 vs 0x8f35200)
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: re-queing SMS not-yet-to-be
>>> resent
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: handling message
>>> (0x8f35200 vs 0x8f35200)
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: re-queing SMS not-yet-to-be
>>> resent
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: time to sleep 30.00
>>> secs.
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: gwlist_len = 1
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: handling message
>>> (0x8f35200 vs 0x8f35200)
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: re-queing SMS not-yet-to-be
>>> resent
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: handling message
>>> (0x8f35200 vs 0x8f35200)
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: re-queing SMS not-yet-to-be
>>> resent
>>> 2009-09-16 15:19:02 [22753] [7] DEBUG: sms_router: time to sleep 30.00
>>> secs.
>>> 2009-09-16 15:19:07 [22753] [13] DEBUG: Dumping 1 messages to store
>>> 2009-09-16 15:19:17 [22753] [14] DEBUG: boxc_receiver: heartbeat with
>>> load value 0 received
>>> 2009-09-16 15:19:26 [22753] [14] INFO: Connection closed by the box
>>> <127.0.0.1>
>>> 2009-09-16 15:19:26 [22753] [15] DEBUG: send_msg: sending msg to box:
>>> <127.0.0.1>
>>> 2009-09-16 15:19:26 [22753] [15] DEBUG: Thread 15
>>> (gw/bb_boxc.c:boxc_sender) terminates.
>>> 2009-09-16 15:19:26 [22753] [14] DEBUG: Thread 14
>>> (gw/bb_boxc.c:function) terminates.
>>> 2009-09-16 15:19:26 [22753] [0] WARNING: Killing signal or HTTP admin
>>> command received, shutting down...
>>> 2009-09-16 15:19:26 [22753] [0] DEBUG: Shutting down Kannel...
>>> 2009-09-16 15:19:26 [22753] [0] DEBUG: shutting down smsc
>>> 2009-09-16 15:19:26 [22753] [0] DEBUG: AT2[TESTLONG]: Shutting down
>>> SMSCConn, slow
>>> 2009-09-16 15:19:26 [22753] [0] DEBUG: shutting down udp
>>> 2009-09-16 15:19:26 [22753] [7] DEBUG: sms_router: gwlist_len = 1
>>> 2009-09-16 15:19:26 [22753] [7] DEBUG: sms_router: handling message
>>> (0x8f35200 vs 0x8f35200)
>>> 2009-09-16 15:19:26 [22753] [7] DEBUG: Routing failed, re-queued.
>>> 2009-09-16 15:19:26 [22753] [7] DEBUG: Thread 7
>>> (gw/bb_smscconn.c:sms_router) terminates.
>>> 2009-09-16 15:19:26 [22753] [8] DEBUG: Thread 8
>>> (gw/bb_udp.c:udp_receiver) terminates.
>>> 2009-09-16 15:19:26 [22753] [9] DEBUG: Thread 9 (gw/bb_udp.c:udp_sender)
>>> terminates.
>>> 2009-09-16 15:19:26 [22753] [10] DEBUG: Thread 10
>>> (gw/bb_udp.c:udp_receiver) terminates.
>>> 2009-09-16 15:19:26 [22753] [11] DEBUG: Thread 11
>>> (gw/bb_udp.c:udp_sender) terminates.
>>> 2009-09-16 15:19:28 [22753] [6] INFO: AT2[TESTLONG]: Closing device
>>> 2009-09-16 15:19:28 [22753] [6] DEBUG: Thread 6
>>> (gw/smsc/smsc_at.c:at2_device_thread) terminates.
>>> 2009-09-16 15:19:28 [22753] [4] DEBUG: Thread 4
>>> (gw/bb_boxc.c:sms_to_smsboxes) terminates.
>>> 2009-09-16 15:19:28 [22753] [5] DEBUG: Thread 5
>>> (gw/bb_boxc.c:smsboxc_run) terminates.
>>> 2009-09-16 15:19:28 [22753] [0] INFO: All flow threads have died,
>>> killing core
>>> 2009-09-16 15:19:28 [22753] [0] DEBUG: Waiting for 2
>>> (gwlib/http.c:server_thread) to terminate
>>> 2009-09-16 15:19:28 [22753] [3] DEBUG: HTTP: No clients with requests,
>>> quitting.
>>> 2009-09-16 15:19:28 [22753] [3] DEBUG: Thread 3
>>> (gw/bb_http.c:httpadmin_run) terminates.
>>> 2009-09-16 15:19:28 [22753] [2] DEBUG: Thread 2
>>> (gwlib/http.c:server_thread) terminates.
>>> 2009-09-16 15:19:28 [22753] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
>>> terminates.
>>> 2009-09-16 15:19:28 [22753] [0] DEBUG: final clean-up for SMSCConn
>>> 2009-09-16 15:19:28 [22753] [0] DEBUG: smsbox MO concatenated message
>>> handling cleaned up
>>> 2009-09-16 15:19:28 [22753] [13] DEBUG: Dumping 1 messages to store
>>> 2009-09-16 15:19:28 [22753] [13] DEBUG: Thread 13
>>> (gw/bb_store_file.c:store_dumper) terminates.
>>> 2009-09-16 15:19:28 [22753] [0] INFO: Total WDP messages: received 0,
>>> sent 0
>>> 2009-09-16 15:19:28 [22753] [0] INFO: Total SMS messages: received 1,
>>> sent 0
>>> 2009-09-16 15:19:28 [22753] [0] DEBUG: Immutable octet strings: 245.
>>>
>>
>>
>> --------------------------------------------------------------------------------
>>
>>
>>># Bearerbox configuration file
>>> group = core
>>> admin-port = 13000
>>> admin-password = test
>>> status-password = test
>>> admin-deny-ip = "*.*.*.*"
>>> admin-allow-ip = "127.0.0.1;192.168.0.*"
>>> smsbox-port = 13001
>>> #wapbox-port = 13002
>>> box-deny-ip = "*.*.*.*"
>>> box-allow-ip = "127.0.0.1"
>>> udp-deny-ip = "*.*.*.*"
>>> udp-deny-ip = "127.0.0.1"
>>> wdp-interface-name = "*"
>>> log-file = "/var/log/kannel/bearerbox.log"
>>> log-level = 0
>>> access-log = "/var/log/kannel/bearerbox.access"
>>> store-type = file
>>> store-location = "/var/log/kannel/kannel.store"
>>> dlr-storage = internal
>>>
>>> ## Wapbox Setup
>>> #group = wapbox
>>> #bearerbox-host = "127.0.0.1"
>>> #log-file = "/var/log/kannel/wapbox.log"
>>> #log-level = 1
>>> #access-log = "/var/log/kannel/wapbox.access"
>>>
>>> # SmsBox Setup
>>> group = smsbox
>>> bearerbox-host = 127.0.0.1
>>> sendsms-port = 13013
>>> log-file = "/var/log/kannel/smsbox.log"
>>> log-level = 0
>>> access-log = "/var/log/kannel/smsbox.access"
>>>
>>> # Edgars long number 073 *** **30
>>> group = smsc
>>> smsc = at
>>> modemtype = TestModem
>>> device = /dev/ttyUSB0
>>> speed = 57600
>>> my-number = 2773*****30
>>> sim-buffering = true
>>> smsc-id = TESTLONG
>>> allowed-smsc-id = TESTLONG
>>>
>>> group = modems
>>> id = modem1
>>> name = TestModem
>>> detect-string = MO200
>>> #detect-string2 = GPRS
>>> init-string = "AT+CNMI=2,0,0,1,0"
>>> enable-hwhs = ""
>>> speed = 57600
>>> need-sleep = true
>>> no-pin = true
>>> sendline-sleep = 100
>>> keepalive-cmd = "AT+CBC;+CSQ"
>>> message-storage = "SM"
>>> #enable-mms = false
>>>
>>> group = sms-service
>>> keyword =
>>> #accepted-smsc = TESTLONG
>>> get-url =
>>> "http://test.local/engine.php?sender=%p&destination=%P&text=%b&smsc=%i&msgid=%I&operator=%o"
>>> max-messages = 0
>>> assume-plain-text = true
>>> omit-empty = true
>>>
>>
>>
>> --------------------------------------------------------------------------------
>>
>>
>>> .
>>>
>>
>>
>> --------------------------------------------------------------------------------
>>
>>
>>> .
>>> 2009-09-16 15:18:47 Log begins
>>>
>>
>