Project

General

Profile

Actions

Bug #6342

open

ms-sdr gprs performance

Added by Hoernchen 4 months ago. Updated 3 months ago.

Status:
Feedback
Priority:
Normal
Assignee:
Category:
-
Target version:
-
Start date:
01/24/2024
Due date:
% Done:

0%

Resolution:
Spec Reference:

Description

A simple ping test using using one 74b ping packet per second

ping -I modem4 google.de

Is reliable, but in a very unreliable way:
 ✘ pi5@rp5  ~/bernd/osmo-trx  ➦ 669ee04 ±  ping -I modem4 google.de
PING  (142.251.36.163) from 176.16.222.14 modem4: 56(84) bytes of data.
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=1 ttl=117 time=1540 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=2 ttl=117 time=587 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=3 ttl=117 time=5486 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=4 ttl=117 time=4530 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=5 ttl=117 time=3584 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=6 ttl=117 time=2620 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=7 ttl=117 time=1656 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=8 ttl=117 time=692 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=9 ttl=117 time=532 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=10 ttl=117 time=654 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=11 ttl=117 time=5593 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=12 ttl=117 time=4621 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=13 ttl=117 time=3675 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=14 ttl=117 time=2711 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=15 ttl=117 time=1747 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=16 ttl=117 time=783 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=17 ttl=117 time=623 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=18 ttl=117 time=745 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=19 ttl=117 time=5703 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=20 ttl=117 time=4732 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=21 ttl=117 time=3791 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=22 ttl=117 time=2827 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=23 ttl=117 time=1863 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=24 ttl=117 time=899 ms
64 bytes from muc12s11-in-f3.1e100.net (142.251.36.163): icmp_seq=25 ttl=117 time=740 ms
^C
---  ping statistics ---
26 packets transmitted, 25 received, 3.84615% packet loss, time 25381ms
rtt min/avg/max/mdev = 532.376/2517.521/5703.264/1774.915 ms, pipe 6

The transfer is chunky and stalls all the time, it always repeats the same block of messages:

