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