DLR (internal) corruption

Nicholas Rahn <[email protected]>
Newsgroups gmane.comp.mobile.kannel.devel
Message-ID <[email protected]> 
Hi,

I've found what seems to me to be a corruption with DLRs. I'm using a
CIMD2 connection with internal DLR storage and a CVS snapshot from a 
few days ago.

The first attachement contains 3 "sets" of bearer box logs.  They were
created by doing a grep for a specific timestamp (ts) value.

As you can see in each set of logs, the submit response arrives from the
SMSC for each number and we correctly add (according to the log) the DLR
entries.  Then we also (correctly i assume) receive the deliver status
report (success) for all but the one number (41787883971) which comes
about 8 hours later (08:31:31) with an unsuccessfull status.  This is
the same thing for each set of logs.  As we set the dlr-mask to 2, the 3
unsuccessful statuses generate 3 separate requests to the configured 
dlr-url.

What is strange are the relevant smsbox log entries in the second attachment
concerning the dlr-url that is called.  As you can see here, there are 3
DLR-URL invoked, but only 1 of them has the correct "receiver"
parameter.  The other 2 "receiver" numbers seem to be "corrupted" as
they are not the same "dst" as we see from the bearerbox log!  Could they
possibly have been corrupted since they were created?  Or were they 
originally stored in a corrupted state?  To me this seems a bit like a
"synchronization" problem somewhere (to use the java term).

Unfortunately, I don't understand the internals of kannel enough to 
answer any of these questions.  Would anyone be able to shed some light 
on this?

thanks in advance for your time,
nick
bearerbox.log (text/plain, 11.2 KB) 
2004-03-23 23:44:27 [3330] [9] DEBUG: CIMD2[testact]: received: <53:247   021:+41787539088        060:040323234427        D2>
2004-03-23 23:44:27 [3330] [9] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234427, src=78931710906, dst=+41787539088, mask=2, boxc=
2004-03-23 23:44:27 [3331] [10] DEBUG: CIMD2[testact]: received: <53:085  021:+41787883971        060:040323234427        D5>
2004-03-23 23:44:27 [3331] [10] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234427, src=78931710906, dst=+41787883971, mask=2, boxc=
2004-03-23 23:44:27 [3331] [10] DEBUG: CIMD2[testact]: received: <23:210  021:41786802357 060:040323234425        061:4   063:040323234427        DB>
2004-03-23 23:44:27 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234425, dst=41786802357, type=1
2004-03-23 23:44:27 [3331] [10] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:44:47 [3330] [9] DEBUG: CIMD2[testact]: received: <23:230   021:41787539088 060:040323234427        061:4   063:040323234445        E8>
2004-03-23 23:44:47 [3330] [9] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234427, dst=41787539088, type=1
2004-03-23 23:44:47 [3330] [9] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-24 08:31:31 [3331] [10] DEBUG: CIMD2[testact]: received: <23:028  021:41787883971 060:040323234427        061:2   063:040324083129        F0>
2004-03-24 08:31:31 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234427, dst=41787883971, type=2
2004-03-24 08:31:31 [3331] [10] DEBUG: DLR[internal]: created DLR message for URL <http://localhost:8047/Red/sms/dlrs/orange?dlrval=%d&dlrres=%A&receiver=%q&sender=%Q>