snibedi snab 8< 8< 8<
20240124150628408 DL1C INFO l1ctl.c:754 Tx Reset Req (1)
20240124150628408 DL1C INFO l1ctl.c:404 Sync Req
20240124150628410 DL1C INFO l1ctl.c:764 Layer1 Reset indication
20240124150628410 DCS NOTICE app_modem.c:219 S_L1CTL_RESET
20240124150628441 DL1C INFO l1ctl.c:138 snr=0000, arfcn=871 result=0
20240124150628441 DCS NOTICE app_modem.c:243 S_L1CTL_FBSB_RESP
20240124150628510 DRR NOTICE grr.c:583 GPRS-RR(1)[0x55678c25b0]{PACKET_NOT_READY}: Cell is usable, GRR becomes ready
20240124150628551 DRR NOTICE grr.c:683 GPRS-RR(1)[0x55678c25b0]{PACKET_ACCESS}: Rx RACH.conf (RA=0x79, T1=6, T3=14, T2=23, FN=8837)
20240124150628734 DTUN DEBUG app_modem.c:122 APN(internet): system wants to transmit IPv4 pkt to 142.251.36.163 (84 bytes)
20240124150628734 DSNDCP INFO sndcp_prim.c:426 Rx from upper layers: SN-UNITDATA.request
20240124150628764 DL1C INFO l1ctl.c:553 Tx Dedic.Mode Est Req (arfcn=979, chan_nr=0xc3)
20240124150628822 DGMM INFO gmm_prim.c:677 Rx from lower layers: GMRR-LLC_TRANSMITTED.indication
20240124150628822 DGMM INFO gmm.c:311 GMME(IMSI-001010000000000:PTMSI-d041daef:TLLI-d041daef) READY timer started (expires in 44 seconds)
20240124150628882 DGMM INFO gmm_prim.c:677 Rx from lower layers: GMRR-LLC_TRANSMITTED.indication
20240124150628882 DGMM INFO gmm.c:311 GMME(IMSI-001010000000000:PTMSI-d041daef:TLLI-d041daef) READY timer started (expires in 44 seconds)
20240124150628942 DGMM INFO gmm_prim.c:677 Rx from lower layers: GMRR-LLC_TRANSMITTED.indication
20240124150628942 DGMM INFO gmm.c:311 GMME(IMSI-001010000000000:PTMSI-d041daef:TLLI-d041daef) READY timer started (expires in 44 seconds)
20240124150629021 DGMM INFO gmm_prim.c:677 Rx from lower layers: GMRR-LLC_TRANSMITTED.indication
20240124150629021 DGMM INFO gmm.c:311 GMME(IMSI-001010000000000:PTMSI-d041daef:TLLI-d041daef) READY timer started (expires in 44 seconds)
20240124150629098 DGMM INFO gmm_prim.c:677 Rx from lower layers: GMRR-LLC_TRANSMITTED.indication
20240124150629098 DGMM INFO gmm.c:311 GMME(IMSI-001010000000000:PTMSI-d041daef:TLLI-d041daef) READY timer started (expires in 44 seconds)
20240124150629159 DGMM INFO gmm_prim.c:677 Rx from lower layers: GMRR-LLC_TRANSMITTED.indication
20240124150629159 DGMM INFO gmm.c:311 GMME(IMSI-001010000000000:PTMSI-d041daef:TLLI-d041daef) READY timer started (expires in 44 seconds)
20240124150629200 DSNDCP INFO sndcp_prim.c:594 Rx from lower layers: LL-UNITDATA.indication
20240124150629260 DSNDCP INFO sndcp_prim.c:594 Rx from lower layers: LL-UNITDATA.indication
20240124150629338 DSNDCP INFO sndcp_prim.c:594 Rx from lower layers: LL-UNITDATA.indication
20240124150629398 DSNDCP INFO sndcp_prim.c:594 Rx from lower layers: LL-UNITDATA.indication
20240124150629458 DSNDCP INFO sndcp_prim.c:594 Rx from lower layers: LL-UNITDATA.indication
20240124150629518 DSNDCP INFO sndcp_prim.c:594 Rx from lower layers: LL-UNITDATA.indication
20240124150629735 DTUN DEBUG app_modem.c:122 APN(internet): system wants to transmit IPv4 pkt to 142.251.36.163 (84 bytes)
20240124150629735 DSNDCP INFO sndcp_prim.c:426 Rx from upper layers: SN-UNITDATA.request
20240124150630119 DGMM INFO gmm_prim.c:677 Rx from lower layers: GMRR-LLC_TRANSMITTED.indication
20240124150630119 DGMM INFO gmm.c:311 GMME(IMSI-001010000000000:PTMSI-d041daef:TLLI-d041daef) READY timer started (expires in 44 seconds)
20240124150630358 DSNDCP INFO sndcp_prim.c:594 Rx from lower layers: LL-UNITDATA.indication
20240124150630735 DTUN DEBUG app_modem.c:122 APN(internet): system wants to transmit IPv4 pkt to 142.251.36.163 (84 bytes)
20240124150630735 DSNDCP INFO sndcp_prim.c:426 Rx from upper layers: SN-UNITDATA.request
20240124150631240 DGMM INFO gmm_prim.c:677 Rx from lower layers: GMRR-LLC_TRANSMITTED.indication
20240124150631240 DGMM INFO gmm.c:311 GMME(IMSI-001010000000000:PTMSI-d041daef:TLLI-d041daef) READY timer started (expires in 44 seconds)
20240124150631480 DSNDCP INFO sndcp_prim.c:594 Rx from lower layers: LL-UNITDATA.indication
20240124150631736 DTUN DEBUG app_modem.c:122 APN(internet): system wants to transmit IPv4 pkt to 142.251.36.163 (84 bytes)
20240124150631736 DSNDCP INFO sndcp_prim.c:426 Rx from upper layers: SN-UNITDATA.request
20240124150632766 DTUN DEBUG app_modem.c:122 APN(internet): system wants to transmit IPv4 pkt to 142.251.36.163 (84 bytes)
20240124150632766 DSNDCP INFO sndcp_prim.c:426 Rx from upper layers: SN-UNITDATA.request
20240124150633790 DTUN DEBUG app_modem.c:122 APN(internet): system wants to transmit IPv4 pkt to 142.251.36.163 (84 bytes)
20240124150633790 DSNDCP INFO sndcp_prim.c:426 Rx from upper layers: SN-UNITDATA.request
20240124150634814 DTUN DEBUG app_modem.c:122 APN(internet): system wants to transmit IPv4 pkt to 142.251.36.163 (84 bytes)
20240124150634814 DSNDCP INFO sndcp_prim.c:426 Rx from upper layers: SN-UNITDATA.request
20240124150635838 DTUN DEBUG app_modem.c:122 APN(internet): system wants to transmit IPv4 pkt to 142.251.36.163 (84 bytes)
20240124150635838 DSNDCP INFO sndcp_prim.c:426 Rx from upper layers: SN-UNITDATA.request
20240124150636600 DRLCMAC NOTICE tbf_dl.c:103 TBF(DL:NR-5:TLLI-d041daef) Timeout of T3190

snibedi snab 8< 8< 8<
loops here

curl --interface modem4 http://fsn.icmp.hetzner.com/1GB.bin -o /dev/null

Fails completely, probably for the same reason.

pcap contains debug log level messages.

all of this is with multislot-class default 1 pcu config.


Files

brokengprs.pcapng brokengprs.pcapng 6.41 MB Hoernchen, 01/24/2024 02:23 PM
ms-cni-gprsping.tar.xz ms-cni-gprsping.tar.xz 2.77 MB ms lo + ms modem4 + cni lo Hoernchen, 01/24/2024 05:19 PM

Related issues

Related to OsmocomBB - Bug #6200: osmo-trx-ms: lots of @Received bad frame (rc=-1, ber=444/444)@NewHoernchen10/03/2023

Actions
Actions

Also available in: Atom PDF

Add picture from clipboard (Maximum size: 48.8 MB)