ftplanner: Stahovani dat se po vypadku rozhrani neobnovi

Je to pripad, kdy neni zaple restartovani testu. Na serveru spadne iperf se segfaultem. U klienta je pak test ve stavu downloading a nic se nedeje. Zde by to chtelo tuto situaci osetrit tak aby se stahlo co je. Jde to?

Pokud probiha test a dojde k restartu rozhrani, tak test je prerusen a dojde k pokusum o stahovani. To trva do doby nez se spojeni obnovi, ale nakonec se data nestahnou viz log:

Thu Jan 26 09:02:56 2023 daemon.warn [14918]: <warn>  [modem2/sim1] couldn't load list of emergency numbers: No AT port available to run command
Thu Jan 26 09:02:56 2023 daemon.warn [14918]: <warn>  [modem2] couldn't load list of own numbers: Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned'
Thu Jan 26 09:02:56 2023 daemon.warn [14918]: <warn>  [modem2] couldn't load UE mode of operation for EPS: No AT port available to run command
Thu Jan 26 09:02:56 2023 daemon.err lua[5637]: Failed to send request: Operation not permitted
Thu Jan 26 09:02:56 2023 daemon.info lua[5637]: ubus-rpc.lua: Failed to fetch data
Thu Jan 26 09:02:56 2023 daemon.info lua[5730]: ftplanner: Response to RPC call related to scenario TCP DOWN-20230126095906 failed! Reason: Failed to fetch data
Thu Jan 26 09:02:56 2023 daemon.warn [14918]: <warn>  [modem2] couldn't load supported assistance data types: LOC service: general failure
Thu Jan 26 09:02:56 2023 daemon.warn [14918]: <warn>  [modem2] couldn't open ports during Modem SIM hot swap enabling: Couldn't get primary port
Thu Jan 26 09:02:56 2023 daemon.info [14918]: <info>  [modem2] state changed (unknown -> disabled)
Thu Jan 26 09:02:57 2023 daemon.err lua[5637]: Failed to send request: Operation not permitted
Thu Jan 26 09:02:57 2023 daemon.info lua[5637]: ubus-rpc.lua: Failed to fetch data
Thu Jan 26 09:02:57 2023 daemon.info lua[5730]: ftplanner: Response to RPC call related to scenario TCP DOWN-20230126095906 failed! Reason: Failed to fetch data
Thu Jan 26 09:02:58 2023 daemon.err lua[5637]: Failed to send request: Operation not permitted
Thu Jan 26 09:02:58 2023 daemon.info lua[5637]: ubus-rpc.lua: Failed to fetch data
Thu Jan 26 09:02:58 2023 daemon.err lua[5637]: Failed to send request: Operation not permitted
Thu Jan 26 09:02:58 2023 daemon.info lua[5637]: ubus-rpc.lua: Failed to fetch data
Thu Jan 26 09:02:58 2023 daemon.info lua[5730]: ftplanner: Response to RPC call related to scenario TCP DOWN-20230126095906 failed! Reason: Failed to fetch data
Thu Jan 26 09:02:58 2023 daemon.info ModemManager[21970]: hotplug: modem exported successfully at /sys/devices/pci0000:00/0000:00:02.5/0000:04:00.0/usb6/6-1
Thu Jan 26 09:02:58 2023 daemon.info ModemManager[21970]: hotplug: setting interface 'mobile' as available
Thu Jan 26 09:02:58 2023 daemon.notice netifd: Interface 'mobile' is setting up now
Thu Jan 26 09:02:58 2023 daemon.notice netifd: mobile (23087): modem available at /org/freedesktop/ModemManager1/Modem/2
Thu Jan 26 09:02:58 2023 daemon.notice netifd: mobile (23087): starting connection with apn 'internet'...
Thu Jan 26 09:02:58 2023 daemon.info [14918]: <info>  [modem2] simple connect started...
Thu Jan 26 09:02:58 2023 daemon.info [14918]: <info>  [modem2] simple connect state (3/8): enable
Thu Jan 26 09:02:58 2023 daemon.info [14918]: <info>  [modem2] state changed (disabled -> enabling)
Thu Jan 26 09:02:58 2023 daemon.info [14918]: <info>  [modem2] power state updated: on
Thu Jan 26 09:02:59 2023 daemon.err lua[5637]: Failed to send request: Operation not permitted
Thu Jan 26 09:02:59 2023 daemon.info lua[5637]: ubus-rpc.lua: Failed to fetch data
Thu Jan 26 09:02:59 2023 daemon.info lua[5730]: ftplanner: Response to RPC call related to scenario TCP DOWN-20230126095906 failed! Reason: Failed to fetch data
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] simple connect state (4/8): wait to get fully enabled
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] state changed (enabling -> enabled)
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] simple connect state (5/8): register
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] 3GPP registration state changed (unknown -> registering)
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] 3GPP registration state changed (registering -> home)
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] state changed (enabled -> registered)
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] simple connect state (6/8): bearer
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] simple connect state (7/8): connect
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] state changed (registered -> connecting)
Thu Jan 26 09:03:00 2023 daemon.debug [14918]: [/dev/cdc-wdm0] Allocating new client ID...
Thu Jan 26 09:03:00 2023 daemon.debug [14918]: [/dev/cdc-wdm0] Registered 'wds' (version 1.196) client with ID '17'
Thu Jan 26 09:03:00 2023 daemon.err lua[5637]: Failed to send request: Operation not permitted
Thu Jan 26 09:03:00 2023 daemon.info lua[5637]: ubus-rpc.lua: Failed to fetch data
Thu Jan 26 09:03:00 2023 daemon.info lua[5730]: ftplanner: Response to RPC call related to scenario TCP DOWN-20230126095906 failed! Reason: Failed to fetch data
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2/bearer3] QMI IPv4 Settings:
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2/bearer3]     address: 10.229.236.214/30
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2/bearer3]     gateway: 10.229.236.213
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2/bearer3]     DNS #1: 31.30.90.1
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2/bearer3]     DNS #2: 31.30.90.2
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2/bearer3]        MTU: 1500
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] state changed (connecting -> connected)
Thu Jan 26 09:03:00 2023 daemon.info [14918]: <info>  [modem2] simple connect state (8/8): all done
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): successfully connected the modem
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): signal refresh rate is not set
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): network operator name: Vodafone CZ
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): network operator MCCMNC: 23003
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): registration type: home
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): access technology: lte
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): signal quality: 65%
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): IPv4 connection setup required in interface mobile: static
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): adding IPv4 address 10.229.236.214, netmask 255.255.255.252
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): adding default IPv4 route via 10.229.236.213
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): adding primary DNS at 31.30.90.1
Thu Jan 26 09:03:00 2023 daemon.notice netifd: mobile (23087): adding secondary DNS at 31.30.90.2
Thu Jan 26 09:03:00 2023 daemon.notice netifd: Interface 'mobile' is now up
Thu Jan 26 09:03:00 2023 daemon.notice netifd: Network device 'wwan0' link is up

