touch-packages team mailing list archive

Thread
Date
[Bug 1480554] Re: High costs due to SMS being sent multiple times when having a bad network connection

To: touch-packages@xxxxxxxxxxxxxxxxxxx
From: Matthias Apitz <guru@xxxxxxxxxxx>
Date: Wed, 05 Aug 2015 07:43:34 -0000
Reply-to: Bug 1480554 <1480554@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
this is how the SMS could be monitored on sending to the modem, but only
"online" or short after the sending:


root@ubuntu-phablet:/home/phablet# /system/bin/logcat -b radio | tee /tmp/radio.out

(btw: one should better use: /system/bin/logcat -b radio -v time
to get as well the timestamps)

...
I/use-Rlog/RLOG-AT( 1821): AT read start
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 3557:main(): Frames received/dropped: 6560/0
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 1573:gsm0710_base_buffer_get_frame(): Get a complete frame. ch:2, ctrl:239, len:34
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2162:extract_frames(): Writing 34 byte frame received on channel 2 to /dev/pts/5
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2181:extract_frames(): write() returned. Written 34/34 bytes of frame to /dev/pts/5
D/use-Rlog/RLOG-AT( 1821): 
D/use-Rlog/RLOG-AT( 1821): +ECSQ: 11, 99, -368, -400, -28
D/use-Rlog/RLOG-AT( 1821): AT< +ECSQ: 11, 99, -368, -400, -28
D/use-Rlog/RLOG-AT( 1821): RIL_URC_READER:+ECSQ: 11, 99, -368, -400, -28
D/use-Rlog/RLOG-AT( 1821): RIL_URC_READER Enter processLine
D/use-Rlog/RLOG-RIL( 1821): Nw URC:+ECSQ: 11, 99, -368, -400, -28
I/use-Rlog/RLOG-RILC( 1821): RIL(1) UNSOLICITED: UNSOL_SIGNAL_STRENGTH length:76
I/use-Rlog/RLOG-RILC( 1821): 01 00 00 00 F1 03 00 00 0B 00 00 00 63 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 63 00 00 00 FF FF FF 7F FF FF FF 7F
I/use-Rlog/RLOG-RILC( 1821): 2D 01 00 00 FF FF FF 7F 01 00 00 00 00 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 90 FE FF FF 70 FE FF FF E4 FF FF FF 0B 00 00 00
I/use-Rlog/RLOG-AT( 1821): AT read start
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 1573:gsm0710_base_buffer_get_frame(): Get a complete frame. ch:2, ctrl:239, len:34
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2162:extract_frames(): Writing 34 byte frame received on channel 2 to /dev/pts/5
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2181:extract_frames(): write() returned. Written 34/34 bytes of frame to /dev/pts/5
D/use-Rlog/RLOG-AT( 1821): 
D/use-Rlog/RLOG-AT( 1821): +ECSQ: 11, 99, -372, -398, -24
D/use-Rlog/RLOG-AT( 1821): AT< +ECSQ: 11, 99, -372, -398, -24
D/use-Rlog/RLOG-AT( 1821): RIL_URC_READER:+ECSQ: 11, 99, -372, -398, -24
D/use-Rlog/RLOG-AT( 1821): RIL_URC_READER Enter processLine
D/use-Rlog/RLOG-RIL( 1821): Nw URC:+ECSQ: 11, 99, -372, -398, -24
I/use-Rlog/RLOG-RILC( 1821): RIL(1) UNSOLICITED: UNSOL_SIGNAL_STRENGTH length:76
I/use-Rlog/RLOG-RILC( 1821): 01 00 00 00 F1 03 00 00 0B 00 00 00 63 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 63 00 00 00 FF FF FF 7F FF FF FF 7F
I/use-Rlog/RLOG-RILC( 1821): 2D 01 00 00 FF FF FF 7F 01 00 00 00 00 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 8C FE FF FF 72 FE FF FF E8 FF FF FF 0B 00 00 00
I/use-Rlog/RLOG-AT( 1821): AT read start
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 3557:main(): Frames received/dropped: 6562/0
I/use-Rlog/RLOG-RILC( 1821): RIL(1) SOCKET REQUEST: SEND_SMS length:348
I/use-Rlog/RLOG-RILC( 1821): 19 00 00 00 27 02 00 00 02 00 00 00 FF FF FF FF
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
I/use-Rlog/RLOG-RILC( 1821): xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx
D/use-Rlog/RLOG-RILC( 1821): New command received from RIL(1)
D/use-Rlog/RLOG-RIL( 1821): SEND_SMS dispatched to RIL_CMD_PROXY_1
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD2_PROXY_4 wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD2_PROXY_4 sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD2_PROXY_2 wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD2_PROXY_2 sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD_PROXY_4 wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD_PROXY_4 sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_URC_PROXY wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_URC_PROXY sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_ATCI_PROXY wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_ATCI_PROXY sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_ATCI2_PROXY wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_ATCI2_PROXY sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD_PROXY_1 wakeup
D/use-Rlog/RLOG-RIL( 1821): SEND_SMS execute on RIL_CMD_PROXY_1 using channel 3
D/use-Rlog/RLOG-RIL( 1821): onRequest: SEND_SMS, datalen = 8
D/use-Rlog/RLOG-RIL( 1821): radioState:4
D/use-Rlog/RLOG-AT( 1821): AT send on RIL_CMD_READER_3, tid:3099159288
D/use-Rlog/RLOG-AT( 1821): AT> AT+EMGS=81, "xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx"
D/use-Rlog/RLOG-AT( 1821): AT+EMGS=81, "xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx"
I/use-Rlog/RLOG-AT( 1821): AT write start
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD_PROXY_2 wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD_PROXY_2 sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_URC2_PROXY wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_URC2_PROXY sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD2_PROXY_1 wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD2_PROXY_1 sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD_PROXY_3 wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD_PROXY_3 sleep
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD2_PROXY_3 wakeup
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD2_PROXY_3 sleep
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 3349:poll_thread(): poll_thread select found
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 978:pseudo_device_read(): Enter
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 602:write_frame(): Sending frame to channel 5
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 699:write_frame(): Write a frame on channel 5 with length 178
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 3349:poll_thread(): poll_thread select found
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 978:pseudo_device_read(): Enter
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 602:write_frame(): Sending frame to channel 5
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 699:write_frame(): Write a frame on channel 5 with length 1
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 1573:gsm0710_base_buffer_get_frame(): Get a complete frame. ch:2, ctrl:239, len:33
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2162:extract_frames(): Writing 33 byte frame received on channel 2 to /dev/pts/5
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2181:extract_frames(): write() returned. Written 33/33 bytes of frame to /dev/pts/5
D/use-Rlog/RLOG-AT( 1821): 
D/use-Rlog/RLOG-AT( 1821): +ECSQ: 8, 99, -380, -421, -38
D/use-Rlog/RLOG-AT( 1821): AT< +ECSQ: 8, 99, -380, -421, -38
D/use-Rlog/RLOG-AT( 1821): RIL_URC_READER:+ECSQ: 8, 99, -380, -421, -38
D/use-Rlog/RLOG-AT( 1821): RIL_URC_READER Enter processLine
D/use-Rlog/RLOG-RIL( 1821): Nw URC:+ECSQ: 8, 99, -380, -421, -38
I/use-Rlog/RLOG-RILC( 1821): RIL(1) UNSOLICITED: UNSOL_SIGNAL_STRENGTH length:76
I/use-Rlog/RLOG-RILC( 1821): 01 00 00 00 F1 03 00 00 08 00 00 00 63 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 63 00 00 00 FF FF FF 7F FF FF FF 7F
I/use-Rlog/RLOG-RILC( 1821): 2D 01 00 00 FF FF FF 7F 01 00 00 00 00 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 84 FE FF FF 5B FE FF FF DA FF FF FF 45 00 39 00
I/use-Rlog/RLOG-AT( 1821): AT read start
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 1573:gsm0710_base_buffer_get_frame(): Get a complete frame. ch:5, ctrl:239, len:13
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2162:extract_frames(): Writing 13 byte frame received on channel 5 to /dev/pts/8
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2181:extract_frames(): write() returned. Written 13/13 bytes of frame to /dev/pts/8
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 1573:gsm0710_base_buffer_get_frame(): Get a complete frame. ch:5, ctrl:239, len:6
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2162:extract_frames(): Writing 6 byte frame received on channel 5 to /dev/pts/8
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2181:extract_frames(): write() returned. Written 6/6 bytes of frame to /dev/pts/8
D/use-Rlog/RLOG-AT( 1821): 
D/use-Rlog/RLOG-AT( 1821): +CMGS: 24
D/use-Rlog/RLOG-AT( 1821): 
D/use-Rlog/RLOG-AT( 1821): OK
D/use-Rlog/RLOG-AT( 1821): AT< +CMGS: 24
D/use-Rlog/RLOG-AT( 1821): RIL_CMD_READER_3:+CMGS: 24
D/use-Rlog/RLOG-AT( 1821): RIL_CMD_READER_3 Enter processLine
D/use-Rlog/RLOG-AT( 1821): AT< OK
D/use-Rlog/RLOG-AT( 1821): RIL_CMD_READER_3:OK
D/use-Rlog/RLOG-AT( 1821): RIL_CMD_READER_3 Enter processLine
I/use-Rlog/RLOG-AT( 1821): AT read start
D/use-Rlog/RLOG-AT( 1821): response received on RIL_CMD_READER_3, tid:3099159288
I/use-Rlog/RLOG-RILC( 1821): RIL SOCKET RESPONSE: SEND_SMS length:24
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 27 02 00 00 00 00 00 00 18 00 00 00
I/use-Rlog/RLOG-RILC( 1821): FF FF FF FF 00 00 00 00 DA FF FF FF 69 00 00 00
I/use-Rlog/RLOG-RIL( 1821): RIL_CMD_PROXY_1 sleep
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 1573:gsm0710_base_buffer_get_frame(): Get a complete frame. ch:2, ctrl:239, len:33
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2162:extract_frames(): Writing 33 byte frame received on channel 2 to /dev/pts/5
I/use-Rlog/RLOG-MUXD( 1234): [gsm0710muxd] 2181:extract_frames(): write() returned. Written 33/33 bytes of frame to /dev/pts/5
D/use-Rlog/RLOG-AT( 1821): 
D/use-Rlog/RLOG-AT( 1821): +ECSQ: 8, 99, -380, -422, -40
D/use-Rlog/RLOG-AT( 1821): AT< +ECSQ: 8, 99, -380, -422, -40
D/use-Rlog/RLOG-AT( 1821): RIL_URC_READER:+ECSQ: 8, 99, -380, -422, -40
D/use-Rlog/RLOG-AT( 1821): RIL_URC_READER Enter processLine
D/use-Rlog/RLOG-RIL( 1821): Nw URC:+ECSQ: 8, 99, -380, -422, -40
I/use-Rlog/RLOG-RILC( 1821): RIL(1) UNSOLICITED: UNSOL_SIGNAL_STRENGTH length:76
I/use-Rlog/RLOG-RILC( 1821): 01 00 00 00 F1 03 00 00 08 00 00 00 63 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 00 00 00 00 63 00 00 00 FF FF FF 7F FF FF FF 7F
I/use-Rlog/RLOG-RILC( 1821): 2D 01 00 00 FF FF FF 7F 01 00 00 00 00 00 00 00
I/use-Rlog/RLOG-RILC( 1821): 84 FE FF FF 5A FE FF FF D8 FF FF FF 00 00 00 00
I/use-Rlog/RLOG-AT( 1821): AT read start
...

-- 
You received this bug notification because you are a member of Ubuntu
Touch seeded packages, which is subscribed to telephony-service in
Ubuntu.
https://bugs.launchpad.net/bugs/1480554

Title:
  High costs due to SMS being sent multiple times when having a bad
  network connection

Status in telephony-service package in Ubuntu:
  Confirmed

Bug description:
  When having a bad network connection, Ubuntu tries to send SMS
  multiple times but fails to recognize which messages have already been
  sent, resulting in very high costs due to the same message actually
  being sent several times.

  I found this when looking through my provider's monthly reports and I
  remember being on train journeys with very bad network connections on
  these days. See the attached screenshots.

  ==========

  This happened on a Nexus 4 running on the stable channel (with up-to-
  date images at the given times).

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/telephony-service/+bug/1480554/+subscriptions
References

[Bug 1480554] [NEW] High costs due to SMS being sent multiple times when having a bad network connection
From: Niklas Wenzel, 2015-08-01