PPG and CIMD2 delivery reports

"Andrikopoulos Dimitrios" <[email protected]>
Newsgroups gmane.comp.mobile.kannel.devel
Message-ID <[email protected]> 
Dear Support,
 
   I have installed Kannel 1.4.1 on a Fedora Core 5 system, and I use it
as a PPG. I use the
attached configuration file to interconnect Kannel with a NOKIA SMS-C,
which supports CIMD2,
and with a MySQL database for storing the DLRs.
 
  I use PAP/HTTP to send unconfirmed WAP Push messages to Kannel PPG
which successfully
delivers them to SMS-C, and finally on my handset. However, I get no
"SMS level" delivery reports
from the SMS-C. Please let me know if:
 
        1. something is missing from the configuration.
 
        2. the PAP XML content should contain specific tag values so as
to trigger SMS-C DLRs.
 
        3. the PAP/HTTP request should contain specific header values,
so as to trigger SMS-C DLRs.
 
  I also attached the Kannel's LOG files which contain the start-up of
the Kannel programs and the
sending of 1 WAP Push message. Please note that I kept everything
running much longer after I
received the WAP Push message on my handset. Please also note that I
took an ETHEREAL trace
on Kannel PPG's network interface and verified that SMS-C didn't
actually send any DLR.
 
