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
>