2004-03-23 23:44:57 [3330] [9] DEBUG: CIMD2[testact]: received: <53:029   021:+41787539088        060:040323234457        D3>
2004-03-23 23:44:57 [3330] [9] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234457, src=78931710906, dst=+41787539088, mask=2, boxc=
2004-03-23 23:44:57 [3331] [10] DEBUG: CIMD2[testact]: received: <53:103  021:+41787883971        060:040323234457        CF>
2004-03-23 23:44:57 [3331] [10] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234457, src=78931710906, dst=+41787883971, mask=2, boxc=
2004-03-23 23:44:57 [3330] [9] DEBUG: CIMD2[testact]: received: <53:031   021:+41786802357        060:040323234457        C3>
2004-03-23 23:44:57 [3330] [9] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234457, src=78931710906, dst=+41786802357, mask=2, boxc=
2004-03-23 23:44:57 [3331] [10] DEBUG: CIMD2[testact]: received: <53:105  021:+41788018737        060:040323234457        C8>
2004-03-23 23:44:57 [3331] [10] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234457, src=78931710906, dst=+41788018737, mask=2, boxc=
2004-03-23 23:44:57 [3330] [9] DEBUG: CIMD2[testact]: received: <53:033   021:+41786822057        060:040323234457        C4>
2004-03-23 23:44:57 [3330] [9] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234457, src=78931710906, dst=+41786822057, mask=2, boxc=
2004-03-23 23:44:57 [3331] [10] DEBUG: CIMD2[testact]: received: <53:107  021:+41787144306        060:040323234457        C1>
2004-03-23 23:44:57 [3331] [10] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234457, src=78931710906, dst=+41787144306, mask=2, boxc=
2004-03-23 23:44:57 [3330] [9] DEBUG: CIMD2[testact]: received: <53:035   021:+41786424773        060:040323234457        C9>
2004-03-23 23:44:57 [3330] [9] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234457, src=78931710906, dst=+41786424773, mask=2, boxc=
2004-03-23 23:45:00 [3331] [10] DEBUG: CIMD2[testact]: received: <23:232  021:41788018737 060:040323234457        061:4   063:040323234459        EC>
2004-03-23 23:45:00 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234457, dst=41788018737, type=1
2004-03-23 23:45:00 [3331] [10] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:45:02 [3331] [10] DEBUG: CIMD2[testact]: received: <23:234  021:41787144306 060:040323234457        061:4   063:040323234501        D9>
2004-03-23 23:45:02 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234457, dst=41787144306, type=1
2004-03-23 23:45:02 [3331] [10] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:45:02 [3330] [9] DEBUG: CIMD2[testact]: received: <23:242   021:41786802357 060:040323234457        061:4   063:040323234502        DF>
2004-03-23 23:45:02 [3330] [9] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234457, dst=41786802357, type=1
2004-03-23 23:45:02 [3330] [9] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:45:05 [3330] [9] DEBUG: CIMD2[testact]: received: <23:246   021:41787539088 060:040323234457        061:4   063:040323234502        EC>
2004-03-23 23:45:05 [3330] [9] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234457, dst=41787539088, type=1
2004-03-23 23:45:05 [3330] [9] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:45:10 [3330] [9] DEBUG: CIMD2[testact]: received: <23:248   021:41786424773 060:040323234457        061:4   063:040323234509        EE>
2004-03-23 23:45:10 [3330] [9] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234457, dst=41786424773, type=1
2004-03-23 23:45:10 [3330] [9] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:45:58 [3330] [9] DEBUG: CIMD2[testact]: received: <23:020   021:41786822057 060:040323234457        061:4   063:040323234554        DF>
2004-03-23 23:45:58 [3330] [9] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234457, dst=41786822057, type=1
2004-03-23 23:45:58 [3330] [9] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-24 08:31:31 [3331] [10] DEBUG: CIMD2[testact]: received: <23:026  021:41787883971 060:040323234457        061:2   063:040324083129        F1>
2004-03-24 08:31:31 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234457, dst=41787883971, type=2
2004-03-24 08:31:31 [3331] [10] DEBUG: DLR[internal]: created DLR message for URL <http://localhost:8047/Red/sms/dlrs/testact?dlrval=%d&dlrres=%A&receiver=%q&sender=%Q>





