Hi everyone, I'm able to process incomming SMS with Kannel default modem configuratin, but I'm not able to process message delivery report, which as shown in the bb log attached are received, but not processed correctly. I get the "got +CMT but pdu_extract failed" message and though I've played for a day with init strings for my ZTE USB modem, the processing of delivery does not happen. The DLR-URL that I use is identical for clickatell SMSC and modem and a sample of sendsms url is shown below
http://localhost:13011/cgi-bin/sendsms?username=user&password=user&to=355682073698&from=%22FZ%20Sh.p.k%22&text=CiaoGjergji&dlr-mask=31&dlr-url=http%3A%2F%2F192.168.1.15%2Fdlr.php%3Fdlr%3D%25d%26to%3D%25p%26from%3D%25P%26ts%3D%25T%26smsID%3D120343994592 Does anybody has any idea or suggestion what shall I fix? Kind Regards, Gjergji
kannel.conf
Description: Binary data
2010-09-29 10:22:05 [17716] [0] INFO: ---------------------------------------- 2010-09-29 10:22:05 [17716] [0] INFO: Kannel bearerbox II version 1.4.3 starting 2010-09-29 10:22:05 [17716] [0] INFO: Loading store file `/var/log/kannel/bearerbox.store' 2010-09-29 10:22:05 [17716] [0] INFO: Store-file size 0, starting to unpack 2010-09-29 10:22:05 [17716] [0] INFO: Retrieved 0 messages, non-acknowledged messages: 0 2010-09-29 10:22:05 [17716] [0] DEBUG: Started thread 10 (gw/bb_store_file.c:store_dumper) 2010-09-29 10:22:05 [17716] [0] INFO: MAIN: Start-up done, entering mainloop 2010-09-29 10:22:05 [17716] [0] DEBUG: AT2[AMCZTE]: start called 2010-09-29 10:22:05 [17716] [9] DEBUG: Thread 9 (gw/bb_smscconn.c:sms_router) maps to pid 17716. 2010-09-29 10:22:05 [17716] [10] DEBUG: Thread 10 (gw/bb_store_file.c:store_dumper) maps to pid 17716. 2010-09-29 10:22:05 [17716] [10] DEBUG: Dumping 0 messages to store 2010-09-29 10:22:05 [17716] [8] DEBUG: Thread 8 (gw/smsc/smsc_at.c:at2_device_thread) maps to pid 17716. 2010-09-29 10:22:05 [17716] [8] INFO: AT2[AMCZTE]: trying to use speed <9600> from modem definition 2010-09-29 10:22:05 [17716] [8] INFO: AT2[AMCZTE]: opening device 2010-09-29 10:22:06 [17716] [8] DEBUG: AT2[AMCZTE]: device opened. Telnet mode = 0 2010-09-29 10:22:06 [17716] [8] DEBUG: AT2[AMCZTE]: device opened 2010-09-29 10:22:06 [17716] [8] INFO: AT2[AMCZTE]: speed set to 9600 2010-09-29 10:22:06 [17716] [8] DEBUG: AT2[AMCZTE]: --> ^M 2010-09-29 10:22:06 [17716] [5] INFO: Client connected from <127.0.0.1> 2010-09-29 10:22:06 [17716] [5] DEBUG: Started thread 11 (gw/bb_boxc.c:function) 2010-09-29 10:22:06 [17716] [11] DEBUG: Thread 11 (gw/bb_boxc.c:function) maps to pid 17716. 2010-09-29 10:22:06 [17716] [11] DEBUG: Started thread 12 (gw/bb_boxc.c:boxc_sender) 2010-09-29 10:22:06 [17716] [12] DEBUG: Thread 12 (gw/bb_boxc.c:boxc_sender) maps to pid 17716. 2010-09-29 10:22:08 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT^M 2010-09-29 10:22:08 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:08 [17716] [8] INFO: AT2[AMCZTE]: Closing device 2010-09-29 10:22:08 [17716] [8] INFO: AT2[AMCZTE]: speed is 9600 2010-09-29 10:22:08 [17716] [8] INFO: AT2[AMCZTE]: opening device 2010-09-29 10:22:08 [17716] [8] DEBUG: AT2[AMCZTE]: device opened. Telnet mode = 0 2010-09-29 10:22:08 [17716] [8] DEBUG: AT2[AMCZTE]: device opened 2010-09-29 10:22:08 [17716] [8] INFO: AT2[AMCZTE]: Logging in 2010-09-29 10:22:09 [17716] [8] INFO: AT2[AMCZTE]: init device 2010-09-29 10:22:09 [17716] [8] INFO: AT2[AMCZTE]: speed set to 9600 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: --> ATZ^M 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT^M 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- AT 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT&F^M 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- AT&F 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: --> ATE0^M 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- ATE0 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT+IFC=2,2^M 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:09 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT+CPIN?^M 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: <-- +CPIN: READY 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT+CSCA="+355681000000"^M 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT+CMGF=0^M 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT+CSMS=?^M 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: <-- +CSMS: (0-1) 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT+CNMI=1,2,0,1,0^M 2010-09-29 10:22:10 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:10 [17716] [8] INFO: AT2[AMCZTE]: AT SMSC successfully opened. 2010-09-29 10:22:18 [17716] [11] DEBUG: boxc_receiver: sms received 2010-09-29 10:22:18 [17716] [11] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2010-09-29 10:22:20 [17716] [8] DEBUG: AT2[AMCZTE]: TP-Validity-Period: 24.0 hours 2010-09-29 10:22:20 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT+CMGS=24^M 2010-09-29 10:22:20 [17716] [8] DEBUG: AT2[AMCZTE]: <-- > 2010-09-29 10:22:20 [17716] [8] DEBUG: AT2[AMCZTE]: send command status: 1 2010-09-29 10:22:20 [17716] [8] DEBUG: AT2[AMCZTE]: --> 11000C815365287063890000A70BCDB4BCDC2EBBCFEAF21C 2010-09-29 10:22:20 [17716] [8] DEBUG: AT2[AMCZTE]: --> ^Z 2010-09-29 10:22:20 [17716] [8] DEBUG: AT2[AMCZTE]: <-- > 2010-09-29 10:22:22 [17716] [8] DEBUG: AT2[AMCZTE]: <-- +CMGS: 38 2010-09-29 10:22:22 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:22:22 [17716] [8] DEBUG: AT2[AMCZTE]: send command status: 0 2010-09-29 10:22:35 [17716] [10] DEBUG: Dumping 0 messages to store 2010-09-29 10:23:01 [17716] [11] DEBUG: boxc_receiver: sms received 2010-09-29 10:23:01 [17716] [11] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2010-09-29 10:23:02 [17716] [8] DEBUG: AT2[AMCZTE]: TP-Validity-Period: 24.0 hours 2010-09-29 10:23:02 [17716] [8] DEBUG: AT2[AMCZTE]: --> AT+CMGS=18^M 2010-09-29 10:23:02 [17716] [8] DEBUG: AT2[AMCZTE]: <-- > 2010-09-29 10:23:02 [17716] [8] DEBUG: AT2[AMCZTE]: send command status: 1 2010-09-29 10:23:02 [17716] [8] DEBUG: AT2[AMCZTE]: --> 31000C815365287063890000A704E6BA9B0C 2010-09-29 10:23:02 [17716] [8] DEBUG: AT2[AMCZTE]: --> ^Z 2010-09-29 10:23:02 [17716] [8] DEBUG: AT2[AMCZTE]: <-- > 2010-09-29 10:23:04 [17716] [8] DEBUG: AT2[AMCZTE]: <-- +CMGS: 39 2010-09-29 10:23:04 [17716] [8] DEBUG: AT2[AMCZTE]: <-- OK 2010-09-29 10:23:04 [17716] [8] DEBUG: AT2[AMCZTE]: send command status: 0 2010-09-29 10:23:04 [17716] [8] DEBUG: DLR[internal]: Adding DLR smsc=AMCZTE, ts=39, src=+355689010838, dst=355682073698, mask=31, boxc= 2010-09-29 10:23:04 [17716] [8] DEBUG: SMSC[AMCZTE]: creating DLR message 2010-09-29 10:23:04 [17716] [8] DEBUG: SMSC[AMCZTE]: DLR = http://192.168.1.6/fcs/KannelDelivery/dlr.php?dlr=%d&to=%p&from=%P&ts=%T&carrier=%i&smsID=36 2010-09-29 10:23:04 [17716] [12] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2010-09-29 10:23:04 [17716] [12] DEBUG: boxc_sender: sent message to <127.0.0.1> 2010-09-29 10:23:04 [17716] [11] DEBUG: boxc_receiver: got ack 2010-09-29 10:23:06 [17716] [11] DEBUG: boxc_receiver: heartbeat with load value 0 received 2010-09-29 10:23:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- +CDS: ,25 2010-09-29 10:23:09 [17716] [8] DEBUG: AT2[AMCZTE]: <-- 079153651800000006270C81536528706389019092813200800190928132408000 2010-09-29 10:23:09 [17716] [8] ERROR: AT2[AMCZTE]: got +CMT but pdu_extract failed 2010-09-29 10:23:15 [17716] [10] DEBUG: Dumping 0 messages to store