Segfault na strane serveru:

Thu Jan 26 08:58:56 2023 daemon.info lua[3667]: ftrm: Time offset for the client is 0s.
Thu Jan 26 08:58:56 2023 daemon.info lua[3667]: ftrm: Booking resources for tests '[{"duration":600,"type":"iperf3","delay":0,"opts":"-i 1 --rcv-timeout 10000 --snd-timeout 10000"}]'
Thu Jan 26 08:59:01 2023 daemon.info lua[3667]: ftrm: Starting a server iperf3-1-20230126-0858-TCP-DOWN-20230126095906-1674723536
Thu Jan 26 09:02:28 2023 kern.info kernel: [  651.413905] iperf3[3775]: segfault at 8c ip 00007f6d0d0a924b sp 00007ffc396072a0 error 4 in libc.so[7f6d0d070000+49000]
Thu Jan 26 09:02:28 2023 kern.info kernel: [  651.424844] Code: 31 ff 48 89 44 24 10 0f 10 02 0f 11 44 24 20 48 8b 42 10 48 8b 54 24 10 48 89 44 24 30 e8 f9 f5 ff ff 85 c0 0f 88 00 01 00 00 <8b> 83 8c 00 00 00 45 31 ed 85 c0 78 0b 48 89 df e8 8c aa ff ff 41

Replikace:

1. Nastaveno automaticke stazeni dat po skonceni testu
2. Spustit test
3. Restartovat modem
4. Behem restartu dojde k ukonceni testu a zacatku stahovani dat
5. Stahovani je neuspesne, protoze modem je odpojen
6. Jakmile je navazano spojeni, tak se data stejne nestahnou a zustanou ve stavu downloading, na serveru je u iperfu segfault