- {"dt": "2024-12-11T12:26:54.793086", "lvl": "warning", "msg": "Action timeout for connect-device exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.793862", "lvl": "warning", "msg": "Action timeout for pdu-reboot exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.794375", "lvl": "warning", "msg": "Action timeout for reset-device exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.794712", "lvl": "warning", "msg": "Action timeout for login-action exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.794973", "lvl": "warning", "msg": "Action timeout for auto-login-action exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.795297", "lvl": "warning", "msg": "Action timeout for expect-shell-connection exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.795558", "lvl": "warning", "msg": "Action timeout for overlay-unpack exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.795829", "lvl": "warning", "msg": "Action timeout for export-device-env exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.796093", "lvl": "warning", "msg": "Action timeout for minimal-boot exceeds Job timeout"}
- {"dt": "2024-12-11T12:26:54.820798", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-12-11T12:26:54.821070", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-12-11T12:26:54.821405", "lvl": "info", "msg": "Start time: 2024-12-11 12:26:54.821391+00:00 (UTC)"}
- {"dt": "2024-12-11T12:26:54.821784", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/initramfs-netboot-image-raspberrypi5-20241028040434.ext4.gz exists"}
- {"dt": "2024-12-11T12:26:55.074884", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image exists"}
- {"dt": "2024-12-11T12:26:55.328960", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/bcm2712-rpi-5-b.dtb exists"}
- {"dt": "2024-12-11T12:26:55.570295", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4.xz exists"}
- {"dt": "2024-12-11T12:26:55.826229", "lvl": "info", "msg": "validate duration: 1.00"}
- {"dt": "2024-12-11T12:26:55.826535", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-12-11T12:26:55.827024", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 00:10:00) [common]"}
- {"dt": "2024-12-11T12:26:55.827271", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2024-12-11T12:26:55.827501", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2024-12-11T12:26:55.827758", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2024-12-11T12:26:55.828219", "lvl": "debug", "msg": "Set_port 55631"}
- {"dt": "2024-12-11T12:26:55.828545", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-12-11T12:26:55.828855", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 00:10:00) [common]"}
- {"dt": "2024-12-11T12:26:55.829423", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/initramfs-netboot-image-raspberrypi5-20241028040434.ext4.gz"}
- {"dt": "2024-12-11T12:26:55.829650", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-11T12:26:55.829866", "lvl": "debug", "msg": "total size: 2153804 (2\u202FMB)"}
- {"dt": "2024-12-11T12:26:55.830116", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-12-11T12:26:56.181559", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:56.595335", "lvl": "debug", "msg": "progress   6\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:56.699666", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:56.802608", "lvl": "debug", "msg": "progress  18\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:56.807489", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:56.904719", "lvl": "debug", "msg": "progress  30\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:56.909580", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:56.913196", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:56.924658", "lvl": "debug", "msg": "progress  47\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.004041", "lvl": "debug", "msg": "progress  53\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.009105", "lvl": "debug", "msg": "progress  59\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.014037", "lvl": "debug", "msg": "progress  65\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.041394", "lvl": "debug", "msg": "progress  71\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.044731", "lvl": "debug", "msg": "progress  76\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.056935", "lvl": "debug", "msg": "progress  82\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.059102", "lvl": "debug", "msg": "progress  88\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.075742", "lvl": "debug", "msg": "progress  94\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.078034", "lvl": "debug", "msg": "progress 100\u202F% (2\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.144198", "lvl": "info", "msg": "2\u202FMB downloaded in 1.31\u202Fs (1.56\u202FMB/s)"}
- {"dt": "2024-12-11T12:26:57.144655", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-12-11T12:26:57.144837", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.32", "extra": {"label": "initrd", "md5sum": "69fcb22a915d8fa6208b51a2d38a1bd0", "sha256sum": "59fb98aa51ec6733051adf76648cfc363ccc8367467a4fb8794cfaf7c3b33988", "sha512sum": "52fefc4f3925b72ed297cf2bb536aefa2358e2d66fefd61cede40b155efd2e9b101119081caf7f09fd61d798027cdc78cb63718fd91f573a718dfcb5894851c7", "size": ! "2153804"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:26:57.145218", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-11T12:26:57.145435", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:09:59) [common]"}
- {"dt": "2024-12-11T12:26:57.145648", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 00:09:59) [common]"}
- {"dt": "2024-12-11T12:26:57.145979", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image"}
- {"dt": "2024-12-11T12:26:57.146137", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/kernel/Image"}
- {"dt": "2024-12-11T12:26:57.146297", "lvl": "debug", "msg": "total size: 28887552 (27\u202FMB)"}
- {"dt": "2024-12-11T12:26:57.146455", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-11T12:26:57.489114", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.025265", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.152183", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.240010", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.297558", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.354885", "lvl": "debug", "msg": "progress  25\u202F% (6\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.412015", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.469528", "lvl": "debug", "msg": "progress  35\u202F% (9\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.526380", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.582591", "lvl": "debug", "msg": "progress  45\u202F% (12\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.640247", "lvl": "debug", "msg": "progress  50\u202F% (13\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.696644", "lvl": "debug", "msg": "progress  55\u202F% (15\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.753712", "lvl": "debug", "msg": "progress  60\u202F% (16\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.812163", "lvl": "debug", "msg": "progress  65\u202F% (17\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.869233", "lvl": "debug", "msg": "progress  70\u202F% (19\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.928189", "lvl": "debug", "msg": "progress  75\u202F% (20\u202FMB)"}
- {"dt": "2024-12-11T12:26:58.984716", "lvl": "debug", "msg": "progress  80\u202F% (22\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.041489", "lvl": "debug", "msg": "progress  85\u202F% (23\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.099085", "lvl": "debug", "msg": "progress  90\u202F% (24\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.155716", "lvl": "debug", "msg": "progress  95\u202F% (26\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.212531", "lvl": "debug", "msg": "progress 100\u202F% (27\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.213057", "lvl": "info", "msg": "27\u202FMB downloaded in 2.07\u202Fs (13.33\u202FMB/s)"}
- {"dt": "2024-12-11T12:26:59.213397", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2024-12-11T12:26:59.213618", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "2.07", "extra": {"label": "kernel", "md5sum": "601b383c3c881d04e3368bd84040fdbd", "sha256sum": "99041f31b2ec83f4057caf62ca4bf1534d860cf2d8612fde079894451d55e486", "sha512sum": "e956efcf89178c3aa3fe2491edd04292d25136cf49bc45f7fdfcaa45b40768824dafc595be0d4dbccf066dc2d8dcfce3c6b446189612fa22ab6f3c465cab66c3", "size": ! "28887552"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:26:59.214044", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:02) [common]"}
- {"dt": "2024-12-11T12:26:59.214282", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:09:57) [common]"}
- {"dt": "2024-12-11T12:26:59.214509", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:09:57) [common]"}
- {"dt": "2024-12-11T12:26:59.214860", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-11T12:26:59.215022", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/dtb/bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-11T12:26:59.215186", "lvl": "debug", "msg": "total size: 78059 (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.215346", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-11T12:26:59.550474", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.650717", "lvl": "debug", "msg": "progress  83\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.651480", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.651861", "lvl": "info", "msg": "0\u202FMB downloaded in 0.44\u202Fs (0.17\u202FMB/s)"}
- {"dt": "2024-12-11T12:26:59.652177", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:26:59.652345", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.44", "extra": {"label": "dtb", "md5sum": "e8c29afbbe377e6f0331d46da1c32f91", "sha256sum": "a91e4fa0dcc0ab08b873cfac3c0b7fc17c22ee715d941cbc915a44966c1e8c99", "sha512sum": "6e77dc7abf1cdc1c07a6f9e5f0168f52a9704b6d0142112e5b28cef3e166f8b93612a76b6341e5168e6c1e3d7ab7b4ed115d87749a7379c2d5174a78e19b46c3", "size": ! "78059"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:26:59.652673", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:26:59.652859", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:09:56) [common]"}
- {"dt": "2024-12-11T12:26:59.653041", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:09:56) [common]"}
- {"dt": "2024-12-11T12:26:59.653334", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4.xz"}
- {"dt": "2024-12-11T12:26:59.653482", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-11T12:26:59.653608", "lvl": "debug", "msg": "total size: 493866112 (470\u202FMB)"}
- {"dt": "2024-12-11T12:26:59.653734", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-12-11T12:27:00.030693", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-11T12:27:05.637706", "lvl": "debug", "msg": "progress   5\u202F% (23\u202FMB)"}
- {"dt": "2024-12-11T12:27:08.375459", "lvl": "debug", "msg": "progress  10\u202F% (47\u202FMB)"}
- {"dt": "2024-12-11T12:27:11.335709", "lvl": "debug", "msg": "progress  15\u202F% (70\u202FMB)"}
- {"dt": "2024-12-11T12:27:14.428826", "lvl": "debug", "msg": "progress  20\u202F% (94\u202FMB)"}
- {"dt": "2024-12-11T12:27:18.197916", "lvl": "debug", "msg": "progress  25\u202F% (117\u202FMB)"}
- {"dt": "2024-12-11T12:27:21.286795", "lvl": "debug", "msg": "progress  30\u202F% (141\u202FMB)"}
- {"dt": "2024-12-11T12:27:24.681858", "lvl": "debug", "msg": "progress  35\u202F% (164\u202FMB)"}
- {"dt": "2024-12-11T12:27:29.681080", "lvl": "debug", "msg": "progress  40\u202F% (188\u202FMB)"}
- {"dt": "2024-12-11T12:27:32.601838", "lvl": "debug", "msg": "progress  45\u202F% (211\u202FMB)"}
- {"dt": "2024-12-11T12:27:35.897335", "lvl": "debug", "msg": "progress  50\u202F% (235\u202FMB)"}
- {"dt": "2024-12-11T12:27:39.004021", "lvl": "debug", "msg": "progress  55\u202F% (259\u202FMB)"}
- {"dt": "2024-12-11T12:27:42.530956", "lvl": "debug", "msg": "progress  60\u202F% (282\u202FMB)"}
- {"dt": "2024-12-11T12:27:45.779991", "lvl": "debug", "msg": "progress  65\u202F% (306\u202FMB)"}
- {"dt": "2024-12-11T12:27:49.010658", "lvl": "debug", "msg": "progress  70\u202F% (329\u202FMB)"}
- {"dt": "2024-12-11T12:27:52.737529", "lvl": "debug", "msg": "progress  75\u202F% (353\u202FMB)"}
- {"dt": "2024-12-11T12:27:56.144269", "lvl": "debug", "msg": "progress  80\u202F% (376\u202FMB)"}
- {"dt": "2024-12-11T12:27:58.939360", "lvl": "debug", "msg": "progress  85\u202F% (400\u202FMB)"}
- {"dt": "2024-12-11T12:28:02.192513", "lvl": "debug", "msg": "progress  90\u202F% (423\u202FMB)"}
- {"dt": "2024-12-11T12:28:04.931109", "lvl": "debug", "msg": "progress  95\u202F% (447\u202FMB)"}
- {"dt": "2024-12-11T12:28:11.404845", "lvl": "debug", "msg": "progress 100\u202F% (470\u202FMB)"}
- {"dt": "2024-12-11T12:28:13.615880", "lvl": "info", "msg": "470\u202FMB downloaded in 73.96\u202Fs (6.37\u202FMB/s)"}
- {"dt": "2024-12-11T12:28:13.616383", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:14) [common]"}
- {"dt": "2024-12-11T12:28:13.616594", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "73.96", "extra": {"label": "nbdroot", "md5sum": "4efb22dd71d11d782c04913029ebe146", "sha256sum": "b475a2953ee1a6be85506449e60e93d14579f8af2dc5577723d004a952eda229", "sha512sum": "888ffdcec6b22a5b3cd3e391eaa7a1bf5c6493efe72df9f895ac361c0117ba23335502223b5b769ad9e63ef740001581525ce5eaeaf2ac29e1b76eb53d8395b8", "size": ! "493866112"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:28:13.616968", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:14) [common]"}
- {"dt": "2024-12-11T12:28:13.617181", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.618347", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv"}
- {"dt": "2024-12-11T12:28:13.618791", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin"}
- {"dt": "2024-12-11T12:28:13.619500", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/tests"}
- {"dt": "2024-12-11T12:28:13.619790", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/results"}
- {"dt": "2024-12-11T12:28:13.620066", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-add-keys"}
- {"dt": "2024-12-11T12:28:13.620927", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-add-sources"}
- {"dt": "2024-12-11T12:28:13.621440", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-background-process-start"}
- {"dt": "2024-12-11T12:28:13.621991", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-background-process-stop"}
- {"dt": "2024-12-11T12:28:13.622523", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-common-functions"}
- {"dt": "2024-12-11T12:28:13.623048", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-echo-ipv4"}
- {"dt": "2024-12-11T12:28:13.623554", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-install-packages"}
- {"dt": "2024-12-11T12:28:13.624091", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-installed-packages"}
- {"dt": "2024-12-11T12:28:13.624664", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-os-build"}
- {"dt": "2024-12-11T12:28:13.626398", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-probe-channel"}
- {"dt": "2024-12-11T12:28:13.627046", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-probe-ip"}
- {"dt": "2024-12-11T12:28:13.627648", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-target-ip"}
- {"dt": "2024-12-11T12:28:13.628195", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-target-mac"}
- {"dt": "2024-12-11T12:28:13.628874", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-target-storage"}
- {"dt": "2024-12-11T12:28:13.629342", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-test-case"}
- {"dt": "2024-12-11T12:28:13.629785", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-test-event"}
- {"dt": "2024-12-11T12:28:13.630228", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-test-feedback"}
- {"dt": "2024-12-11T12:28:13.630645", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-test-raise"}
- {"dt": "2024-12-11T12:28:13.631077", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-test-reference"}
- {"dt": "2024-12-11T12:28:13.631501", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-test-runner"}
- {"dt": "2024-12-11T12:28:13.631930", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-test-set"}
- {"dt": "2024-12-11T12:28:13.632347", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-test-shell"}
- {"dt": "2024-12-11T12:28:13.632761", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-install-packages (oe)"}
- {"dt": "2024-12-11T12:28:13.633359", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/bin/lava-installed-packages (oe)"}
- {"dt": "2024-12-11T12:28:13.633925", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/environment"}
- {"dt": "2024-12-11T12:28:13.634321", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-12-11T12:28:13.634539", "lvl": "debug", "msg": "- LAVA_JOB_ID=38294"}
- {"dt": "2024-12-11T12:28:13.634729", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.111.1"}
- {"dt": "2024-12-11T12:28:13.635048", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.636759", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.637072", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.637266", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-12-11T12:28:13.637493", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.637697", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.637844", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-12-11T12:28:13.638103", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.638312", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.638509", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-12-11T12:28:13.638735", "lvl": "debug", "msg": "start: 1.5.4.1 inline-repo-action (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.638918", "lvl": "debug", "msg": "Using /lava-38294 at stage 0"}
- {"dt": "2024-12-11T12:28:13.640227", "lvl": "debug", "msg": "uuid=38294_1.5.4.1 testdef=None"}
- {"dt": "2024-12-11T12:28:13.641341", "lvl": "debug", "msg": "end: 1.5.4.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.641601", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.644458", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.644666", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38294_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:28:13.645033", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.647079", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.647304", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "skipped test-install-overlay": "38294_1.5.4.1", "uuid": "38294_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:28:13.647672", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.649682", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/0/tests/0_dmesg test_uuid 38294_1.5.4.1"}
- {"dt": "2024-12-11T12:28:13.650229", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.650449", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/0/tests/0_dmesg/run.sh", "from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38294_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:28:13.650842", "lvl": "debug", "msg": "start: 1.5.4.5 inline-repo-action (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.651072", "lvl": "debug", "msg": "Using /lava-38294 at stage 1"}
- {"dt": "2024-12-11T12:28:13.652064", "lvl": "debug", "msg": "uuid=38294_1.5.4.5 testdef=None"}
- {"dt": "2024-12-11T12:28:13.652323", "lvl": "debug", "msg": "end: 1.5.4.5 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.652534", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.653854", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.654169", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38294_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:28:13.654532", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.656238", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.656545", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "skipped test-install-overlay": "38294_1.5.4.5", "uuid": "38294_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:28:13.657038", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.659041", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/1/tests/1_bootrr test_uuid 38294_1.5.4.5"}
- {"dt": "2024-12-11T12:28:13.659526", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.659721", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/1/tests/1_bootrr/run.sh", "from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38294_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:28:13.660021", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-12-11T12:28:13.660166", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/0 for stage 0"}
- {"dt": "2024-12-11T12:28:13.660399", "lvl": "debug", "msg": "- 0_dmesg"}
- {"dt": "2024-12-11T12:28:13.660603", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38294/lava-overlay-rttwe2hv/lava-38294/1 for stage 1"}
- {"dt": "2024-12-11T12:28:13.660826", "lvl": "debug", "msg": "- 1_bootrr"}
- {"dt": "2024-12-11T12:28:13.661067", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.661271", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.683597", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.683889", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.684115", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.684324", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.684525", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.684722", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.684919", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.685093", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.685266", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.685410", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2024-12-11T12:28:13.685601", "lvl": "debug", "msg": "NBD-IP: 192.168.111.1, NBD-PORT: 55631, NBD-ROOT: 38294/nbd-deploy-_igmuc2z/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-11T12:28:13.687844", "lvl": "debug", "msg": "nbd-server 55631 /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-11T12:28:13.702106", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-11T12:28:13.702478", "lvl": "debug", "msg": "output: ** (process:1866): WARNING **: 12:28:13.701: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2024-12-11T12:28:13.702713", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-11T12:28:13.702886", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2024-12-11T12:28:13.703178", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.703435", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:01:18) [common]"}
- {"dt": "2024-12-11T12:28:13.703722", "lvl": "info", "msg": "start: 2 minimal-boot (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.703996", "lvl": "debug", "msg": "start: 2.1 connect-device (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.704226", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2024-12-11T12:28:13.713643", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-12-11T12:28:13.714707", "lvl": "debug", "msg": "end: 2.1 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.715103", "lvl": "debug", "msg": "start: 2.2 reset-device (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.715434", "lvl": "debug", "msg": "start: 2.2.1 pdu-reboot (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.715904", "lvl": "debug", "msg": "Calling: 'hardresetpi5-01.sh'"}
- {"dt": "2024-12-11T12:28:13.735282", "lvl": "debug", "msg": "Returned 0 in 0 seconds"}
- {"dt": "2024-12-11T12:28:13.836179", "lvl": "debug", "msg": "end: 2.2.1 pdu-reboot (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.836448", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "0.12", "extra": {"status": "success"}, "level": "2.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:28:13.836824", "lvl": "debug", "msg": "end: 2.2 reset-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:28:13.837061", "lvl": "debug", "msg": "start: 2.3 auto-login-action (timeout 00:08:42) [common]"}
- {"dt": "2024-12-11T12:28:13.837263", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-12-11T12:28:13.837462", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2024-12-11T12:28:13.838055", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-12-11T12:28:13.838304", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-12-11T12:28:13.838476", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-12-11T12:36:55.837353", "lvl": "exception", "msg": "auto-login-action timed out after 522 seconds"}
- {"dt": "2024-12-11T12:36:55.837824", "lvl": "debug", "msg": "end: 2.3 auto-login-action (duration 00:08:42) [common]"}
- {"dt": "2024-12-11T12:36:55.838197", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "522.00", "extra": {"fail": "auto-login-action timed out after 522 seconds"}, "level": "2.3", "namespace": "common", "result": "fail"}}
- {"dt": "2024-12-11T12:36:55.838676", "lvl": "error", "msg": "minimal-boot failed: 1 of 1 attempts. 'auto-login-action timed out after 522 seconds'"}
- {"dt": "2024-12-11T12:36:55.839012", "lvl": "exception", "msg": "auto-login-action timed out after 522 seconds"}
- {"dt": "2024-12-11T12:36:55.839290", "lvl": "info", "msg": "end: 2 minimal-boot (duration 00:08:42) [common]"}
- {"dt": "2024-12-11T12:36:55.839560", "lvl": "results", "msg": {"case": "minimal-boot", "definition": "lava", "duration": "522.14", "extra": {"fail": "auto-login-action timed out after 522 seconds"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2024-12-11T12:36:55.840024", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-12-11T12:36:55.840282", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/initrd"}
- {"dt": "2024-12-11T12:36:55.886936", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/kernel"}
- {"dt": "2024-12-11T12:36:55.908204", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/dtb"}
- {"dt": "2024-12-11T12:36:55.929110", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38294/nbd-deploy-_igmuc2z/nbdroot"}
- {"dt": "2024-12-11T12:36:56.035427", "lvl": "debug", "msg": "start: 5.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-12-11T12:36:56.035850", "lvl": "debug", "msg": "Calling: 'curl' '-s' '-G' '-d' 'id=5' '-d' 'set=0' 'http://admin:admin@192.168.2.254/xml/jsonswitch.php'"}
- {"dt": "2024-12-11T12:36:56.086017", "lvl": "debug", "msg": ">> {\"result\":{\"id\":\"5\",\"error\":0,\"status\":0,\"set\":\"0\"}}"}
- {"dt": "2024-12-11T12:36:56.086713", "lvl": "debug", "msg": "Returned 0 in 0 seconds"}
- {"dt": "2024-12-11T12:36:56.187363", "lvl": "debug", "msg": "end: 5.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-12-11T12:36:56.187713", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "0.15", "extra": {"status": "success"}, "level": "5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-11T12:36:56.188228", "lvl": "debug", "msg": "start: 5.2 read-feedback (timeout 00:10:00) [common]"}
- {"dt": "2024-12-11T12:36:56.188908", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-12-11T12:36:57.189765", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-12-11T12:36:57.190194", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-12-11T12:36:57.190461", "lvl": "target", "msg": "\x14B\\\uFFFD\uFFFD\uFFFD \uFFFD\uFFFD\uFFFD\uFFFD\xF2\uFFFD\0\x06\uFFFD\x10L\b!\uFFFD!\uFFFD)Rv4\uFFFD\uFFFD\uFFFDj\uFFFD\uFFFD\uFFFD\uFFFD\uFFFD9\b\uFFFD\uFFFD"}
- {"dt": "2024-12-11T12:36:57.291016", "lvl": "debug", "msg": "end: 5.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-12-11T12:36:57.291398", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2024-12-11T12:36:57.291634", "lvl": "debug", "msg": "clean NBD port 55631"}
- {"dt": "2024-12-11T12:36:57.410925", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38294"}
- {"dt": "2024-12-11T12:36:57.414353", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38294"}
- {"dt": "2024-12-11T12:36:57.414821", "lvl": "error", "msg": "JobError: Your job cannot terminate cleanly."}
- {"dt": "2024-12-11T12:36:57.415061", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "auto-login-action timed out after 522 seconds", "error_type": "Job", "result": "fail"}}
