> > Yes, I use SMPP SMSC. > bearerbox log: > 2013-02-25 14:04:51 [3768] [6] DEBUG: SMPP[xxxxx]: throughput (0.00,1.00) > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter tag (0x000e) > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter length read as 1 > 2013-02-25 14:04:51 [3768] [6] WARNING: SMPP: Unknown > TLV(0x000e,0x0001,01) for PDU type (deliver_sm) received! > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter tag (0x0006) > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter length read as 1 > 2013-02-25 14:04:51 [3768] [6] WARNING: SMPP: Unknown > TLV(0x0006,0x0001,01) for PDU type (deliver_sm) received! > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter tag (0x020c) > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter length read as 2 > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter tag (0x020e) > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter length read as 1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter tag (0x020f) > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter length read as 1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter tag (0x0424) > 2013-02-25 14:04:51 [3768] [6] DEBUG: Optional parameter length read as 27 > 2013-02-25 14:04:51 [3768] [6] DEBUG: SMPP[xxxxx]: Got PDU: > 2013-02-25 14:04:51 [3768] [6] DEBUG: SMPP PDU 0x8dc3190 dump: > 2013-02-25 14:04:51 [3768] [6] DEBUG: type_name: deliver_sm > 2013-02-25 14:04:51 [3768] [6] DEBUG: command_id: 5 = 0x00000005 > 2013-02-25 14:04:51 [3768] [6] DEBUG: command_status: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sequence_number: 3 = 0x00000003 > 2013-02-25 14:04:51 [3768] [6] DEBUG: service_type: NULL > 2013-02-25 14:04:51 [3768] [6] DEBUG: source_addr_ton: 1 = 0x00000001 > 2013-02-25 14:04:51 [3768] [6] DEBUG: source_addr_npi: 1 = 0x00000001 > 2013-02-25 14:04:51 [3768] [6] DEBUG: source_addr: "923029422885" > 2013-02-25 14:04:51 [3768] [6] DEBUG: dest_addr_ton: 4 = 0x00000004 > 2013-02-25 14:04:51 [3768] [6] DEBUG: dest_addr_npi: 1 = 0x00000001 > 2013-02-25 14:04:51 [3768] [6] DEBUG: destination_addr: "1767" > 2013-02-25 14:04:51 [3768] [6] DEBUG: esm_class: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: protocol_id: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: priority_flag: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: schedule_delivery_time: NULL > 2013-02-25 14:04:51 [3768] [6] DEBUG: validity_period: NULL > 2013-02-25 14:04:51 [3768] [6] DEBUG: registered_delivery: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: replace_if_present_flag: 0 = > 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data_coding: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sm_default_msg_id: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sm_length: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: short_message: "" > 2013-02-25 14:04:51 [3768] [6] DEBUG: sar_msg_ref_num: 87 = 0x00000057 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sar_total_segments: 2 = 0x00000002 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sar_segment_seqnum: 2 = 0x00000002 > 2013-02-25 14:04:51 [3768] [6] DEBUG: message_payload: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string at 0x8dc4478: > 2013-02-25 14:04:51 [3768] [6] DEBUG: len: 27 > 2013-02-25 14:04:51 [3768] [6] DEBUG: size: 28 > 2013-02-25 14:04:51 [3768] [6] DEBUG: immutable: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: 78 20 6a 75 6d 70 73 20 > 6f 76 65 72 20 74 68 65 x jumps over the > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: 20 6c 61 7a 79 20 64 6f > 67 2e 20 lazy dog. > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: SMPP PDU dump ends. > 2013-02-25 14:04:51 [3768] [6] ERROR: Failed to convert string from <gsm> > to <UTF-8> - probably broken type names. > 2013-02-25 14:04:51 [3768] [6] ERROR: Failed to convert msgdata from > charset <gsm> to <UTF-8>, will leave as is. > 2013-02-25 14:04:51 [3768] [6] DEBUG: Got part 255 [ref 2, total parts > 255] of message from xxxxx. Dump follows: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Msg object at 0x8dcf538: > 2013-02-25 14:04:51 [3768] [6] DEBUG: type: sms > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.sender: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string at 0x8dcf608: > 2013-02-25 14:04:51 [3768] [6] DEBUG: len: 13 > 2013-02-25 14:04:51 [3768] [6] DEBUG: size: 14 > 2013-02-25 14:04:51 [3768] [6] DEBUG: immutable: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: xxxxx xxxxx > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.receiver: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string at 0x8dcf638: > 2013-02-25 14:04:51 [3768] [6] DEBUG: len: 4 > 2013-02-25 14:04:51 [3768] [6] DEBUG: size: 5 > 2013-02-25 14:04:51 [3768] [6] DEBUG: immutable: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: > xxxxxxxx xxxxxxxx > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.udhdata: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string at 0x8dcf650: > 2013-02-25 14:04:51 [3768] [6] DEBUG: len: 6 > 2013-02-25 14:04:51 [3768] [6] DEBUG: size: 7 > 2013-02-25 14:04:51 [3768] [6] DEBUG: immutable: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: 05 00 03 02 ff > ff ...... > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.msgdata: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string at 0x8dcf678: > 2013-02-25 14:04:51 [3768] [6] DEBUG: len: 27 > 2013-02-25 14:04:51 [3768] [6] DEBUG: size: 28 > 2013-02-25 14:04:51 [3768] [6] DEBUG: immutable: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: 78 20 6a 75 6d 70 73 20 6f > 76 65 72 20 74 68 65 x jumps over the > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: 20 6c 61 7a 79 20 64 6f 67 > 2e 20 lazy dog. > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.time: 1361783091 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.smsc_id: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string at 0x8dcf690: > 2013-02-25 14:04:51 [3768] [6] DEBUG: len: 7 > 2013-02-25 14:04:51 [3768] [6] DEBUG: size: 8 > 2013-02-25 14:04:51 [3768] [6] DEBUG: immutable: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: 4c 41 5f 31 37 36 > 37 xxxxx > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.smsc_number: > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.foreign_id: > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.service: > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.account: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string at 0x8dcf6b8: > 2013-02-25 14:04:51 [3768] [6] DEBUG: len: 5 > 2013-02-25 14:04:51 [3768] [6] DEBUG: size: 6 > 2013-02-25 14:04:51 [3768] [6] DEBUG: immutable: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: > xxxxxxxxxxxxx xxxxx > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.id: > 864f6df5-3944-4d67-bcf1-b8d31162ed06 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.sms_type: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.mclass: -1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.mwi: -1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.coding: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.compress: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.validity: -1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.deferred: -1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.dlr_mask: -1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.dlr_url: > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.pid: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.alt_dcs: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.rpi: -1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.charset: > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.boxc_id: > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.binfo: > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.msg_left: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.split_parts: (nil) > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.priority: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.resend_try: -1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.resend_time: -1 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sms.meta_data: > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string at 0x8dcf6e0: > 2013-02-25 14:04:51 [3768] [6] DEBUG: len: 6 > 2013-02-25 14:04:51 [3768] [6] DEBUG: size: 7 > 2013-02-25 14:04:51 [3768] [6] DEBUG: immutable: 0 > 2013-02-25 14:04:51 [3768] [6] DEBUG: data: 3f 73 6d 70 70 > 3f ?smpp? > 2013-02-25 14:04:51 [3768] [6] DEBUG: Octet string dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: Msg object ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: SMPP[xxxxx]: Sending PDU: > 2013-02-25 14:04:51 [3768] [6] DEBUG: SMPP PDU 0x8dc32a8 dump: > 2013-02-25 14:04:51 [3768] [6] DEBUG: type_name: deliver_sm_resp > 2013-02-25 14:04:51 [3768] [6] DEBUG: command_id: 2147483653 = > 0x80000005 > 2013-02-25 14:04:51 [3768] [6] DEBUG: command_status: 0 = 0x00000000 > 2013-02-25 14:04:51 [3768] [6] DEBUG: sequence_number: 3 = 0x00000003 > 2013-02-25 14:04:51 [3768] [6] DEBUG: message_id: NULL > 2013-02-25 14:04:51 [3768] [6] DEBUG: SMPP PDU dump ends. > 2013-02-25 14:04:51 [3768] [6] DEBUG: SMPP[xxxxx]: throughput (0.00,1.00) > 2013-02-25 14:04:54 [3768] [3] DEBUG: HTTP: Resetting HTTPClient for > `xxxxx'. > 2013-02-25 14:04:55 [3768] [3] DEBUG: HTTP: Resetting HTTPClient for > `xxxxx'. > > > SMSBOX: > 2013-02-25 13:55:44 [3776] [0] INFO: Starting to log to file > /var/log/kannel/smsbox.log level 0 > 2013-02-25 13:55:44 [3776] [0] INFO: Added logfile > `/var/log/kannel/smsbox.log' with level `0'. > 2013-02-25 13:55:44 [3776] [0] INFO: Service global sender set as '13013' > 2013-02-25 13:55:44 [3776] [0] INFO: HTTP: Opening server at port 13013. > 2013-02-25 13:55:44 [3776] [0] DEBUG: Started thread 1 > (gwlib/fdset.c:poller) > 2013-02-25 13:55:44 [3776] [0] DEBUG: Started thread 2 > (gwlib/http.c:server_thread) > 2013-02-25 13:55:44 [3776] [0] INFO: Set up send sms service at port 13013 > 2013-02-25 13:55:44 [3776] [0] DEBUG: Started thread 3 > (gw/smsbox.c:sendsms_thread) > 2013-02-25 13:55:44 [3776] [0] DEBUG: > ---------------------------------------------- > 2013-02-25 13:55:44 [3776] [0] DEBUG: Kannel smsbox version svn-r5024M > starting > 2013-02-25 13:55:44 [3776] [0] DEBUG: dumping group (sendsms-user): > 2013-02-25 13:55:44 [3776] [0] DEBUG: <group> = <sendsms-user> > 2013-02-25 13:55:44 [3776] [0] DEBUG: <user-deny-ip> = <*.*.*.*> > 2013-02-25 13:55:44 [3776] [0] DEBUG: <forced-smsc> = <xxxxxx> > 2013-02-25 13:55:44 [3776] [0] DEBUG: <password> = <abc> > 2013-02-25 13:55:44 [3776] [0] DEBUG: <max-messages> = <20> > 2013-02-25 13:55:44 [3776] [0] DEBUG: <username> = <test> > 2013-02-25 13:55:44 [3776] [0] DEBUG: <concatenation> = <true> > 2013-02-25 13:55:44 [3776] [0] DEBUG: <user-allow-ip> = <127.0.0.1> > 2013-02-25 13:55:44 [3776] [0] DEBUG: Started thread 4 > (gw/smsbox.c:obey_request_thread) > 2013-02-25 13:55:44 [3776] [0] DEBUG: Started thread 5 > (gw/smsbox.c:url_result_thread) > 2013-02-25 13:55:44 [3776] [0] DEBUG: Started thread 6 > (gw/smsbox.c:http_queue_thread) > 2013-02-25 13:55:44 [3776] [3] DEBUG: Thread 3 > (gw/smsbox.c:sendsms_thread) maps to pid 3776. > 2013-02-25 13:55:44 [3776] [0] DEBUG: Connecting to <127.0.0.1> > 2013-02-25 13:55:44 [3776] [2] DEBUG: Thread 2 > (gwlib/http.c:server_thread) maps to pid 3776. > 2013-02-25 13:55:44 [3776] [2] DEBUG: HTTP: Including port 13013, fd 9 for > polling in server thread > 2013-02-25 13:55:44 [3776] [0] INFO: Connected to bearerbox at 127.0.0.1 > port 13001. > 2013-02-25 13:55:44 [3776] [0] DEBUG: Started thread 7 > (gw/heartbeat.c:heartbeat_thread) > 2013-02-25 13:55:44 [3776] [6] DEBUG: Thread 6 > (gw/smsbox.c:http_queue_thread) maps to pid 3776. > 2013-02-25 13:55:44 [3776] [5] DEBUG: Thread 5 > (gw/smsbox.c:url_result_thread) maps to pid 3776. > 2013-02-25 13:55:44 [3776] [4] DEBUG: Thread 4 > (gw/smsbox.c:obey_request_thread) maps to pid 3776. > 2013-02-25 13:55:44 [3776] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps > to pid 3776. > 2013-02-25 13:55:44 [3776] [7] DEBUG: Thread 7 > (gw/heartbeat.c:heartbeat_thread) maps to pid 3776. > 2013-02-25 13:56:10 [3776] [2] DEBUG: HTTP: Creating HTTPClient for > `127.0.0.1'. > 2013-02-25 13:56:10 [3776] [2] DEBUG: HTTP: Created HTTPClient area > 0x90f9340. > 2013-02-25 13:56:10 [3776] [3] INFO: smsbox: Got HTTP request > </cgi-bin/sendsms> from <127.0.0.1> > 2013-02-25 13:56:10 [3776] [3] INFO: sendsms used by <test> > 2013-02-25 13:56:10 [3776] [3] INFO: sendsms sender:<test:FrfNOC> > (127.0.0.1) to:<xxxxxxxxxxxxx> msg:<nop> > 2013-02-25 13:56:10 [3776] [3] DEBUG: Stored UUID > eef26f83-7f30-43e7-8c4b-e45b8057451f > 2013-02-25 13:56:10 [3776] [3] DEBUG: message length 3, sending 1 messages > 2013-02-25 13:56:10 [3776] [3] DEBUG: Status: 202 Answer: <Sent.> > 2013-02-25 13:56:10 [3776] [3] DEBUG: Delayed reply - wait for bearerbox > 2013-02-25 13:56:10 [3776] [0] DEBUG: Got ACK (0) of > eef26f83-7f30-43e7-8c4b-e45b8057451f > 2013-02-25 13:56:10 [3776] [0] DEBUG: HTTP: Destroying HTTPClient area > 0x90f9340. > 2013-02-25 13:56:10 [3776] [0] DEBUG: HTTP: Destroying HTTPClient for > `127.0.0.1'. > > > > On Mon, Feb 25, 2013 at 2:12 PM, Alexander Malysh <amal...@kannel.org>wrote: > >> Hi, >> >> do you use SMPP SMSC? Could you please provide bearerbox & smsbox debug >> log? >> >> Alex >> >> Am 25.02.2013 um 07:51 schrieb [Digital^Dude] ® <millennium....@gmail.com >> >: >> >> Hello, >> >> Since I have upgraded from *r5014*, I am unable to receive any hit for >> inbound sms in my bearerbox. Kannel status does show the correct number of >> sms received, but I cannot see the message details in any log. And hence, I >> am unable to send an automated reply on incoming messages. Debug mode >> logging of sms also does not facilitate. >> >> *OS:* >> Ubuntu 11.04 natty >> *Kannel:* >> URL: https://svn.kannel.org/gateway/trunk >> Repository Root: https://svn.kannel.org/gateway >> Repository UUID: a7d65f57-29cd-40c9-b71e-ebca51ad1b1d >> Revision: 5024 >> Node Kind: directory >> Schedule: normal >> Last Changed Author: amalysh >> Last Changed Rev: 5024 >> Last Changed Date: 2013-02-21 22:01:03 +0500 (Thu, 21 Feb 2013) >> *gw/smskannel.conf*: >> group = sms-service >> keyword = default >> text = "No service specified" >> alt-charset=gsm >> forced-smsc=XXXXX >> concatenation=true >> catch-all=true >> >> I also have the following in my core: >> sms-combine-concatenated-mo = true >> sms-combine-concatenated-mo-timeout = "1800" >> >> >> I have to use the latest version (r5024+) in order to use MO >> concatenation feature comitted by amalysh: >> 2013-02-21 Alexander Malysh <amalysh at kannel.org> >> * gw/smsc/smsc_smpp.c: added support for sar_ multipart TLVs. >> >> Please help me get to the issue so that it can be resolved. >> Thanks >> >> >> > As I shutdown kannel, I noticed that it wrote the following lines in the bearerbox (wrote on exit only): 2013-02-25 14:39:06 Receive SMS [SMSC:xxxxxxxx] [SVC:] [ACT:xxxxxxxx] [BINF:] [FID:] [META:?smpp?] [from:+xxxxxxxx] [to:xxxxxxxx] [flags:-1:0:-1:0:-1] [msg:153:The quick brown fox jumps over the lazy dog. The quick brown fox jumps over the lazy dog. The quick brown fox jumps over the lazy dog. The quick brown fo] [udh:6:05000301FFFF] *2013-02-25 14:39:06 Receive SMS [SMSC:xxxxxxxx] [SVC:] [ACT:xxxxxxxx] [BINF:] [FID:] [META:?smpp?] [from:+xxxxxxxx] [to:xxxxxxxx] [flags:-1:0:-1:0:-1] [msg:3:Nop] [udh:6:050003FFFFFF]* 2013-02-25 14:39:06 Receive SMS [SMSC:xxxxxxxx] [SVC:] [ACT:xxxxxxxx] [BINF:] [FID:] [META:?smpp?] [from:+xxxxxxxx] [to:xxxxxxxx] [flags:-1:0:-1:0:-1] [msg:27:x jumps over the lazy dog. ] [udh:6:05000302FFFF] 2013-02-25 14:39:06 Log ends
NOTE: the message "Nop" was not part of any message, it was an individual short message sent from the same MSISDN. The long sms contains "The quick brown fox...... " sentences. Message "Nop" was sent at an earlier time. however, bearerbox logs all stuff at the time of kannel shutdown. This can be confirmed from my log output above. smsbox still hasn't logged anything.
