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"
<[email protected]>
To: <[email protected]>
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