2004-03-23 23:45:05 [3331] [10] DEBUG: CIMD2[testact]: received: <53:109  021:+41787883971        060:040323234505        CF>
2004-03-23 23:45:05 [3331] [10] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234505, src=78931710906, dst=+41787883971, mask=2, boxc=
2004-03-23 23:45:05 [3330] [9] DEBUG: CIMD2[testact]: received: <53:037   021:+41787539088        060:040323234505        CC>
2004-03-23 23:45:05 [3330] [9] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234505, src=78931710906, dst=+41787539088, mask=2, boxc=
2004-03-23 23:45:05 [3331] [10] DEBUG: CIMD2[testact]: received: <53:111  021:+41787144306        060:040323234505        B6>
2004-03-23 23:45:05 [3331] [10] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234505, src=78931710906, dst=+41787144306, mask=2, boxc=
2004-03-23 23:45:05 [3330] [9] DEBUG: CIMD2[testact]: received: <53:039   021:+41788018737        060:040323234505        C8>
2004-03-23 23:45:05 [3330] [9] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234505, src=78931710906, dst=+41788018737, mask=2, boxc=
2004-03-23 23:45:05 [3331] [10] DEBUG: CIMD2[testact]: received: <53:113  021:+41786424773        060:040323234505        C0>
2004-03-23 23:45:05 [3331] [10] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234505, src=78931710906, dst=+41786424773, mask=2, boxc=
2004-03-23 23:45:05 [3330] [9] DEBUG: CIMD2[testact]: received: <53:041   021:+41786822057        060:040323234505        BD>
2004-03-23 23:45:05 [3330] [9] DEBUG: DLR[internal]: Adding DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234505, src=78931710906, dst=+41786822057, mask=2, boxc=
2004-03-23 23:45:08 [3331] [10] DEBUG: CIMD2[testact]: received: <23:236  021:41788553938 060:040323234002        061:4   063:040323234508        E4>
2004-03-23 23:45:08 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234002, dst=41788553938, type=1
2004-03-23 23:45:10 [3331] [10] DEBUG: CIMD2[testact]: received: <23:238  021:41787144306 060:040323234505        061:4   063:040323234510        D7>
2004-03-23 23:45:10 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234505, dst=41787144306, type=1
2004-03-23 23:45:10 [3331] [10] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:45:11 [3330] [9] DEBUG: CIMD2[testact]: received: <23:250   021:41788018737 060:040323234505        061:4   063:040323234510        DA>
2004-03-23 23:45:11 [3330] [9] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234505, dst=41788018737, type=1
2004-03-23 23:45:12 [3330] [9] DEBUG: CIMD2[testact]: received: <23:252   021:41787539088 060:040323234505        061:4   063:040323234511        E3>
2004-03-23 23:45:12 [3330] [9] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234505, dst=41787539088, type=1
2004-03-23 23:45:12 [3330] [9] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:45:58 [3330] [9] DEBUG: CIMD2[testact]: received: <23:022   021:41786822057 060:040323234505        061:4   063:040323234556        DD>
2004-03-23 23:45:58 [3330] [9] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:xxx.xxx.xxx.xxx:9971:testuser, ts=040323234505, dst=41786822057, type=1
2004-03-23 23:45:58 [3330] [9] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-23 23:47:41 [3331] [10] DEBUG: CIMD2[testact]: received: <23:060  021:41786424773 060:040323234505        061:4   063:040323234740        DD>
2004-03-23 23:47:41 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234505, dst=41786424773, type=1
2004-03-23 23:47:41 [3331] [10] DEBUG: DLR[internal]: Ignoring DLR message because of mask type=1 dlr->mask=2
2004-03-24 08:31:31 [3331] [10] DEBUG: CIMD2[testact]: received: <23:030  021:41787883971 060:040323234505        061:2   063:040324083129        E6>
2004-03-24 08:31:31 [3331] [10] DEBUG: DLR[internal]: Looking for DLR smsc=CIMD2:yyy.yyy.yyy.yyy:9971:testuser, ts=040323234505, dst=41787883971, type=2
2004-03-24 08:31:31 [3331] [10] DEBUG: DLR[internal]: created DLR message for URL <http://localhost:8047/Red/sms/dlrs/orange?dlrval=%d&dlrres=%A&receiver=%q&sender=%Q>
smsbox.log (text/plain, 746 B) 
2004-03-24 08:31:31 [3341] [4] INFO: Starting delivery report <testact> from <78931710906>
2004-03-24 08:31:31 [3348] [9] DEBUG: Parsing URL `http://localhost:8047/Red/sms/dlrs/testuser?dlrval=2&dlrres=&receiver=%2B41787144306&sender=78931710906':

2004-03-24 08:31:31 [3341] [4] INFO: Starting delivery report <testact> from <78931710906>
2004-03-24 08:31:31 [3348] [9] DEBUG: Parsing URL `http://localhost:8047/Red/sms/dlrs/testuser?dlrval=2&dlrres=&receiver=%2B41787883971&sender=78931710906':

2004-03-24 08:31:31 [3341] [4] INFO: Starting delivery report <testact> from <78931710906>
2004-03-24 08:31:31 [3348] [9] DEBUG: Parsing URL `http://localhost:8047/Red/sms/dlrs/testuser?dlrval=2&dlrres=&receiver=%2B41786424773&sender=78931710906':
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.