Hi,

What you probably intend to do, is to use:

keyword = default

Please read the user guide before asking such questions.

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


>
> 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
>>>>>
>>>>
>>>
>>
>>