Hi Nikos,

Thank you for the response. This morning I went and made the change to
the modem type field to use the id and not the name. After making that
change I decided to try and fix the WARNING: No translation found for in
the sms box. What I found after a while was that SMS-SERVICE config is
incorrect. It looks like I can not set the keyword to being blank.

#Kannel.conf

group = sms-service
keyword-regex = .*
catch-all = yes
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

# End of kannel.conf


Now when Kannel is running I do not seem to get the PDU. I was wondering
if some could clarify if this means that the  problem is solved or that
because the  message is now processed  correctly that the event that
causes the error does not occur.

Kind Regards,

Wilhelm Ellmann



Nikos Balkanas wrote:
> 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
>>>>
>>>
>>
>
>