Thanks,
Dimitris
smsbox-access.log (application/octet-stream, 31 B) 
2006-11-30 10:36:13 Log begins
wapaccess.log (application/octet-stream, 31 B) 
2006-11-30 10:36:11 Log begins
wapbox.log (application/octet-stream, 6.1 KB) 
2006-11-30 10:36:11 [8841] [0] INFO: Added logfile `/tmp/wapbox.log' with level `0'.
2006-11-30 10:36:11 [8841] [0] INFO: Starting to log to file /tmp/wapbox.log level 0
2006-11-30 10:36:11 [8841] [0] DEBUG: syslog parameter is none
2006-11-30 10:36:11 [8841] [0] INFO: Logging accesses to '/tmp/wapaccess.log'.
2006-11-30 10:36:11 [8841] [0] INFO: Started access logfile `/tmp/wapaccess.log'.
2006-11-30 10:36:11 [8841] [0] INFO: ------------------------------------------------------------
2006-11-30 10:36:11 [8841] [0] INFO: Kannel wapbox version 1.4.1 starting up.
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 1 (wap/wsp_session.c:main_thread)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 2 (wap/wsp_unit.c:main_thread)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 3 (wap/wsp_push_client.c:main_thread)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 4 (wap/timers.c:watch_timers)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 5 (wap/wtp_init.c:main_thread)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 6 (wap/wtp_resp.c:main_thread)
2006-11-30 10:36:11 [8841] [5] DEBUG: Thread 5 (wap/wtp_init.c:main_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 7 (gw/wap-appl.c:main_thread)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 8 (gw/wap-appl.c:return_replies_thread)
2006-11-30 10:36:11 [8841] [9] DEBUG: Thread 9 (gw/wap_push_ota.c:main_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [1] DEBUG: Thread 1 (wap/wsp_session.c:main_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [6] DEBUG: Thread 6 (wap/wtp_resp.c:main_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [3] DEBUG: Thread 3 (wap/wsp_push_client.c:main_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 9 (gw/wap_push_ota.c:main_thread)
2006-11-30 10:36:11 [8841] [0] INFO: HTTP: Opening server at port 7080.
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 10 (gwlib/fdset.c:poller)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 11 (gwlib/http.c:server_thread)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 12 (gw/wap_push_ppg.c:ota_read_thread)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 13 (gw/wap_push_ppg.c:http_read_thread)
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 14 (gw/wap_push_ppg.c:pap_request_thread)
2006-11-30 10:36:11 [8841] [0] INFO: Connected to bearerbox at 172.16.15.30 port 13002.
2006-11-30 10:36:11 [8841] [7] DEBUG: Thread 7 (gw/wap-appl.c:main_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [8] DEBUG: Thread 8 (gw/wap-appl.c:return_replies_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [0] DEBUG: Started thread 15 (gw/heartbeat.c:heartbeat_thread)
2006-11-30 10:36:11 [8841] [10] DEBUG: Thread 10 (gwlib/fdset.c:poller) maps to pid 8841.
2006-11-30 10:36:11 [8841] [11] DEBUG: Thread 11 (gwlib/http.c:server_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [12] DEBUG: Thread 12 (gw/wap_push_ppg.c:ota_read_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [13] DEBUG: Thread 13 (gw/wap_push_ppg.c:http_read_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [14] DEBUG: Thread 14 (gw/wap_push_ppg.c:pap_request_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [15] DEBUG: Thread 15 (gw/heartbeat.c:heartbeat_thread) maps to pid 8841.
2006-11-30 10:36:11 [8841] [4] DEBUG: Thread 4 (wap/timers.c:watch_timers) maps to pid 8841.
2006-11-30 10:36:11 [8841] [2] DEBUG: Thread 2 (wap/wsp_unit.c:main_thread) maps to pid 8841.
2006-11-30 10:36:20 [8841] [11] DEBUG: HTTP: Creating HTTPClient for `172.17.60.28'.
2006-11-30 10:36:20 [8841] [11] DEBUG: HTTP: Created HTTPClient area 0x8eb6828.
2006-11-30 10:36:20 [8841] [14] INFO: PPG: Accept request </push/miep_normal> from <172.17.60.28>
2006-11-30 10:36:20 [8841] [14] DEBUG: PPG: http_read_thread: pap multipart accepted
2006-11-30 10:36:20 [8841] [14] INFO: client address was <+306973983868>, accepted
2006-11-30 10:36:20 [8841] [14] DEBUG: PAP COMPILER: doing semantic analysis for address type a phone number
2006-11-30 10:36:20 [8841] [14] DEBUG: using defaults instead of anys
2006-11-30 10:36:20 [8841] [14] DEBUG: PPG: http_read_thread: pap control entity compiled ok
2006-11-30 10:36:20 [8841] [14] DEBUG: PPG: transform_message: push message content and headers valid
2006-11-30 10:36:20 [8841] [14] DEBUG: PPG: push machine 0 created
2006-11-30 10:36:20 [8841] [14] DEBUG: PPG: store_push_data: push machine 0 appended to unit push list
2006-11-30 10:36:20 [8841] [14] DEBUG: PPG: send_push_response: telling pi
2006-11-30 10:36:20 [8841] [14] DEBUG: HTTP: Destroying HTTPClient area 0x8eb6828.
2006-11-30 10:36:20 [8841] [14] DEBUG: HTTP: Destroying HTTPClient for `172.17.60.28'.
2006-11-30 10:36:20 [8841] [14] INFO: PPG: handle_push_message: push message accepted for processing
2006-11-30 10:36:20 [8841] [14] DEBUG: PPG: OTA request for unit push
2006-11-30 10:36:20 [8841] [14] INFO: PPG: unconfirmed push delivered to OTA
2006-11-30 10:36:20 [8841] [14] DEBUG: PPG: destroying push machine 0
2006-11-30 10:36:20 [8841] [9] DEBUG: OTA: event arrived
2006-11-30 10:36:20 [8841] [9] DEBUG: OTA: made connectionless session service request
2006-11-30 10:36:20 [8841] [2] DEBUG: WSP (UNIT): event arrived
2006-11-30 10:36:20 [8841] [2] DEBUG: WSP_UNIT: Connectionless push accepted
2006-11-30 10:36:20 [8841] [2] DEBUG: WSP: Mapping `application/vnd.wap.sic', WSP 1.2 to 0x002e.
2006-11-30 10:36:20 [8841] [2] DEBUG: WSP: Mapping `User-Agent', WSP 1.3 to 0x0029.
2006-11-30 10:36:20 [8841] [2] DEBUG: WSP: Mapping `Content-Length', WSP 1.3 to 0x000d.
2006-11-30 10:36:20 [8841] [2] DEBUG: WSP: Mapping `Content-Type', WSP 1.3 to 0x0011.
2006-11-30 10:36:20 [8841] [2] DEBUG: WSP: Mapping `Encoding-Version', WSP 1.3 to 0x0043.
2006-11-30 10:36:20 [8841] [2] DEBUG: WDP (wapbox): delivering 3 segments to bearerbox
2006-11-30 10:36:20 [8841] [2] DEBUG: WSP (UNIT): delivering to wdp
2006-11-30 10:36:20 [8841] [0] WARNING: Received other message than wdp/admin, ignoring!
2006-11-30 10:36:20 [8841] [0] WARNING: Received other message than wdp/admin, ignoring!
2006-11-30 10:36:20 [8841] [0] WARNING: Received other message than wdp/admin, ignoring!
bearerbox.log (application/octet-stream, 7.2 KB) 
2006-11-30 10:35:44 [8824] [0] INFO: Added logfile `/tmp/bearerbox.log' with level `0'.
2006-11-30 10:35:44 [8824] [0] INFO: HTTP: Opening server at port 13000.
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 1 (gwlib/fdset.c:poller)
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 2 (gwlib/http.c:server_thread)
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 3 (gw/bb_http.c:httpadmin_run)
2006-11-30 10:35:44 [8824] [0] DEBUG: starting smsbox connection module
2006-11-30 10:35:44 [8824] [0] INFO: BOXC: 'smsbox-max-pending' not set, using default (100).
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 4 (gw/bb_boxc.c:sms_to_smsboxes)
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 5 (gw/bb_boxc.c:smsboxc_run)
2006-11-30 10:35:44 [8824] [0] INFO: Set SMS resend frequency to 60 seconds.
2006-11-30 10:35:44 [8824] [0] INFO: SMS resend retry set to unlimited.
2006-11-30 10:35:44 [8824] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run) maps to pid 8824.
2006-11-30 10:35:44 [8824] [4] DEBUG: Thread 4 (gw/bb_boxc.c:sms_to_smsboxes) maps to pid 8824.
2006-11-30 10:35:44 [8824] [0] INFO: Set throughput to 10.000 for smsc id <(null)>
2006-11-30 10:35:44 [8824] [0] INFO: DLR rerouting for smsc id <(null)> disabled.
2006-11-30 10:35:44 [8824] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run) maps to pid 8824.
2006-11-30 10:35:44 [8824] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread) maps to pid 8824.
2006-11-30 10:35:44 [8824] [0] DEBUG: CIMD2[(null)]: Keepalive set to 60 seconds
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 6 (gw/smsc/smsc_cimd2.c:io_thread)
2006-11-30 10:35:44 [8824] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps to pid 8824.
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 7 (gw/bb_smscconn.c:sms_router)
2006-11-30 10:35:44 [8824] [0] DEBUG: starting UDP sender/receiver module
2006-11-30 10:35:44 [8824] [0] INFO: Adding interface *
2006-11-30 10:35:44 [8824] [0] DEBUG: udpc_create: Bound to UDP <0.0.0.0:9200>
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 8 (gw/bb_udp.c:udp_receiver)
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 9 (gw/bb_udp.c:udp_sender)
2006-11-30 10:35:44 [8824] [0] DEBUG: udpc_create: Bound to UDP <0.0.0.0:9201>
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 10 (gw/bb_udp.c:udp_receiver)
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 11 (gw/bb_udp.c:udp_sender)
2006-11-30 10:35:44 [8824] [0] DEBUG: starting wapbox connection module
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 12 (gw/bb_boxc.c:wdp_to_wapboxes)
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 13 (gw/bb_boxc.c:wapboxc_run)
2006-11-30 10:35:44 [8824] [0] DEBUG: starting WDP router
2006-11-30 10:35:44 [8824] [0] DEBUG: Started thread 14 (gw/bearerbox.c:wdp_router)
2006-11-30 10:35:44 [8824] [0] INFO: ----------------------------------------
2006-11-30 10:35:44 [8824] [0] INFO: Kannel bearerbox II version 1.4.1 starting
2006-11-30 10:35:44 [8824] [0] INFO: MAIN: Start-up done, entering mainloop
2006-11-30 10:35:44 [8824] [0] DEBUG: SMSCConn CIMD2 (null), start called
2006-11-30 10:35:44 [8824] [6] DEBUG: Thread 6 (gw/smsc/smsc_cimd2.c:io_thread) maps to pid 8824.
2006-11-30 10:35:44 [8824] [7] DEBUG: Thread 7 (gw/bb_smscconn.c:sms_router) maps to pid 8824.
2006-11-30 10:35:44 [8824] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2006-11-30 10:35:44 [8824] [7] DEBUG: sms_router: gwlist_len = 0
2006-11-30 10:35:44 [8824] [8] DEBUG: Thread 8 (gw/bb_udp.c:udp_receiver) maps to pid 8824.
2006-11-30 10:35:44 [8824] [9] DEBUG: Thread 9 (gw/bb_udp.c:udp_sender) maps to pid 8824.
2006-11-30 10:35:44 [8824] [10] DEBUG: Thread 10 (gw/bb_udp.c:udp_receiver) maps to pid 8824.
2006-11-30 10:35:44 [8824] [11] DEBUG: Thread 11 (gw/bb_udp.c:udp_sender) maps to pid 8824.
2006-11-30 10:35:44 [8824] [12] DEBUG: Thread 12 (gw/bb_boxc.c:wdp_to_wapboxes) maps to pid 8824.
2006-11-30 10:35:44 [8824] [13] DEBUG: Thread 13 (gw/bb_boxc.c:wapboxc_run) maps to pid 8824.
2006-11-30 10:35:44 [8824] [14] DEBUG: Thread 14 (gw/bearerbox.c:wdp_router) maps to pid 8824.
2006-11-30 10:35:44 [8824] [6] DEBUG: CIMD2[(null)]: sending <01:001	010:wap	011:wap1	A1>
2006-11-30 10:35:44 [8824] [6] DEBUG: CIMD2[(null)]: received: <51:001	3C>
2006-11-30 10:35:44 [8824] [6] INFO: CIMD2[(null)] logged in.
2006-11-30 10:36:11 [8824] [13] INFO: Client connected from <172.16.15.30> 
2006-11-30 10:36:11 [8824] [13] DEBUG: Started thread 15 (gw/bb_boxc.c:function)
2006-11-30 10:36:11 [8824] [15] DEBUG: Thread 15 (gw/bb_boxc.c:function) maps to pid 8824.
2006-11-30 10:36:11 [8824] [15] DEBUG: setting up systems for new wapbox
2006-11-30 10:36:11 [8824] [15] DEBUG: Started thread 16 (gw/bb_boxc.c:boxc_sender)
2006-11-30 10:36:11 [8824] [16] DEBUG: Thread 16 (gw/bb_boxc.c:boxc_sender) maps to pid 8824.
2006-11-30 10:36:13 [8824] [5] INFO: Client connected from <172.16.15.30> 
2006-11-30 10:36:13 [8824] [5] DEBUG: Started thread 17 (gw/bb_boxc.c:function)
2006-11-30 10:36:13 [8824] [17] DEBUG: Thread 17 (gw/bb_boxc.c:function) maps to pid 8824.
2006-11-30 10:36:13 [8824] [17] DEBUG: Started thread 18 (gw/bb_boxc.c:boxc_sender)
2006-11-30 10:36:13 [8824] [17] DEBUG: boxc_receiver: got boxc_id <dlrbox> from <172.16.15.30>
2006-11-30 10:36:13 [8824] [18] DEBUG: Thread 18 (gw/bb_boxc.c:boxc_sender) maps to pid 8824.
2006-11-30 10:36:20 [8824] [15] DEBUG: boxc_receiver: got sms from wapbox
2006-11-30 10:36:20 [8824] [15] DEBUG: send_msg: sending msg to box: <172.16.15.30>
2006-11-30 10:36:20 [8824] [15] DEBUG: boxc_receiver: got sms from wapbox
2006-11-30 10:36:20 [8824] [15] DEBUG: send_msg: sending msg to box: <172.16.15.30>
2006-11-30 10:36:20 [8824] [15] DEBUG: boxc_receiver: got sms from wapbox
2006-11-30 10:36:20 [8824] [15] DEBUG: send_msg: sending msg to box: <172.16.15.30>
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: sending message
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: sending <03:003	021:+306973983868	023:1234	050:167	056:0	055:0	044:1	032:0B05040B8423F00003000301	034:000614AEA97761705F707573682F312E30008D01FDC39302056A0045C60C037761702E6D79636F736D6F732E67722F436173696E6F526F79616C652E6173700011035065726C5363726970745F53492F6161353764663634326439333165666332353161353662303233303164656532000AC3072006113010361910C3072006	030:4	5B>
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: received: <53:003	021:+306973983868	060:061130103620	F7>
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: sending message
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: sending <03:005	021:+306973983868	023:1234	050:167	056:0	055:0	044:1	032:0B05040B8423F00003000302	034:1203103619080103313A20CEA9504120CE934941204D4F59CEA3494B4821203135204E4541205245414C544F4E45532041CEA04F20544F204E454F20414C42554D205448CEA320CEA045CE934B59205A484E4120CEA34520CEA04550494D454E4F594E2041CEA04F4BCE9B4549CEA354494B4120CEA3544F204D55534943205A	030:4	0C>
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: received: <53:005	021:+306973983868	060:061130103621	FA>
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: sending message
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: sending <03:007	021:+306973983868	023:1234	050:167	056:0	055:0	044:0	032:0B05040B8423F00003000303	034:4F4E45205448CEA320434F534D4F544521000101	030:4	20>
2006-11-30 10:36:20 [8824] [6] DEBUG: CIMD2[(null)]: received: <53:007	021:+306973983868	060:061130103622	FD>
smsbox.log (application/octet-stream, 1.2 KB) 
2006-11-30 10:36:13 [8842] [0] INFO: Added logfile `/tmp/smsbox.log' with level `0'.
2006-11-30 10:36:13 [8842] [0] INFO: Logging accesses to '/tmp/smsbox-access.log'.
2006-11-30 10:36:13 [8842] [0] INFO: Started access logfile `/tmp/smsbox-access.log'.
2006-11-30 10:36:13 [8842] [0] DEBUG: ----------------------------------------------
2006-11-30 10:36:13 [8842] [0] DEBUG: Kannel smsbox version 1.4.1 starting
2006-11-30 10:36:13 [8842] [0] DEBUG: Started thread 1 (gw/smsbox.c:obey_request_thread)
2006-11-30 10:36:13 [8842] [0] DEBUG: Started thread 2 (gw/smsbox.c:url_result_thread)
2006-11-30 10:36:13 [8842] [0] DEBUG: Started thread 3 (gw/smsbox.c:http_queue_thread)
2006-11-30 10:36:13 [8842] [0] INFO: Connected to bearerbox at 172.16.15.30 port 13001.
2006-11-30 10:36:13 [8842] [0] DEBUG: Started thread 4 (gw/heartbeat.c:heartbeat_thread)
2006-11-30 10:36:13 [8842] [1] DEBUG: Thread 1 (gw/smsbox.c:obey_request_thread) maps to pid 8842.
2006-11-30 10:36:13 [8842] [2] DEBUG: Thread 2 (gw/smsbox.c:url_result_thread) maps to pid 8842.
2006-11-30 10:36:13 [8842] [3] DEBUG: Thread 3 (gw/smsbox.c:http_queue_thread) maps to pid 8842.
2006-11-30 10:36:13 [8842] [4] DEBUG: Thread 4 (gw/heartbeat.c:heartbeat_thread) maps to pid 8842.
wapkannel.conf (application/octet-stream, 1.2 KB) 
group = core
admin-port = 13000
smsbox-port = 13001
wapbox-port = 13002
admin-password = bar

wdp-interface-name = "*"
log-file = "/tmp/bearerbox.log"
log-level = 0
dlr-storage = mysql

group = wapbox
bearerbox-host = 172.16.15.30
log-file = "/tmp/wapbox.log"
log-level = 0
syslog-level = none
access-log = "/tmp/wapaccess.log"

group = smsbox
smsbox-id = dlrbox
bearerbox-host = 172.16.15.30
log-file = "/tmp/smsbox.log"
log-level = 0
access-log = "/tmp/smsbox-access.log"

# SMSC Nokia CIMD2
group = smsc
smsc = cimd2
host = 172.25.38.20
port = 9971
smsc-username = wap
smsc-password = wap1
throughput = 10
keepalive = 60

group = ppg
ppg-url = /push/miep_normal
ppg-port = 7080
ppg-allow-ip = 172.17.60.28
concurrent-pushes = 100
trusted-pi = true
service-name = ppg
ppg-smsbox-id = dlrbox

group = wap-push-user
wap-push-user = dimitris
ppg-username = my_user
ppg-password = my_pass

group = mysql-connection
id = mydlr
host = 172.17.60.28
username = my_dlr
password = my_dlr
database = dlr
max-connections = 1

group = dlr-db
id = mydlr
table = dlr
field-smsc = smsc
field-timestamp = ts
field-destination = destination
field-source = source
field-service = service
field-url = url
field-mask = mask
field-status = status
field-boxc-id = boxc
lmpx.com only provides a reader for public news (NNTP) servers. It is not affiliated with the servers or forums shown here and is not responsible for the content of articles, which is written by their respective authors.