- {"dt": "2024-12-03T13:00:59.388337", "lvl": "warning", "msg": "Action timeout for connect-device exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.388923", "lvl": "warning", "msg": "Action timeout for pdu-reboot exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.389070", "lvl": "warning", "msg": "Action timeout for reset-device exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.389209", "lvl": "warning", "msg": "Action timeout for login-action exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.389319", "lvl": "warning", "msg": "Action timeout for auto-login-action exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.389452", "lvl": "warning", "msg": "Action timeout for expect-shell-connection exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.389559", "lvl": "warning", "msg": "Action timeout for overlay-unpack exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.389675", "lvl": "warning", "msg": "Action timeout for export-device-env exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.389802", "lvl": "warning", "msg": "Action timeout for minimal-boot exceeds Job timeout"}
- {"dt": "2024-12-03T13:00:59.403757", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-12-03T13:00:59.403902", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-12-03T13:00:59.404078", "lvl": "info", "msg": "Start time: 2024-12-03 13:00:59.404064+00:00 (UTC)"}
- {"dt": "2024-12-03T13:00:59.404293", "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-03T13:00:59.688863", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image exists"}
- {"dt": "2024-12-03T13:00:59.895485", "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-03T13:01:00.106939", "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-03T13:01:00.315687", "lvl": "info", "msg": "validate duration: 0.91"}
- {"dt": "2024-12-03T13:01:00.315829", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-12-03T13:01:00.316006", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 00:10:00) [common]"}
- {"dt": "2024-12-03T13:01:00.316095", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2024-12-03T13:01:00.316171", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2024-12-03T13:01:00.316246", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2024-12-03T13:01:00.316412", "lvl": "debug", "msg": "Set_port 61952"}
- {"dt": "2024-12-03T13:01:00.316520", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-12-03T13:01:00.316619", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 00:10:00) [common]"}
- {"dt": "2024-12-03T13:01:00.316859", "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-03T13:01:00.316960", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-03T13:01:00.317047", "lvl": "debug", "msg": "total size: 2153804 (2\u202FMB)"}
- {"dt": "2024-12-03T13:01:00.317129", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-12-03T13:01:00.619396", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:00.816856", "lvl": "debug", "msg": "progress   6\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:00.920118", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:00.938053", "lvl": "debug", "msg": "progress  18\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.023858", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.033863", "lvl": "debug", "msg": "progress  30\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.035188", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.122368", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.127336", "lvl": "debug", "msg": "progress  47\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.129672", "lvl": "debug", "msg": "progress  53\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.148040", "lvl": "debug", "msg": "progress  59\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.149587", "lvl": "debug", "msg": "progress  65\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.157569", "lvl": "debug", "msg": "progress  71\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.220949", "lvl": "debug", "msg": "progress  76\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.224096", "lvl": "debug", "msg": "progress  82\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.233631", "lvl": "debug", "msg": "progress  88\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.243534", "lvl": "debug", "msg": "progress  94\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.244337", "lvl": "debug", "msg": "progress 100\u202F% (2\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.278942", "lvl": "info", "msg": "2\u202FMB downloaded in 0.96\u202Fs (2.14\u202FMB/s)"}
- {"dt": "2024-12-03T13:01:01.279278", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:01:01.279382", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.96", "extra": {"label": "initrd", "md5sum": "69fcb22a915d8fa6208b51a2d38a1bd0", "sha256sum": "59fb98aa51ec6733051adf76648cfc363ccc8367467a4fb8794cfaf7c3b33988", "sha512sum": "52fefc4f3925b72ed297cf2bb536aefa2358e2d66fefd61cede40b155efd2e9b101119081caf7f09fd61d798027cdc78cb63718fd91f573a718dfcb5894851c7", "size": ! "2153804"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:01:01.279601", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:01:01.279718", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:09:59) [common]"}
- {"dt": "2024-12-03T13:01:01.279828", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 00:09:59) [common]"}
- {"dt": "2024-12-03T13:01:01.280086", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image"}
- {"dt": "2024-12-03T13:01:01.280159", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/kernel/Image"}
- {"dt": "2024-12-03T13:01:01.280235", "lvl": "debug", "msg": "total size: 28887552 (27\u202FMB)"}
- {"dt": "2024-12-03T13:01:01.280313", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-03T13:01:01.581630", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:02.297378", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-12-03T13:01:02.793318", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2024-12-03T13:01:03.301323", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-12-03T13:01:03.790841", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-12-03T13:01:04.289273", "lvl": "debug", "msg": "progress  25\u202F% (6\u202FMB)"}
- {"dt": "2024-12-03T13:01:04.755640", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-12-03T13:01:05.210411", "lvl": "debug", "msg": "progress  35\u202F% (9\u202FMB)"}
- {"dt": "2024-12-03T13:01:05.701678", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-12-03T13:01:06.140301", "lvl": "debug", "msg": "progress  45\u202F% (12\u202FMB)"}
- {"dt": "2024-12-03T13:01:06.539356", "lvl": "debug", "msg": "progress  50\u202F% (13\u202FMB)"}
- {"dt": "2024-12-03T13:01:07.008199", "lvl": "debug", "msg": "progress  55\u202F% (15\u202FMB)"}
- {"dt": "2024-12-03T13:01:07.401140", "lvl": "debug", "msg": "progress  60\u202F% (16\u202FMB)"}
- {"dt": "2024-12-03T13:01:07.758040", "lvl": "debug", "msg": "progress  65\u202F% (17\u202FMB)"}
- {"dt": "2024-12-03T13:01:08.113673", "lvl": "debug", "msg": "progress  70\u202F% (19\u202FMB)"}
- {"dt": "2024-12-03T13:01:10.458513", "lvl": "debug", "msg": "progress  75\u202F% (20\u202FMB)"}
- {"dt": "2024-12-03T13:01:10.756983", "lvl": "debug", "msg": "progress  80\u202F% (22\u202FMB)"}
- {"dt": "2024-12-03T13:01:11.056711", "lvl": "debug", "msg": "progress  85\u202F% (23\u202FMB)"}
- {"dt": "2024-12-03T13:01:11.288177", "lvl": "debug", "msg": "progress  90\u202F% (24\u202FMB)"}
- {"dt": "2024-12-03T13:01:11.566192", "lvl": "debug", "msg": "progress  95\u202F% (26\u202FMB)"}
- {"dt": "2024-12-03T13:01:11.789847", "lvl": "debug", "msg": "progress 100\u202F% (27\u202FMB)"}
- {"dt": "2024-12-03T13:01:11.790854", "lvl": "info", "msg": "27\u202FMB downloaded in 10.51\u202Fs (2.62\u202FMB/s)"}
- {"dt": "2024-12-03T13:01:11.791586", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:11) [common]"}
- {"dt": "2024-12-03T13:01:11.791929", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "10.51", "extra": {"label": "kernel", "md5sum": "601b383c3c881d04e3368bd84040fdbd", "sha256sum": "99041f31b2ec83f4057caf62ca4bf1534d860cf2d8612fde079894451d55e486", "sha512sum": "e956efcf89178c3aa3fe2491edd04292d25136cf49bc45f7fdfcaa45b40768824dafc595be0d4dbccf066dc2d8dcfce3c6b446189612fa22ab6f3c465cab66c3", "size": ! "28887552"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:01:11.792653", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:11) [common]"}
- {"dt": "2024-12-03T13:01:11.793107", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:09:49) [common]"}
- {"dt": "2024-12-03T13:01:11.793484", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:09:49) [common]"}
- {"dt": "2024-12-03T13:01:11.794198", "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-03T13:01:11.794512", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/dtb/bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-03T13:01:11.794780", "lvl": "debug", "msg": "total size: 78059 (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:11.795053", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-03T13:01:12.106809", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:12.205503", "lvl": "debug", "msg": "progress  83\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:12.206975", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:12.207726", "lvl": "info", "msg": "0\u202FMB downloaded in 0.41\u202Fs (0.18\u202FMB/s)"}
- {"dt": "2024-12-03T13:01:12.208369", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:01:12.208693", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.41", "extra": {"label": "dtb", "md5sum": "e8c29afbbe377e6f0331d46da1c32f91", "sha256sum": "a91e4fa0dcc0ab08b873cfac3c0b7fc17c22ee715d941cbc915a44966c1e8c99", "sha512sum": "6e77dc7abf1cdc1c07a6f9e5f0168f52a9704b6d0142112e5b28cef3e166f8b93612a76b6341e5168e6c1e3d7ab7b4ed115d87749a7379c2d5174a78e19b46c3", "size": ! "78059"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:01:12.209444", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:01:12.209807", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:09:48) [common]"}
- {"dt": "2024-12-03T13:01:12.210172", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:09:48) [common]"}
- {"dt": "2024-12-03T13:01:12.210435", "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-03T13:01:12.210507", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-03T13:01:12.210569", "lvl": "debug", "msg": "total size: 493866112 (470\u202FMB)"}
- {"dt": "2024-12-03T13:01:12.210633", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-12-03T13:01:12.513271", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-03T13:01:19.620128", "lvl": "debug", "msg": "progress   5\u202F% (23\u202FMB)"}
- {"dt": "2024-12-03T13:01:23.255342", "lvl": "debug", "msg": "progress  10\u202F% (47\u202FMB)"}
- {"dt": "2024-12-03T13:01:25.390519", "lvl": "debug", "msg": "progress  15\u202F% (70\u202FMB)"}
- {"dt": "2024-12-03T13:01:28.752957", "lvl": "debug", "msg": "progress  20\u202F% (94\u202FMB)"}
- {"dt": "2024-12-03T13:01:30.658624", "lvl": "debug", "msg": "progress  25\u202F% (117\u202FMB)"}
- {"dt": "2024-12-03T13:01:32.511323", "lvl": "debug", "msg": "progress  30\u202F% (141\u202FMB)"}
- {"dt": "2024-12-03T13:01:34.290402", "lvl": "debug", "msg": "progress  35\u202F% (164\u202FMB)"}
- {"dt": "2024-12-03T13:01:36.208775", "lvl": "debug", "msg": "progress  40\u202F% (188\u202FMB)"}
- {"dt": "2024-12-03T13:01:37.792035", "lvl": "debug", "msg": "progress  45\u202F% (211\u202FMB)"}
- {"dt": "2024-12-03T13:01:39.458046", "lvl": "debug", "msg": "progress  50\u202F% (235\u202FMB)"}
- {"dt": "2024-12-03T13:01:43.396749", "lvl": "debug", "msg": "progress  55\u202F% (259\u202FMB)"}
- {"dt": "2024-12-03T13:01:45.196154", "lvl": "debug", "msg": "progress  60\u202F% (282\u202FMB)"}
- {"dt": "2024-12-03T13:01:47.702978", "lvl": "debug", "msg": "progress  65\u202F% (306\u202FMB)"}
- {"dt": "2024-12-03T13:01:49.454170", "lvl": "debug", "msg": "progress  70\u202F% (329\u202FMB)"}
- {"dt": "2024-12-03T13:01:51.633548", "lvl": "debug", "msg": "progress  75\u202F% (353\u202FMB)"}
- {"dt": "2024-12-03T13:01:53.480007", "lvl": "debug", "msg": "progress  80\u202F% (376\u202FMB)"}
- {"dt": "2024-12-03T13:01:55.492012", "lvl": "debug", "msg": "progress  85\u202F% (400\u202FMB)"}
- {"dt": "2024-12-03T13:01:57.264048", "lvl": "debug", "msg": "progress  90\u202F% (423\u202FMB)"}
- {"dt": "2024-12-03T13:01:59.030229", "lvl": "debug", "msg": "progress  95\u202F% (447\u202FMB)"}
- {"dt": "2024-12-03T13:02:03.017336", "lvl": "debug", "msg": "progress 100\u202F% (470\u202FMB)"}
- {"dt": "2024-12-03T13:02:04.440230", "lvl": "info", "msg": "470\u202FMB downloaded in 52.23\u202Fs (9.02\u202FMB/s)"}
- {"dt": "2024-12-03T13:02:04.440595", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:52) [common]"}
- {"dt": "2024-12-03T13:02:04.440726", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "52.23", "extra": {"label": "nbdroot", "md5sum": "4efb22dd71d11d782c04913029ebe146", "sha256sum": "b475a2953ee1a6be85506449e60e93d14579f8af2dc5577723d004a952eda229", "sha512sum": "888ffdcec6b22a5b3cd3e391eaa7a1bf5c6493efe72df9f895ac361c0117ba23335502223b5b769ad9e63ef740001581525ce5eaeaf2ac29e1b76eb53d8395b8", "size": ! "493866112"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:02:04.440942", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:52) [common]"}
- {"dt": "2024-12-03T13:02:04.441035", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.441310", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k"}
- {"dt": "2024-12-03T13:02:04.441490", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin"}
- {"dt": "2024-12-03T13:02:04.441608", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/tests"}
- {"dt": "2024-12-03T13:02:04.441717", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/results"}
- {"dt": "2024-12-03T13:02:04.441828", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-add-keys"}
- {"dt": "2024-12-03T13:02:04.442006", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-add-sources"}
- {"dt": "2024-12-03T13:02:04.442168", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-background-process-start"}
- {"dt": "2024-12-03T13:02:04.442325", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-background-process-stop"}
- {"dt": "2024-12-03T13:02:04.442496", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-common-functions"}
- {"dt": "2024-12-03T13:02:04.442654", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-echo-ipv4"}
- {"dt": "2024-12-03T13:02:04.442805", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-install-packages"}
- {"dt": "2024-12-03T13:02:04.442953", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-installed-packages"}
- {"dt": "2024-12-03T13:02:04.443098", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-os-build"}
- {"dt": "2024-12-03T13:02:04.443244", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-probe-channel"}
- {"dt": "2024-12-03T13:02:04.443391", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-probe-ip"}
- {"dt": "2024-12-03T13:02:04.443535", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-target-ip"}
- {"dt": "2024-12-03T13:02:04.443680", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-target-mac"}
- {"dt": "2024-12-03T13:02:04.443831", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-target-storage"}
- {"dt": "2024-12-03T13:02:04.443987", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-test-case"}
- {"dt": "2024-12-03T13:02:04.444134", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-test-event"}
- {"dt": "2024-12-03T13:02:04.444278", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-test-feedback"}
- {"dt": "2024-12-03T13:02:04.444425", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-test-raise"}
- {"dt": "2024-12-03T13:02:04.444581", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-test-reference"}
- {"dt": "2024-12-03T13:02:04.444725", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-test-runner"}
- {"dt": "2024-12-03T13:02:04.444900", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-test-set"}
- {"dt": "2024-12-03T13:02:04.445043", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-test-shell"}
- {"dt": "2024-12-03T13:02:04.445191", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-install-packages (oe)"}
- {"dt": "2024-12-03T13:02:04.445373", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/bin/lava-installed-packages (oe)"}
- {"dt": "2024-12-03T13:02:04.445550", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/environment"}
- {"dt": "2024-12-03T13:02:04.445704", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-12-03T13:02:04.445781", "lvl": "debug", "msg": "- LAVA_JOB_ID=38078"}
- {"dt": "2024-12-03T13:02:04.445846", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2024-12-03T13:02:04.445966", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.446296", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.446397", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.446469", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-12-03T13:02:04.446546", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.446625", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.446685", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-12-03T13:02:04.446758", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.446835", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.446907", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-12-03T13:02:04.446995", "lvl": "debug", "msg": "start: 1.5.4.1 inline-repo-action (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.447069", "lvl": "debug", "msg": "Using /lava-38078 at stage 0"}
- {"dt": "2024-12-03T13:02:04.447502", "lvl": "debug", "msg": "uuid=38078_1.5.4.1 testdef=None"}
- {"dt": "2024-12-03T13:02:04.447599", "lvl": "debug", "msg": "end: 1.5.4.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.447683", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.448315", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.448399", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38078_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:02:04.448559", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.449450", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.449534", "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": "38078_1.5.4.1", "uuid": "38078_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:02:04.449703", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.450514", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/0/tests/0_dmesg test_uuid 38078_1.5.4.1"}
- {"dt": "2024-12-03T13:02:04.450714", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.450796", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/0/tests/0_dmesg/run.sh", "from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38078_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:02:04.450960", "lvl": "debug", "msg": "start: 1.5.4.5 inline-repo-action (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.451031", "lvl": "debug", "msg": "Using /lava-38078 at stage 1"}
- {"dt": "2024-12-03T13:02:04.451407", "lvl": "debug", "msg": "uuid=38078_1.5.4.5 testdef=None"}
- {"dt": "2024-12-03T13:02:04.451500", "lvl": "debug", "msg": "end: 1.5.4.5 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.451582", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.452129", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.452203", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38078_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:02:04.452358", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.453213", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.453293", "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": "38078_1.5.4.5", "uuid": "38078_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:02:04.453454", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.454217", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/1/tests/1_bootrr test_uuid 38078_1.5.4.5"}
- {"dt": "2024-12-03T13:02:04.454406", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.454485", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/1/tests/1_bootrr/run.sh", "from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38078_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:02:04.454627", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-12-03T13:02:04.454688", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/0 for stage 0"}
- {"dt": "2024-12-03T13:02:04.454791", "lvl": "debug", "msg": "- 0_dmesg"}
- {"dt": "2024-12-03T13:02:04.454881", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38078/lava-overlay-wxg3gj8k/lava-38078/1 for stage 1"}
- {"dt": "2024-12-03T13:02:04.454983", "lvl": "debug", "msg": "- 1_bootrr"}
- {"dt": "2024-12-03T13:02:04.455088", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.455172", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.464589", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.464727", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.464857", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.464955", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.465049", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.465142", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.465242", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.465328", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.465416", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.465487", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2024-12-03T13:02:04.465558", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61952, NBD-ROOT: 38078/nbd-deploy-xy3o15y2/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-03T13:02:04.466005", "lvl": "debug", "msg": "nbd-server 61952 /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-03T13:02:04.468616", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-03T13:02:04.468836", "lvl": "debug", "msg": "output: ** (process:5284): WARNING **: 13:02:04.468: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2024-12-03T13:02:04.468945", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-03T13:02:04.469030", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2024-12-03T13:02:04.469152", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.469262", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:01:04) [common]"}
- {"dt": "2024-12-03T13:02:04.469370", "lvl": "info", "msg": "start: 2 minimal-boot (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.469476", "lvl": "debug", "msg": "start: 2.1 connect-device (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.469558", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63007'"}
- {"dt": "2024-12-03T13:02:04.473091", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-12-03T13:02:04.473527", "lvl": "debug", "msg": "end: 2.1 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:02:04.473691", "lvl": "debug", "msg": "start: 2.2 reset-device (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.473822", "lvl": "debug", "msg": "start: 2.2.1 pdu-reboot (timeout 00:08:56) [common]"}
- {"dt": "2024-12-03T13:02:04.474037", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'reset' '2'"}
- {"dt": "2024-12-03T13:02:05.487143", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-03T13:02:05.487712", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-12-03T13:02:09.527333", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-03T13:02:09.527974", "lvl": "debug", "msg": ">> Switched outlet 2 on\r"}
- {"dt": "2024-12-03T13:02:11.530818", "lvl": "debug", "msg": ">> export LANG='C'\r"}
- {"dt": "2024-12-03T13:02:11.531442", "lvl": "debug", "msg": ">> export LC_ALL='C.UTF-8'\r"}
- {"dt": "2024-12-03T13:02:11.531816", "lvl": "debug", "msg": ">> export PATH='/usr/local/bin:/usr/local/sbin:/bin:/usr/bin:/usr/sbin:/sbin'\r"}
- {"dt": "2024-12-03T13:02:11.532130", "lvl": "debug", "msg": ">> export PWD='/'\r"}
- {"dt": "2024-12-03T13:02:11.536351", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-12-03T13:02:11.537726", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp\r"}
- {"dt": "2024-12-03T13:02:11.543648", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-03T13:02:11.543796", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-03T13:02:11.543905", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-12-03T13:02:11.547331", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-12-03T13:02:11.554270", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/kernel/Image\r"}
- {"dt": "2024-12-03T13:02:11.559301", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=38078/nbd-deploy-xy3o15y2/kernel/Image\r"}
- {"dt": "2024-12-03T13:02:11.562735", "lvl": "debug", "msg": ">> cp: '/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4' and '/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4' are the same file\r"}
- {"dt": "2024-12-03T13:02:11.600675", "lvl": "debug", "msg": ">> cp: '/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz' and '/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz' are the same file\r"}
- {"dt": "2024-12-03T13:02:11.627944", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61952      0.0.0.0:*    users:((\"nbd-server\",pid=5285,fd=3))\r"}
- {"dt": "2024-12-03T13:02:11.629839", "lvl": "debug", "msg": ">> DEBUG: PID=5285\r"}
- {"dt": "2024-12-03T13:02:11.636982", "lvl": "debug", "msg": ">> NBDPORT=61952\r"}
- {"dt": "2024-12-03T13:02:11.638539", "lvl": "debug", "msg": ">> basename: extra operand \u2018/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\u2019\r"}
- {"dt": "2024-12-03T13:02:11.638687", "lvl": "debug", "msg": ">> Try 'basename --help' for more information.\r"}
- {"dt": "2024-12-03T13:02:11.639481", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-12-03T13:02:11.639626", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-12-03T13:02:11.639731", "lvl": "debug", "msg": ">> \t\tkernel 38078/nbd-deploy-xy3o15y2/kernel/Image\r"}
- {"dt": "2024-12-03T13:02:11.639816", "lvl": "debug", "msg": ">> \t\tinitramfs \r"}
- {"dt": "2024-12-03T13:02:11.639893", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-12-03T13:02:11.640675", "lvl": "debug", "msg": ">> cp: '/var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb' and '/var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb' are the same file\r"}
- {"dt": "2024-12-03T13:02:11.640971", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/dtb/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-12-03T13:02:11.642547", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2\r"}
- {"dt": "2024-12-03T13:02:11.644028", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-03T13:02:11.645834", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=38078/nbd-deploy-xy3o15y2/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-03T13:02:11.647356", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/kernel/Image\r"}
- {"dt": "2024-12-03T13:02:11.649303", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=38078/nbd-deploy-xy3o15y2/kernel/Image\r"}
- {"dt": "2024-12-03T13:02:11.702953", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61952      0.0.0.0:*    users:((\"nbd-server\",pid=5285,fd=3))\r"}
- {"dt": "2024-12-03T13:02:11.705161", "lvl": "debug", "msg": ">> DEBUG: PID=5285\r"}
- {"dt": "2024-12-03T13:02:11.713257", "lvl": "debug", "msg": ">> NBDPORT=61952\r"}
- {"dt": "2024-12-03T13:02:11.716407", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-12-03T13:02:11.716647", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-12-03T13:02:11.716953", "lvl": "debug", "msg": ">> \t\tkernel 38078/nbd-deploy-xy3o15y2/kernel/Image\r"}
- {"dt": "2024-12-03T13:02:11.717158", "lvl": "debug", "msg": ">> \t\tinitramfs initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-03T13:02:11.717398", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-12-03T13:02:11.719794", "lvl": "debug", "msg": "Returned 0 in 7 seconds"}
- {"dt": "2024-12-03T13:02:11.820820", "lvl": "debug", "msg": "end: 2.2.1 pdu-reboot (duration 00:00:07) [common]"}
- {"dt": "2024-12-03T13:02:11.821399", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "7.35", "extra": {"status": "success"}, "level": "2.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:02:11.822081", "lvl": "debug", "msg": "end: 2.2 reset-device (duration 00:00:07) [common]"}
- {"dt": "2024-12-03T13:02:11.822491", "lvl": "debug", "msg": "start: 2.3 auto-login-action (timeout 00:08:48) [common]"}
- {"dt": "2024-12-03T13:02:11.822845", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-12-03T13:02:11.823167", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2024-12-03T13:02:11.824191", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-12-03T13:02:11.824503", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-12-03T13:02:11.824792", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-12-03T13:02:11.825054", "lvl": "target", "msg": "\0"}
- {"dt": "2024-12-03T13:02:11.825302", "lvl": "target", "msg": "  1.27 RPi: BOOTSYS release VERSION:0b72471b DATE: 2024/11/27 TIME: 14:28:19"}
- {"dt": "2024-12-03T13:02:11.825526", "lvl": "target", "msg": "  1.31 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1732717699 serial a18d1bdb boardrev d04170 stc 1631478"}
- {"dt": "2024-12-03T13:02:11.825584", "lvl": "target", "msg": "  1.41 AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-12-03T13:02:11.825642", "lvl": "target", "msg": "  1.49 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-03T13:02:11.825699", "lvl": "target", "msg": "  1.51 PM_RSTS: 0x00001000"}
- {"dt": "2024-12-03T13:02:11.825755", "lvl": "target", "msg": "  1.51 part 00000000 reset_info 00000000"}
- {"dt": "2024-12-03T13:02:11.825811", "lvl": "target", "msg": "  1.55 PMIC reset-event 00000000 rtc 00000001 alarm 00000000 enabled 0"}
- {"dt": "2024-12-03T13:02:11.825867", "lvl": "target", "msg": "  1.61 uSD voltage 3.3V"}
- {"dt": "2024-12-03T13:02:11.825923", "lvl": "target", "msg": "  1.81 Initialising SDRAM rank 2 total-size: 64 Gbit 4267 (0x14 0x00)"}
- {"dt": "2024-12-03T13:02:11.825980", "lvl": "target", "msg": "  1.84 DDR 4267 1 0 64 152 BL:-1"}
- {"dt": "2024-12-03T13:02:13.585907", "lvl": "target", "msg": "  3.56 OTP boardrev d04170 bootrom a a"}
- {"dt": "2024-12-03T13:02:13.589397", "lvl": "target", "msg": "  3.58 Customer key hash 0000000000000000000000000000000000000000000000000000000000000000"}
- {"dt": "2024-12-03T13:02:13.589961", "lvl": "target", "msg": "  3.65 VC-JTAG unlocked"}
- {"dt": "2024-12-03T13:02:13.605078", "lvl": "target", "msg": "  3.88 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-03T13:02:14.147941", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:02:14.227840", "lvl": "target", "msg": "  4.16 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-03T13:02:14.243743", "lvl": "target", "msg": "  4.16 RP1_BOOT: fw size 46888"}
- {"dt": "2024-12-03T13:02:14.866369", "lvl": "target", "msg": "  4.54 PCI2 init"}
- {"dt": "2024-12-03T13:02:14.866900", "lvl": "target", "msg": "  4.54 PCI2 reset"}
- {"dt": "2024-12-03T13:02:14.945038", "lvl": "target", "msg": "  4.99 PCIe scan 00001de4:00000001"}
- {"dt": "2024-12-03T13:02:14.945530", "lvl": "target", "msg": "  4.99 RP1_CHIP_INFO 20001927"}
- {"dt": "2024-12-03T13:02:14.945823", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:02:14.946089", "lvl": "target", "msg": "  5.02 RPi: BOOTLOADER release VERSION:0b72471b DATE: 2024/11/27 TIME: 14:28:19"}
- {"dt": "2024-12-03T13:02:14.948590", "lvl": "target", "msg": "  5.09 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1732717699 serial a18d1bdb boardrev d04170 stc 5009409"}
- {"dt": "2024-12-03T13:02:14.949152", "lvl": "target", "msg": "  5.19 AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-12-03T13:02:15.002689", "lvl": "target", "msg": "  5.47 usb_pd_init status 3"}
- {"dt": "2024-12-03T13:02:15.003218", "lvl": "target", "msg": "  5.47 USB_PD CONFIG 0 41"}
- {"dt": "2024-12-03T13:02:15.003513", "lvl": "target", "msg": "  5.53 XHCI-STOP"}
- {"dt": "2024-12-03T13:02:15.003774", "lvl": "target", "msg": "  5.53 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:15.004027", "lvl": "target", "msg": "  5.58 USBSTS 1"}
- {"dt": "2024-12-03T13:02:15.004269", "lvl": "target", "msg": "  5.60 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:15.004513", "lvl": "target", "msg": "  5.65 xHC0 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-03T13:02:15.004792", "lvl": "target", "msg": "  5.77 XHCI-STOP"}
- {"dt": "2024-12-03T13:02:15.005331", "lvl": "target", "msg": "  5.77 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:15.005600", "lvl": "target", "msg": "  5.82 USBSTS 1"}
- {"dt": "2024-12-03T13:02:15.005848", "lvl": "target", "msg": "  5.84 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:15.006152", "lvl": "target", "msg": "  5.89 xHC1 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-03T13:02:15.021065", "lvl": "target", "msg": "  5.97 Boot mode: SD (01) order 24"}
- {"dt": "2024-12-03T13:02:15.548647", "lvl": "target", "msg": "  5.97 USB-PD: src-cap PDO object1 0x0a11912c"}
- {"dt": "2024-12-03T13:02:15.549226", "lvl": "target", "msg": "  5.99 Current 3000 mA"}
- {"dt": "2024-12-03T13:02:15.549530", "lvl": "target", "msg": "  5.01 Voltage 5000 mV"}
- {"dt": "2024-12-03T13:02:15.549800", "lvl": "target", "msg": "  5.03 USB-PD: src-cap PDO object2 0x0012d12c"}
- {"dt": "2024-12-03T13:02:15.550054", "lvl": "target", "msg": "  5.07 Current 3000 mA"}
- {"dt": "2024-12-03T13:02:15.550295", "lvl": "target", "msg": "  5.09 Voltage 9000 mV"}
- {"dt": "2024-12-03T13:02:15.550535", "lvl": "target", "msg": "  5.11 USB-PD: src-cap PDO object3 0x0014b12c"}
- {"dt": "2024-12-03T13:02:15.550782", "lvl": "target", "msg": "  5.15 Current 3000 mA"}
- {"dt": "2024-12-03T13:02:15.551022", "lvl": "target", "msg": "  5.17 Voltage 15000 mV"}
- {"dt": "2024-12-03T13:02:15.551259", "lvl": "target", "msg": "  5.19 USB-PD: src-cap PDO object4 0x00164145"}
- {"dt": "2024-12-03T13:02:15.551495", "lvl": "target", "msg": "  5.24 Current 3250 mA"}
- {"dt": "2024-12-03T13:02:15.551739", "lvl": "target", "msg": "  5.26 Voltage 20000 mV"}
- {"dt": "2024-12-03T13:02:15.580588", "lvl": "target", "msg": "  5.42 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-03T13:02:15.583789", "lvl": "target", "msg": "  5.53 SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-03T13:02:15.679445", "lvl": "target", "msg": "  5.62 EMMC"}
- {"dt": "2024-12-03T13:02:15.775433", "lvl": "target", "msg": "  5.62 SD retry 1 oc 0"}
- {"dt": "2024-12-03T13:02:15.814531", "lvl": "target", "msg": "  5.86 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-03T13:02:15.909992", "lvl": "target", "msg": "  5.95 SD retry 2 oc 0"}
- {"dt": "2024-12-03T13:02:15.947182", "lvl": "target", "msg": "  6.20 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-03T13:02:16.042825", "lvl": "target", "msg": "  6.29 SDV1"}
- {"dt": "2024-12-03T13:02:16.156152", "lvl": "target", "msg": "  6.29 SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-12-03T13:02:16.159258", "lvl": "target", "msg": "  6.30 Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-12-03T13:02:16.159676", "lvl": "target", "msg": "  6.36 Retry SD 1"}
- {"dt": "2024-12-03T13:02:16.204354", "lvl": "target", "msg": "  6.66 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-03T13:02:16.207484", "lvl": "target", "msg": "  6.77 SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-03T13:02:16.303311", "lvl": "target", "msg": "  6.86 EMMC"}
- {"dt": "2024-12-03T13:02:16.399231", "lvl": "target", "msg": "  6.86 SD retry 1 oc 0"}
- {"dt": "2024-12-03T13:02:16.438298", "lvl": "target", "msg": "  6.10 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-03T13:02:16.533867", "lvl": "target", "msg": "  6.20 SD retry 2 oc 0"}
- {"dt": "2024-12-03T13:02:16.572107", "lvl": "target", "msg": "  6.44 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-03T13:02:16.667771", "lvl": "target", "msg": "  6.53 SDV1"}
- {"dt": "2024-12-03T13:02:16.781279", "lvl": "target", "msg": "  6.53 SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-12-03T13:02:16.784679", "lvl": "target", "msg": "  6.54 Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-12-03T13:02:16.785245", "lvl": "target", "msg": "  6.60 Boot mode: USB-MSD (04) order 2"}
- {"dt": "2024-12-03T13:02:16.834159", "lvl": "target", "msg": "  6.78 XHCI-STOP"}
- {"dt": "2024-12-03T13:02:16.834696", "lvl": "target", "msg": "  6.78 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:16.835001", "lvl": "target", "msg": "  6.83 USBSTS 0"}
- {"dt": "2024-12-03T13:02:16.835268", "lvl": "target", "msg": "  6.86 XHCI-STOP"}
- {"dt": "2024-12-03T13:02:16.835521", "lvl": "target", "msg": "  6.86 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:16.835770", "lvl": "target", "msg": "  6.92 USBSTS 0"}
- {"dt": "2024-12-03T13:02:16.836009", "lvl": "target", "msg": "  6.99 XHCI-STOP"}
- {"dt": "2024-12-03T13:02:16.836244", "lvl": "target", "msg": "  6.99 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:16.836486", "lvl": "target", "msg": "  6.04 USBSTS 1"}
- {"dt": "2024-12-03T13:02:16.836719", "lvl": "target", "msg": "  6.06 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:16.837007", "lvl": "target", "msg": "  6.11 xHC0 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-03T13:02:16.837260", "lvl": "target", "msg": "  6.23 XHCI-STOP"}
- {"dt": "2024-12-03T13:02:16.860097", "lvl": "target", "msg": "  6.23 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:16.860631", "lvl": "target", "msg": "  6.28 USBSTS 1"}
- {"dt": "2024-12-03T13:02:16.860969", "lvl": "target", "msg": "  6.30 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-03T13:02:16.861247", "lvl": "target", "msg": "  6.35 xHC1 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-03T13:02:41.869622", "lvl": "target", "msg": " 31.43 USB MSD stopped. Timeout: 25 seconds"}
- {"dt": "2024-12-03T13:02:41.870160", "lvl": "target", "msg": " 31.44 Boot mode: NETWORK (02) order 0"}
- {"dt": "2024-12-03T13:02:41.958603", "lvl": "target", "msg": " 32.31 NETWORK: d8:3a:dd:e5:72:37 wait for link TFTP: 192.168.66.1"}
- {"dt": "2024-12-03T13:02:44.979560", "lvl": "target", "msg": " 35.55 LINK STATUS: speed: 1000 full duplex"}
- {"dt": "2024-12-03T13:02:44.980104", "lvl": "target", "msg": " 35.56 Link ready"}
- {"dt": "2024-12-03T13:02:45.026636", "lvl": "target", "msg": " 35.99 RX: 0 IP: 0 IPV4: 0 MAC: 0 UDP: 0 UDP RECV: 0 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.050274", "lvl": "target", "msg": " 43.00 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-03T13:02:53.050819", "lvl": "target", "msg": " 43.02 YI_ADDR 192.168.66.231"}
- {"dt": "2024-12-03T13:02:53.051128", "lvl": "target", "msg": " 43.04 SI_ADDR 192.168.66.1"}
- {"dt": "2024-12-03T13:02:53.051396", "lvl": "target", "msg": " 43.07 OPTIONS:-"}
- {"dt": "2024-12-03T13:02:53.051646", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv OFFER (2) expect OFFER 43.13 "}
- {"dt": "2024-12-03T13:02:53.051897", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1 43.16 "}
- {"dt": "2024-12-03T13:02:53.052142", "lvl": "target", "msg": "\top: 51 len:   4  43.18 "}
- {"dt": "2024-12-03T13:02:53.052385", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0 43.22 "}
- {"dt": "2024-12-03T13:02:53.052624", "lvl": "target", "msg": "\top:  3 len:   4 192.168.66.1 43.25 "}
- {"dt": "2024-12-03T13:02:53.052905", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-03T13:02:53.053468", "lvl": "target", "msg": " 43.27 "}
- {"dt": "2024-12-03T13:02:53.053760", "lvl": "target", "msg": " 43.28 NET 192.168.66.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.66.1"}
- {"dt": "2024-12-03T13:02:53.054007", "lvl": "target", "msg": " 43.34 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-03T13:02:53.054327", "lvl": "target", "msg": " 43.39 YI_ADDR 192.168.66.231"}
- {"dt": "2024-12-03T13:02:53.093498", "lvl": "target", "msg": " 43.41 SI_ADDR 192.168.66.1"}
- {"dt": "2024-12-03T13:02:53.094056", "lvl": "target", "msg": " 43.44 OPTIONS:-"}
- {"dt": "2024-12-03T13:02:53.094364", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv ACK (5) expect ACK 43.49 "}
- {"dt": "2024-12-03T13:02:53.094638", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1 43.53 "}
- {"dt": "2024-12-03T13:02:53.094890", "lvl": "target", "msg": "\top: 51 len:   4  43.55 "}
- {"dt": "2024-12-03T13:02:53.095137", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0 43.58 "}
- {"dt": "2024-12-03T13:02:53.095380", "lvl": "target", "msg": "\top:  3 len:   4 192.168.66.1 43.62 "}
- {"dt": "2024-12-03T13:02:53.095623", "lvl": "target", "msg": "\top:  6 len:   4 10.1.1.1 43.65 "}
- {"dt": "2024-12-03T13:02:53.096166", "lvl": "target", "msg": "\top: 150 len:   4  43.67 "}
- {"dt": "2024-12-03T13:02:53.096441", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-03T13:02:53.096701", "lvl": "target", "msg": " 43.69 "}
- {"dt": "2024-12-03T13:02:53.096984", "lvl": "target", "msg": "\top: 15 len:  11  43.72 "}
- {"dt": "2024-12-03T13:02:53.097224", "lvl": "target", "msg": " 43.73 ARP 192.168.66.1 70:70:fc:05:68:c7"}
- {"dt": "2024-12-03T13:02:53.097462", "lvl": "target", "msg": " 43.76 NET 192.168.66.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.66.1"}
- {"dt": "2024-12-03T13:02:53.136627", "lvl": "target", "msg": " 43.82 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.137284", "lvl": "target", "msg": " 43.90 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 a18d1bdb/config.txt"}
- {"dt": "2024-12-03T13:02:53.137613", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:02:53.137887", "lvl": "target", "msg": " 43.96 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.138146", "lvl": "target", "msg": " 43.06 TFTP 1: File not found "}
- {"dt": "2024-12-03T13:02:53.138391", "lvl": "target", "msg": " 43.06 RX: 4 IP: 0 IPV4: 3 MAC: 3 UDP: 3 UDP RECV: 3 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.138634", "lvl": "target", "msg": " 43.14 BMD \\\"a18d1bdb/config.txt\\\" not found"}
- {"dt": "2024-12-03T13:02:53.138883", "lvl": "target", "msg": " 43.19 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 config.txt"}
- {"dt": "2024-12-03T13:02:53.139124", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:02:53.139920", "lvl": "target", "msg": " 43.23 RX: 4 IP: 0 IPV4: 3 MAC: 3 UDP: 3 UDP RECV: 3 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.174484", "lvl": "target", "msg": " 43.33 TFTP: complete 137"}
- {"dt": "2024-12-03T13:02:53.175035", "lvl": "target", "msg": " 43.33 RX: 6 IP: 0 IPV4: 5 MAC: 5 UDP: 5 UDP RECV: 5 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.175345", "lvl": "target", "msg": " 43.40 Read config.txt bytes      137 hnd 0x0"}
- {"dt": "2024-12-03T13:02:53.175618", "lvl": "target", "msg": " 43.44 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 pieeprom.sig"}
- {"dt": "2024-12-03T13:02:53.175871", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:02:53.177655", "lvl": "target", "msg": " 43.50 RX: 6 IP: 0 IPV4: 5 MAC: 5 UDP: 5 UDP RECV: 5 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.209658", "lvl": "target", "msg": " 43.99 TFTP 1: File not found "}
- {"dt": "2024-12-03T13:02:53.249403", "lvl": "target", "msg": " 43.99 RX: 7 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.250041", "lvl": "target", "msg": " 43.07 Read pieeprom.upd failed"}
- {"dt": "2024-12-03T13:02:53.250361", "lvl": "target", "msg": " 43.10 usb_max_current_enable default 0 max-current 3000"}
- {"dt": "2024-12-03T13:02:53.250633", "lvl": "target", "msg": " 43.15 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-03T13:02:53.250972", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:02:53.252509", "lvl": "target", "msg": " 43.21 RX: 7 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.332262", "lvl": "target", "msg": " 43.86 TFTP: complete 78059"}
- {"dt": "2024-12-03T13:02:53.332822", "lvl": "target", "msg": " 43.86 RX: 62 IP: 0 IPV4: 61 MAC: 61 UDP: 61 UDP RECV: 61 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-03T13:02:53.333139", "lvl": "target", "msg": " 43.93 Read bcm2712-rpi-5-b.dtb bytes    78059 hnd 0x0"}
- {"dt": "2024-12-03T13:02:53.333416", "lvl": "target", "msg": " 43.98 dt-match: compatible: raspberrypi,5-model-b match: brcm,bcm2712"}
- {"dt": "2024-12-03T13:02:53.333673", "lvl": "target", "msg": " 43.04 dt-match: compatible: brcm,bcm2712 match: brcm,bcm2712"}
- {"dt": "2024-12-03T13:02:53.335321", "lvl": "target", "msg": " 43.11 Selecting USB low current limit"}
- {"dt": "2024-12-03T13:02:53.335645", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:03:21.200134", "lvl": "target", "msg": "NOTICE:  BL31: v2.6(release):v2.6-240-gfc45bc492-dirty"}
- {"dt": "2024-12-03T13:03:21.203384", "lvl": "target", "msg": "NOTICE:  BL31: Built : 13:41:55, Sep 23 2024"}
- {"dt": "2024-12-03T13:03:21.347930", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x414fd0b1]"}
- {"dt": "2024-12-03T13:03:21.348465", "lvl": "target", "msg": "[    0.000000] Linux version 6.6.22-v8-16k (oe-user@oe-host) (aarch64-agl-linux-gcc (GCC) 13.3.0, GNU ld (GNU Binutils) 2.42.0.20240716) #1 SMP PREEMPT Thu Oct 24 14:13:42 UTC 2024"}
- {"dt": "2024-12-03T13:03:21.348811", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-12-03T13:03:21.349088", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-12-03T13:03:21.349340", "lvl": "target", "msg": "[    0.000000] Machine model: Raspberry Pi 5 Model B Rev 1.0"}
- {"dt": "2024-12-03T13:03:21.349587", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-12-03T13:03:21.350345", "lvl": "debug", "msg": "start: 2.3.1 login-action (timeout 00:07:39) [common]"}
- {"dt": "2024-12-03T13:03:21.350732", "lvl": "warning", "msg": "The string '/ #' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely."}
- {"dt": "2024-12-03T13:03:21.351028", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-12-03T13:03:21.351369", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2024-12-03T13:03:21.351676", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-12-03T13:03:21.351926", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-12-03T13:03:21.352188", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-12-03T13:03:21.352424", "lvl": "debug", "msg": ["-\\[ cut here \\]", "Unhandled fault", "BUG: KCSAN:", "BUG: KASAN:", "BUG: KFENCE:", "Oops(?: -|:)", "WARNING:", "(kernel BUG at|BUG:)", "invalid opcode:", "Kernel panic - not syncing", "raspberrypi5:~#", "/ #", "login:", "Login incorrect"]}
- {"dt": "2024-12-03T13:03:21.352940", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:07:39)"}
- {"dt": "2024-12-03T13:03:21.391186", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000002000000, size 64 MiB"}
- {"dt": "2024-12-03T13:03:21.391724", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool"}
- {"dt": "2024-12-03T13:03:21.392020", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000002000000..0x0000000005ffffff (65536 KiB) map reusable linux,cma"}
- {"dt": "2024-12-03T13:03:21.392619", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0"}
- {"dt": "2024-12-03T13:03:21.392936", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x000000003fd16180..0x000000003fd1627a (0 KiB) nomap non-reusable nvram@0"}
- {"dt": "2024-12-03T13:03:21.394579", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-12-03T13:03:21.434496", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000000000000-0x00000000ffffffff]"}
- {"dt": "2024-12-03T13:03:21.435015", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-12-03T13:03:21.435320", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]"}
- {"dt": "2024-12-03T13:03:21.435590", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-12-03T13:03:21.435846", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-12-03T13:03:21.436092", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000007ffff]"}
- {"dt": "2024-12-03T13:03:21.436336", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000080000-0x000000003f7fffff]"}
- {"dt": "2024-12-03T13:03:21.436576", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x00000001ffffffff]"}
- {"dt": "2024-12-03T13:03:21.477884", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]"}
- {"dt": "2024-12-03T13:03:21.478408", "lvl": "target", "msg": "[    0.000000] On node 0, zone DMA: 512 pages in unavailable ranges"}
- {"dt": "2024-12-03T13:03:21.478712", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-12-03T13:03:21.478983", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-12-03T13:03:21.479231", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-12-03T13:03:21.479475", "lvl": "target", "msg": "[    0.000000] psci: MIGRATE_INFO_TYPE not supported."}
- {"dt": "2024-12-03T13:03:21.479714", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.2"}
- {"dt": "2024-12-03T13:03:21.481028", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 14 pages/cpu s184936 r8192 d36248 u229376"}
- {"dt": "2024-12-03T13:03:21.521174", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-12-03T13:03:21.521699", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-12-03T13:03:21.522005", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Hardware dirty bit management"}
- {"dt": "2024-12-03T13:03:21.522273", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-12-03T13:03:21.522529", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-BHB"}
- {"dt": "2024-12-03T13:03:21.522775", "lvl": "target", "msg": "[    0.000000] CPU features: kernel page table isolation forced ON by KASLR"}
- {"dt": "2024-12-03T13:03:21.523022", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)"}
- {"dt": "2024-12-03T13:03:21.524350", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-12-03T13:03:21.607728", "lvl": "target", "msg": "[    0.000000] Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:E5:72:37 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000  console=ttyAMA10,115200n8 rw nbd.server=192.168.68.1 nbd.port=61952 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 ip=dhcp verbose earlyprintk systemd.log_color=false rw systemd.mask=systemd-network-generator.service"}
- {"dt": "2024-12-03T13:03:21.608287", "lvl": "target", "msg": "[    0.000000] Unknown kernel command line parameters \\\"verbose earlyprintk\\\", will be passed to user space."}
- {"dt": "2024-12-03T13:03:21.608600", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 9, 8388608 bytes, linear)"}
- {"dt": "2024-12-03T13:03:21.608902", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 8, 4194304 bytes, linear)"}
- {"dt": "2024-12-03T13:03:21.609157", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 521728"}
- {"dt": "2024-12-03T13:03:21.610910", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-12-03T13:03:21.651053", "lvl": "target", "msg": "[    0.000000] software IO TLB: area num 4."}
- {"dt": "2024-12-03T13:03:21.651576", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000fbffc000-0x00000000ffffc000] (64MB)"}
- {"dt": "2024-12-03T13:03:21.651884", "lvl": "target", "msg": "[    0.000000] Memory: 8155696K/8380416K available (15104K kernel code, 2290K rwdata, 5024K rodata, 5568K init, 1247K bss, 159184K reserved, 65536K cma-reserved)"}
- {"dt": "2024-12-03T13:03:21.652160", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1"}
- {"dt": "2024-12-03T13:03:21.652419", "lvl": "target", "msg": "[    0.000000] ftrace: allocating 49047 entries in 48 pages"}
- {"dt": "2024-12-03T13:03:21.654261", "lvl": "target", "msg": "[    0.000000] ftrace: allocated 48 pages with 2 groups"}
- {"dt": "2024-12-03T13:03:21.694349", "lvl": "target", "msg": "[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-12-03T13:03:21.694875", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-12-03T13:03:21.695185", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-12-03T13:03:21.695461", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4."}
- {"dt": "2024-12-03T13:03:21.695716", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2024-12-03T13:03:21.695963", "lvl": "target", "msg": "[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-12-03T13:03:21.696204", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-12-03T13:03:21.697499", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-12-03T13:03:21.737638", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4"}
- {"dt": "2024-12-03T13:03:21.738170", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-12-03T13:03:21.738474", "lvl": "target", "msg": "[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-12-03T13:03:21.738742", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-12-03T13:03:21.738994", "lvl": "target", "msg": "[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-12-03T13:03:21.739239", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys)."}
- {"dt": "2024-12-03T13:03:21.740838", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns"}
- {"dt": "2024-12-03T13:03:21.780957", "lvl": "target", "msg": "[    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns"}
- {"dt": "2024-12-03T13:03:21.781491", "lvl": "target", "msg": "[    0.000115] Console: colour dummy device 80x25"}
- {"dt": "2024-12-03T13:03:21.781799", "lvl": "target", "msg": "[    0.000129] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)"}
- {"dt": "2024-12-03T13:03:21.782073", "lvl": "target", "msg": "[    0.000133] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-12-03T13:03:21.782326", "lvl": "target", "msg": "[    0.000148] LSM: initializing lsm=capability,integrity"}
- {"dt": "2024-12-03T13:03:21.782573", "lvl": "target", "msg": "[    0.000192] Mount-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-03T13:03:21.824265", "lvl": "target", "msg": "[    0.000206] Mountpoint-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-03T13:03:21.824840", "lvl": "target", "msg": "[    0.000456] cgroup: Disabling memory control group subsystem"}
- {"dt": "2024-12-03T13:03:21.825154", "lvl": "target", "msg": "[    0.000734] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-03T13:03:21.825428", "lvl": "target", "msg": "[    0.000748] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-03T13:03:21.825681", "lvl": "target", "msg": "[    0.000761] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-03T13:03:21.825927", "lvl": "target", "msg": "[    0.000795] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-12-03T13:03:21.827484", "lvl": "target", "msg": "[    0.000796] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-12-03T13:03:21.867544", "lvl": "target", "msg": "[    0.001177] EFI services will not be available."}
- {"dt": "2024-12-03T13:03:21.868070", "lvl": "target", "msg": "[    0.001216] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-12-03T13:03:21.868372", "lvl": "target", "msg": "[    0.001320] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-12-03T13:03:21.868639", "lvl": "target", "msg": "[    0.001348] CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1]"}
- {"dt": "2024-12-03T13:03:21.868940", "lvl": "target", "msg": "[    0.001467] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-12-03T13:03:21.869193", "lvl": "target", "msg": "[    0.001489] CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1]"}
- {"dt": "2024-12-03T13:03:21.869438", "lvl": "target", "msg": "[    0.001601] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-12-03T13:03:21.869677", "lvl": "target", "msg": "[    0.001621] CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1]"}
- {"dt": "2024-12-03T13:03:21.870715", "lvl": "target", "msg": "[    0.001642] smp: Brought up 1 node, 4 CPUs"}
- {"dt": "2024-12-03T13:03:21.910817", "lvl": "target", "msg": "[    0.001643] SMP: Total of 4 processors activated."}
- {"dt": "2024-12-03T13:03:21.911341", "lvl": "target", "msg": "[    0.001645] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-12-03T13:03:21.911646", "lvl": "target", "msg": "[    0.001646] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-12-03T13:03:21.911919", "lvl": "target", "msg": "[    0.001647] CPU features: detected: Common not Private translations"}
- {"dt": "2024-12-03T13:03:21.912177", "lvl": "target", "msg": "[    0.001648] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-12-03T13:03:21.912429", "lvl": "target", "msg": "[    0.001650] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-12-03T13:03:21.914035", "lvl": "target", "msg": "[    0.001651] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-12-03T13:03:21.954206", "lvl": "target", "msg": "[    0.001652] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-12-03T13:03:21.954730", "lvl": "target", "msg": "[    0.001653] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-12-03T13:03:21.955034", "lvl": "target", "msg": "[    0.001655] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-12-03T13:03:21.955306", "lvl": "target", "msg": "[    0.001677] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-12-03T13:03:21.955561", "lvl": "target", "msg": "[    0.001678] alternatives: applying system-wide alternatives"}
- {"dt": "2024-12-03T13:03:21.955809", "lvl": "target", "msg": "[    0.003115] devtmpfs: initialized"}
- {"dt": "2024-12-03T13:03:21.956051", "lvl": "target", "msg": "[    0.004932] Enabled cp15_barrier support"}
- {"dt": "2024-12-03T13:03:21.956292", "lvl": "target", "msg": "[    0.004936] Enabled setend support"}
- {"dt": "2024-12-03T13:03:21.997221", "lvl": "target", "msg": "[    0.005001] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-12-03T13:03:21.997734", "lvl": "target", "msg": "[    0.005005] futex hash table entries: 1024 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-12-03T13:03:21.998042", "lvl": "target", "msg": "[    0.005219] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-12-03T13:03:21.998314", "lvl": "target", "msg": "[    0.005304] DMI not present or invalid."}
- {"dt": "2024-12-03T13:03:21.998571", "lvl": "target", "msg": "[    0.005421] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-12-03T13:03:21.998821", "lvl": "target", "msg": "[    0.005724] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-12-03T13:03:22.000477", "lvl": "target", "msg": "[    0.005751] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-12-03T13:03:22.040769", "lvl": "target", "msg": "[    0.005780] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-12-03T13:03:22.041330", "lvl": "target", "msg": "[    0.005791] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-12-03T13:03:22.041639", "lvl": "target", "msg": "[    0.005825] audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-12-03T13:03:22.041912", "lvl": "target", "msg": "[    0.005908] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-12-03T13:03:22.042165", "lvl": "target", "msg": "[    0.005915] cpuidle: using governor menu"}
- {"dt": "2024-12-03T13:03:22.042414", "lvl": "target", "msg": "[    0.005965] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-12-03T13:03:22.043889", "lvl": "target", "msg": "[    0.005984] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-12-03T13:03:22.101293", "lvl": "target", "msg": "[    0.006174] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-12-03T13:03:22.101831", "lvl": "target", "msg": "[    0.006659] bcm2835-mbox 107c013880.mailbox: mailbox enabled"}
- {"dt": "2024-12-03T13:03:22.102142", "lvl": "target", "msg": "[    0.006783] 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2"}
- {"dt": "2024-12-03T13:03:22.102417", "lvl": "target", "msg": "[    0.006790] printk: console [ttyAMA10] enabled"}
- {"dt": "2024-12-03T13:03:22.102674", "lvl": "target", "msg": "[    0.770849] raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-27T14:28:19, variant start_cd"}
- {"dt": "2024-12-03T13:03:22.104394", "lvl": "target", "msg": "[    0.784895] raspberrypi-firmware soc:firmware: Firmware hash is 0b72471b00000000000000000000000000000000"}
- {"dt": "2024-12-03T13:03:22.146951", "lvl": "target", "msg": "[    0.799740] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-12-03T13:03:22.147482", "lvl": "target", "msg": "[    0.806817] Modules: 0 pages in range for non-PLT usage"}
- {"dt": "2024-12-03T13:03:22.147785", "lvl": "target", "msg": "[    0.806818] Modules: 129224 pages in range for PLT usage"}
- {"dt": "2024-12-03T13:03:22.148055", "lvl": "target", "msg": "[    0.813847] bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1"}
- {"dt": "2024-12-03T13:03:22.148310", "lvl": "target", "msg": "[    0.826471] iommu: Default domain type: Translated"}
- {"dt": "2024-12-03T13:03:22.148556", "lvl": "target", "msg": "[    0.831299] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-12-03T13:03:22.148874", "lvl": "target", "msg": "[    0.837739] SCSI subsystem initialized"}
- {"dt": "2024-12-03T13:03:22.150127", "lvl": "target", "msg": "[    0.841517] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-12-03T13:03:22.201138", "lvl": "target", "msg": "[    0.847024] usbcore: registered new interface driver hub"}
- {"dt": "2024-12-03T13:03:22.201664", "lvl": "target", "msg": "[    0.852356] usbcore: registered new device driver usb"}
- {"dt": "2024-12-03T13:03:22.201967", "lvl": "target", "msg": "[    0.857480] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-12-03T13:03:22.202233", "lvl": "target", "msg": "[    0.862458] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-12-03T13:03:22.202494", "lvl": "target", "msg": "[    0.871626] PTP clock support registered"}
- {"dt": "2024-12-03T13:03:22.202741", "lvl": "target", "msg": "[    0.875592] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-12-03T13:03:22.202989", "lvl": "target", "msg": "[    0.881861] vgaarb: loaded"}
- {"dt": "2024-12-03T13:03:22.204271", "lvl": "target", "msg": "[    0.884656] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-12-03T13:03:22.335795", "lvl": "target", "msg": "[    0.991995] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-12-03T13:03:22.336319", "lvl": "target", "msg": "[    0.995937] VFS: Dquot-cache hash table entries: 2048 (order 0, 16384 bytes)"}
- {"dt": "2024-12-03T13:03:22.336628", "lvl": "target", "msg": "[    1.003028] FS-Cache: Loaded"}
- {"dt": "2024-12-03T13:03:22.336956", "lvl": "target", "msg": "[    1.005937] CacheFiles: Loaded"}
- {"dt": "2024-12-03T13:03:22.337213", "lvl": "target", "msg": "[    1.009963] NET: Registered PF_INET protocol family"}
- {"dt": "2024-12-03T13:03:22.337460", "lvl": "target", "msg": "[    1.014956] IP idents hash table entries: 131072 (order: 6, 1048576 bytes, linear)"}
- {"dt": "2024-12-03T13:03:22.337705", "lvl": "target", "msg": "[    1.024067] tcp_listen_portaddr_hash hash table entries: 4096 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-12-03T13:03:22.381155", "lvl": "target", "msg": "[    1.032659] Table-perturb hash table entries: 65536 (order: 4, 262144 bytes, linear)"}
- {"dt": "2024-12-03T13:03:22.381687", "lvl": "target", "msg": "[    1.040431] TCP established hash table entries: 65536 (order: 5, 524288 bytes, linear)"}
- {"dt": "2024-12-03T13:03:22.381996", "lvl": "target", "msg": "[    1.048543] TCP bind hash table entries: 65536 (order: 7, 2097152 bytes, linear)"}
- {"dt": "2024-12-03T13:03:22.382269", "lvl": "target", "msg": "[    1.057459] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-12-03T13:03:22.382531", "lvl": "target", "msg": "[    1.064099] MPTCP token hash table entries: 8192 (order: 3, 196608 bytes, linear)"}
- {"dt": "2024-12-03T13:03:22.382779", "lvl": "target", "msg": "[    1.071758] UDP hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-03T13:03:22.424849", "lvl": "target", "msg": "[    1.078630] UDP-Lite hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-03T13:03:22.425369", "lvl": "target", "msg": "[    1.085976] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-12-03T13:03:22.425673", "lvl": "target", "msg": "[    1.091783] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-12-03T13:03:22.425940", "lvl": "target", "msg": "[    1.097724] RPC: Registered udp transport module."}
- {"dt": "2024-12-03T13:03:22.426191", "lvl": "target", "msg": "[    1.102439] RPC: Registered tcp transport module."}
- {"dt": "2024-12-03T13:03:22.426437", "lvl": "target", "msg": "[    1.107155] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-12-03T13:03:22.426679", "lvl": "target", "msg": "[    1.112660] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-12-03T13:03:22.427979", "lvl": "target", "msg": "[    1.119124] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-12-03T13:03:22.469571", "lvl": "target", "msg": "[    1.123227] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2024-12-03T13:03:22.470102", "lvl": "target", "msg": "[    1.127546] kvm [1]: GICV region size/alignment is unsafe, using trapping (reduced performance)"}
- {"dt": "2024-12-03T13:03:22.470408", "lvl": "target", "msg": "[    1.127551] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-12-03T13:03:22.470683", "lvl": "target", "msg": "[    1.136301] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-12-03T13:03:22.470935", "lvl": "target", "msg": "[    1.141717] rootfs image is not initramfs (invalid magic at start of compressed archive); looks like an initrd"}
- {"dt": "2024-12-03T13:03:22.471183", "lvl": "target", "msg": "[    1.155670] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-12-03T13:03:22.471424", "lvl": "target", "msg": "[    1.161932] Initialise system trusted keyrings"}
- {"dt": "2024-12-03T13:03:22.513193", "lvl": "target", "msg": "[    1.166709] workingset: timestamp_bits=46 max_order=19 bucket_order=0"}
- {"dt": "2024-12-03T13:03:22.513714", "lvl": "target", "msg": "[    1.168152] Freeing initrd memory: 12288K"}
- {"dt": "2024-12-03T13:03:22.514016", "lvl": "target", "msg": "[    1.173209] zbud: loaded"}
- {"dt": "2024-12-03T13:03:22.514286", "lvl": "target", "msg": "[    1.179891] NFS: Registering the id_resolver key type"}
- {"dt": "2024-12-03T13:03:22.514542", "lvl": "target", "msg": "[    1.184964] Key type id_resolver registered"}
- {"dt": "2024-12-03T13:03:22.514787", "lvl": "target", "msg": "[    1.189156] Key type id_legacy registered"}
- {"dt": "2024-12-03T13:03:22.515026", "lvl": "target", "msg": "[    1.193177] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-12-03T13:03:22.515265", "lvl": "target", "msg": "[    1.199901] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-12-03T13:03:22.516346", "lvl": "target", "msg": "[    1.207357] F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-12-03T13:03:22.556782", "lvl": "target", "msg": "[    1.212906] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-12-03T13:03:22.557325", "lvl": "target", "msg": "[    1.217711] Key type asymmetric registered"}
- {"dt": "2024-12-03T13:03:22.557629", "lvl": "target", "msg": "[    1.221817] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-12-03T13:03:22.557894", "lvl": "target", "msg": "[    1.226719] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2024-12-03T13:03:22.558151", "lvl": "target", "msg": "[    1.234172] io scheduler mq-deadline registered"}
- {"dt": "2024-12-03T13:03:22.558395", "lvl": "target", "msg": "[    1.238715] io scheduler kyber registered"}
- {"dt": "2024-12-03T13:03:22.558639", "lvl": "target", "msg": "[    1.242739] io scheduler bfq registered"}
- {"dt": "2024-12-03T13:03:22.558877", "lvl": "target", "msg": "[    1.246755] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 26)"}
- {"dt": "2024-12-03T13:03:22.595331", "lvl": "target", "msg": "[    1.254485] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 27)"}
- {"dt": "2024-12-03T13:03:22.595855", "lvl": "target", "msg": "[    1.262196] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 28)"}
- {"dt": "2024-12-03T13:03:22.596160", "lvl": "target", "msg": "[    1.269940] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 29)"}
- {"dt": "2024-12-03T13:03:22.598487", "lvl": "target", "msg": "[    1.279218] bcm2708_fb soc:fb: FB found 1 display(s)"}
- {"dt": "2024-12-03T13:03:22.621216", "lvl": "target", "msg": "[    1.292585] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-12-03T13:03:22.624450", "lvl": "target", "msg": "[    1.301544] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1920x1080"}
- {"dt": "2024-12-03T13:03:22.682668", "lvl": "target", "msg": "[    1.337610] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled"}
- {"dt": "2024-12-03T13:03:22.683174", "lvl": "target", "msg": "[    1.344156] 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 32, base_baud = 6000000) is a Broadcom BCM7271 UART"}
- {"dt": "2024-12-03T13:03:22.683480", "lvl": "target", "msg": "[    1.354752] serial serial0: tty port ttyS0 registered"}
- {"dt": "2024-12-03T13:03:22.683746", "lvl": "target", "msg": "[    1.359994] iproc-rng200 107d208000.rng: hwrng registered"}
- {"dt": "2024-12-03T13:03:22.683995", "lvl": "target", "msg": "[    1.365440] vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB)"}
- {"dt": "2024-12-03T13:03:22.685838", "lvl": "target", "msg": "[    1.373876] bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe"}
- {"dt": "2024-12-03T13:03:22.731421", "lvl": "target", "msg": "[    1.382442] brd: module loaded"}
- {"dt": "2024-12-03T13:03:22.731933", "lvl": "target", "msg": "[    1.386364] loop: module loaded"}
- {"dt": "2024-12-03T13:03:22.732233", "lvl": "target", "msg": "[    1.393307] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver"}
- {"dt": "2024-12-03T13:03:22.732497", "lvl": "target", "msg": "[    1.400687] Loading iSCSI transport class v2.0-870."}
- {"dt": "2024-12-03T13:03:22.732785", "lvl": "target", "msg": "[    1.406127] usbcore: registered new device driver r8152-cfgselector"}
- {"dt": "2024-12-03T13:03:22.733039", "lvl": "target", "msg": "[    1.412422] usbcore: registered new interface driver r8152"}
- {"dt": "2024-12-03T13:03:22.733282", "lvl": "target", "msg": "[    1.417927] usbcore: registered new interface driver lan78xx"}
- {"dt": "2024-12-03T13:03:22.734590", "lvl": "target", "msg": "[    1.423641] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)"}
- {"dt": "2024-12-03T13:03:22.783755", "lvl": "target", "msg": "[    1.429503] usbcore: registered new interface driver uas"}
- {"dt": "2024-12-03T13:03:22.784274", "lvl": "target", "msg": "[    1.434836] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-12-03T13:03:22.784580", "lvl": "target", "msg": "[    1.440881] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-12-03T13:03:22.784890", "lvl": "target", "msg": "[    1.447440] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-12-03T13:03:22.785146", "lvl": "target", "msg": "[    1.453468] usbcore: registered new interface driver cp210x"}
- {"dt": "2024-12-03T13:03:22.785392", "lvl": "target", "msg": "[    1.459059] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2024-12-03T13:03:22.785632", "lvl": "target", "msg": "[    1.465053] mousedev: PS/2 mouse device common for all mice"}
- {"dt": "2024-12-03T13:03:22.786912", "lvl": "target", "msg": "[    1.478180] rpi-rtc soc:rpi_rtc: registered as rtc0"}
- {"dt": "2024-12-03T13:03:22.829506", "lvl": "target", "msg": "[    1.484451] rpi-rtc soc:rpi_rtc: setting system clock to 1970-01-01T00:01:12 UTC (72)"}
- {"dt": "2024-12-03T13:03:22.830040", "lvl": "target", "msg": "[    1.492350] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-12-03T13:03:22.830344", "lvl": "target", "msg": "[    1.498666] bcm2835-wdt bcm2835-wdt: Poweroff handler already present!"}
- {"dt": "2024-12-03T13:03:22.830613", "lvl": "target", "msg": "[    1.505218] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer"}
- {"dt": "2024-12-03T13:03:22.830862", "lvl": "target", "msg": "[    1.513623] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-12-03T13:03:22.831108", "lvl": "target", "msg": "[    1.519829] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-12-03T13:03:22.832918", "lvl": "target", "msg": "[    1.524217] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-12-03T13:03:22.873163", "lvl": "target", "msg": "[    1.530026] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-12-03T13:03:22.873686", "lvl": "target", "msg": "[    1.536086] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ...."}
- {"dt": "2024-12-03T13:03:22.874000", "lvl": "target", "msg": "[    1.542558] hid: raw HID events driver (C) Jiri Kosina"}
- {"dt": "2024-12-03T13:03:22.874270", "lvl": "target", "msg": "[    1.547738] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-12-03T13:03:22.874522", "lvl": "target", "msg": "[    1.553328] usbhid: USB HID core driver"}
- {"dt": "2024-12-03T13:03:22.874770", "lvl": "target", "msg": "[    1.557401] hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available"}
- {"dt": "2024-12-03T13:03:22.876695", "lvl": "target", "msg": "[    1.565711] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-12-03T13:03:22.921986", "lvl": "target", "msg": "[    1.572011] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-12-03T13:03:22.922506", "lvl": "target", "msg": "[    1.577097] Key type dns_resolver registered"}
- {"dt": "2024-12-03T13:03:22.922805", "lvl": "target", "msg": "[    1.584678] registered taskstats version 1"}
- {"dt": "2024-12-03T13:03:22.923072", "lvl": "target", "msg": "[    1.588827] Loading compiled-in X.509 certificates"}
- {"dt": "2024-12-03T13:03:22.923325", "lvl": "target", "msg": "[    1.594918] Key type .fscrypt registered"}
- {"dt": "2024-12-03T13:03:22.923569", "lvl": "target", "msg": "[    1.598851] Key type fscrypt-provisioning registered"}
- {"dt": "2024-12-03T13:03:22.923811", "lvl": "target", "msg": "[    1.604403] brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges:"}
- {"dt": "2024-12-03T13:03:22.925145", "lvl": "target", "msg": "[    1.611399] brcm-pcie 1000120000.pcie:   No bus range found for /axi/pcie@120000, using [bus 00-ff]"}
- {"dt": "2024-12-03T13:03:22.966657", "lvl": "target", "msg": "[    1.620564] brcm-pcie 1000120000.pcie:      MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000"}
- {"dt": "2024-12-03T13:03:22.967192", "lvl": "target", "msg": "[    1.628863] brcm-pcie 1000120000.pcie:      MEM 0x1c00000000..0x1effffffff -> 0x0400000000"}
- {"dt": "2024-12-03T13:03:22.967502", "lvl": "target", "msg": "[    1.637162] brcm-pcie 1000120000.pcie:   IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000"}
- {"dt": "2024-12-03T13:03:22.967773", "lvl": "target", "msg": "[    1.645459] brcm-pcie 1000120000.pcie:   IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000"}
- {"dt": "2024-12-03T13:03:22.968025", "lvl": "target", "msg": "[    1.654918] brcm-pcie 1000120000.pcie: Forcing gen 2"}
- {"dt": "2024-12-03T13:03:22.969816", "lvl": "target", "msg": "[    1.660102] brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-12-03T13:03:23.010633", "lvl": "target", "msg": "[    1.666566] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-12-03T13:03:23.011176", "lvl": "target", "msg": "[    1.672069] pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb])"}
- {"dt": "2024-12-03T13:03:23.011487", "lvl": "target", "msg": "[    1.682634] pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff])"}
- {"dt": "2024-12-03T13:03:23.011759", "lvl": "target", "msg": "[    1.693817] pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400"}
- {"dt": "2024-12-03T13:03:23.012014", "lvl": "target", "msg": "[    1.699862] pci 0000:00:00.0: PME# supported from D0 D3hot"}
- {"dt": "2024-12-03T13:03:23.029462", "lvl": "target", "msg": "[    1.705948] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring"}
- {"dt": "2024-12-03T13:03:23.175597", "lvl": "target", "msg": "[    1.820660] brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC)"}
- {"dt": "2024-12-03T13:03:23.176120", "lvl": "target", "msg": "[    1.827312] pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000"}
- {"dt": "2024-12-03T13:03:23.176422", "lvl": "target", "msg": "[    1.833349] pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff]"}
- {"dt": "2024-12-03T13:03:23.176692", "lvl": "target", "msg": "[    1.839641] pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff]"}
- {"dt": "2024-12-03T13:03:23.177046", "lvl": "target", "msg": "[    1.845933] pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff]"}
- {"dt": "2024-12-03T13:03:23.177300", "lvl": "target", "msg": "[    1.852280] pci 0000:01:00.0: supports D1"}
- {"dt": "2024-12-03T13:03:23.177543", "lvl": "target", "msg": "[    1.856299] pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold"}
- {"dt": "2024-12-03T13:03:23.218921", "lvl": "target", "msg": "[    1.872664] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01"}
- {"dt": "2024-12-03T13:03:23.219435", "lvl": "target", "msg": "[    1.879306] pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-12-03T13:03:23.219739", "lvl": "target", "msg": "[    1.886468] pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff]"}
- {"dt": "2024-12-03T13:03:23.220010", "lvl": "target", "msg": "[    1.893630] pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff]"}
- {"dt": "2024-12-03T13:03:23.220265", "lvl": "target", "msg": "[    1.900793] pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff]"}
- {"dt": "2024-12-03T13:03:23.220509", "lvl": "target", "msg": "[    1.907956] pci 0000:00:00.0: PCI bridge to [bus 01]"}
- {"dt": "2024-12-03T13:03:23.222123", "lvl": "target", "msg": "[    1.912934] pci 0000:00:00.0:   bridge window [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-12-03T13:03:23.262397", "lvl": "target", "msg": "[    1.920096] pci 0000:00:00.0: Max Payload Size set to  256/ 512 (was  128), Max Read Rq  512"}
- {"dt": "2024-12-03T13:03:23.262922", "lvl": "target", "msg": "[    1.928571] pci 0000:01:00.0: Max Payload Size set to  256/ 256 (was  128), Max Read Rq  512"}
- {"dt": "2024-12-03T13:03:23.263226", "lvl": "target", "msg": "[    1.937087] pcieport 0000:00:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-12-03T13:03:23.263498", "lvl": "target", "msg": "[    1.943225] pcieport 0000:00:00.0: PME: Signaling with IRQ 37"}
- {"dt": "2024-12-03T13:03:23.263749", "lvl": "target", "msg": "[    1.949027] pcieport 0000:00:00.0: AER: enabled with IRQ 37"}
- {"dt": "2024-12-03T13:03:23.265529", "lvl": "target", "msg": "[    1.954672] rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200"}
- {"dt": "2024-12-03T13:03:23.294873", "lvl": "target", "msg": "[    1.963755] rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200"}
- {"dt": "2024-12-03T13:03:23.295403", "lvl": "target", "msg": "[    1.973016] rp1 0000:01:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-12-03T13:03:23.298190", "lvl": "target", "msg": "[    1.979302] rp1 0000:01:00.0: chip_id 0x20001927"}
- {"dt": "2024-12-03T13:03:23.348128", "lvl": "target", "msg": "[    2.002585] macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 105 (d8:3a:dd:e5:72:37)"}
- {"dt": "2024-12-03T13:03:23.348632", "lvl": "target", "msg": "[    2.016657] dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels"}
- {"dt": "2024-12-03T13:03:23.348972", "lvl": "target", "msg": "[    2.025142] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-12-03T13:03:23.349245", "lvl": "target", "msg": "[    2.030213] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-12-03T13:03:23.351349", "lvl": "target", "msg": "[    2.037892] xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-12-03T13:03:23.391558", "lvl": "target", "msg": "[    2.046891] xhci-hcd xhci-hcd.0: irq 130, io mem 0x1f00200000"}
- {"dt": "2024-12-03T13:03:23.392086", "lvl": "target", "msg": "[    2.052711] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-12-03T13:03:23.392391", "lvl": "target", "msg": "[    2.057777] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-12-03T13:03:23.392662", "lvl": "target", "msg": "[    2.065025] xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-03T13:03:23.392950", "lvl": "target", "msg": "[    2.071170] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-12-03T13:03:23.393203", "lvl": "target", "msg": "[    2.079468] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-03T13:03:23.394768", "lvl": "target", "msg": "[    2.086720] usb usb1: Product: xHCI Host Controller"}
- {"dt": "2024-12-03T13:03:23.435069", "lvl": "target", "msg": "[    2.091612] usb usb1: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-03T13:03:23.435585", "lvl": "target", "msg": "[    2.097638] usb usb1: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-12-03T13:03:23.435885", "lvl": "target", "msg": "[    2.102175] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-12-03T13:03:23.436150", "lvl": "target", "msg": "[    2.105943] hub 1-0:1.0: 2 ports detected"}
- {"dt": "2024-12-03T13:03:23.436398", "lvl": "target", "msg": "[    2.110059] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-12-03T13:03:23.436648", "lvl": "target", "msg": "[    2.118357] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-03T13:03:23.436923", "lvl": "target", "msg": "[    2.125606] usb usb2: Product: xHCI Host Controller"}
- {"dt": "2024-12-03T13:03:23.438211", "lvl": "target", "msg": "[    2.130497] usb usb2: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-03T13:03:23.479471", "lvl": "target", "msg": "[    2.136533] usb usb2: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-12-03T13:03:23.479991", "lvl": "target", "msg": "[    2.141108] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-12-03T13:03:23.480292", "lvl": "target", "msg": "[    2.144874] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-12-03T13:03:23.480562", "lvl": "target", "msg": "[    2.149021] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-12-03T13:03:23.480856", "lvl": "target", "msg": "[    2.154091] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-12-03T13:03:23.481108", "lvl": "target", "msg": "[    2.161868] xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-12-03T13:03:23.481354", "lvl": "target", "msg": "[    2.170869] xhci-hcd xhci-hcd.1: irq 135, io mem 0x1f00300000"}
- {"dt": "2024-12-03T13:03:23.522966", "lvl": "target", "msg": "[    2.176788] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-12-03T13:03:23.523482", "lvl": "target", "msg": "[    2.181855] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-12-03T13:03:23.523785", "lvl": "target", "msg": "[    2.189103] xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-03T13:03:23.524053", "lvl": "target", "msg": "[    2.195330] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-12-03T13:03:23.524308", "lvl": "target", "msg": "[    2.203626] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-03T13:03:23.524551", "lvl": "target", "msg": "[    2.210969] usb usb3: Product: xHCI Host Controller"}
- {"dt": "2024-12-03T13:03:23.526110", "lvl": "target", "msg": "[    2.215860] usb usb3: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-03T13:03:23.566836", "lvl": "target", "msg": "[    2.221885] usb usb3: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-12-03T13:03:23.567361", "lvl": "target", "msg": "[    2.226498] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-12-03T13:03:23.567664", "lvl": "target", "msg": "[    2.230264] hub 3-0:1.0: 2 ports detected"}
- {"dt": "2024-12-03T13:03:23.567933", "lvl": "target", "msg": "[    2.234363] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-12-03T13:03:23.568189", "lvl": "target", "msg": "[    2.242755] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-03T13:03:23.568435", "lvl": "target", "msg": "[    2.250005] usb usb4: Product: xHCI Host Controller"}
- {"dt": "2024-12-03T13:03:23.568680", "lvl": "target", "msg": "[    2.254918] usb usb4: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-03T13:03:23.570019", "lvl": "target", "msg": "[    2.261051] usb usb4: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-12-03T13:03:23.611190", "lvl": "target", "msg": "[    2.265614] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-12-03T13:03:23.611712", "lvl": "target", "msg": "[    2.269386] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-12-03T13:03:23.612014", "lvl": "target", "msg": "[    2.273756] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-03T13:03:23.612290", "lvl": "target", "msg": "[    2.282198] platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-12-03T13:03:23.612543", "lvl": "target", "msg": "[    2.290234] platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-12-03T13:03:23.612834", "lvl": "target", "msg": "[    2.298472] platform 1000800000.codec: Adding to iommu group 0"}
- {"dt": "2024-12-03T13:03:23.614417", "lvl": "target", "msg": "[    2.304329] platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-12-03T13:03:23.655207", "lvl": "target", "msg": "[    2.312538] platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-12-03T13:03:23.655731", "lvl": "target", "msg": "[    2.320938] platform 1000880000.pisp_be: Adding to iommu group 0"}
- {"dt": "2024-12-03T13:03:23.656037", "lvl": "target", "msg": "[    2.326979] platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-12-03T13:03:23.656302", "lvl": "target", "msg": "[    2.334837] platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-12-03T13:03:23.656551", "lvl": "target", "msg": "[    2.343057] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-03T13:03:23.710043", "lvl": "target", "msg": "[    2.350021] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-03T13:03:23.710556", "lvl": "target", "msg": "[    2.358454] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-03T13:03:23.710863", "lvl": "target", "msg": "[    2.365465] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-03T13:03:23.711133", "lvl": "target", "msg": "[    2.373893] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-03T13:03:23.711385", "lvl": "target", "msg": "[    2.380904] sdhci-brcmstb 1000fff000.mmc: Got CD GPIO"}
- {"dt": "2024-12-03T13:03:23.711634", "lvl": "target", "msg": "[    2.386190] mmc1: CQHCI version 5.10"}
- {"dt": "2024-12-03T13:03:23.711876", "lvl": "target", "msg": "[    2.390066] of_cfs_init"}
- {"dt": "2024-12-03T13:03:23.712111", "lvl": "target", "msg": "[    2.390110] mmc0: CQHCI version 5.10"}
- {"dt": "2024-12-03T13:03:23.713111", "lvl": "target", "msg": "[    2.392550] of_cfs_init: OK"}
- {"dt": "2024-12-03T13:03:23.753817", "lvl": "target", "msg": "[    2.431893] mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit"}
- {"dt": "2024-12-03T13:03:23.941565", "lvl": "target", "msg": "[    2.577286] mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit"}
- {"dt": "2024-12-03T13:03:23.942090", "lvl": "target", "msg": "[    2.592016] macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL)"}
- {"dt": "2024-12-03T13:03:23.942393", "lvl": "target", "msg": "[    2.602935] macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode"}
- {"dt": "2024-12-03T13:03:23.942662", "lvl": "target", "msg": "[    2.613026] pps pps0: new PPS source ptp0"}
- {"dt": "2024-12-03T13:03:23.942914", "lvl": "target", "msg": "[    2.617052] mmc1: new ultra high speed DDR50 SDIO card at address 0001"}
- {"dt": "2024-12-03T13:03:23.944772", "lvl": "target", "msg": "[    2.617075] macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered."}
- {"dt": "2024-12-03T13:03:27.007652", "lvl": "target", "msg": "[    5.685670] macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-12-03T13:03:27.065855", "lvl": "target", "msg": "[    5.708656] Sending DHCP requests ., OK"}
- {"dt": "2024-12-03T13:03:27.066340", "lvl": "target", "msg": "[    5.728504] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.231"}
- {"dt": "2024-12-03T13:03:27.066639", "lvl": "target", "msg": "[    5.736452] IP-Config: Complete:"}
- {"dt": "2024-12-03T13:03:27.066902", "lvl": "target", "msg": "[    5.739685]      device=eth0, hwaddr=d8:3a:dd:e5:72:37, ipaddr=192.168.66.231, mask=255.255.255.0, gw=192.168.66.1"}
- {"dt": "2024-12-03T13:03:27.067848", "lvl": "target", "msg": "[    5.750075]      host=192.168.66.231, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-12-03T13:03:27.069230", "lvl": "target", "msg": "[    5.757061]      bootserver=192.168.66.1, rootserver=192.168.66.1, rootpath="}
- {"dt": "2024-12-03T13:03:27.069622", "lvl": "target", "msg": "[    5.757062]      nameserver0=10.1.1.1"}
- {"dt": "2024-12-03T13:03:27.106117", "lvl": "target", "msg": "[    5.767876] clk: Disabling unused clocks"}
- {"dt": "2024-12-03T13:03:27.106644", "lvl": "target", "msg": "[    5.780335] ALSA device list:"}
- {"dt": "2024-12-03T13:03:27.106937", "lvl": "target", "msg": "[    5.783318]   No soundcards found."}
- {"dt": "2024-12-03T13:03:27.107199", "lvl": "target", "msg": "[    5.786760] uart-pl011 107d001000.serial: no DMA platform data"}
- {"dt": "2024-12-03T13:03:27.109479", "lvl": "target", "msg": "[    5.792621] Waiting 7 sec before mounting root device..."}
- {"dt": "2024-12-03T13:03:34.200609", "lvl": "target", "msg": "[   12.852679] RAMDISK: ext2 filesystem found at block 0"}
- {"dt": "2024-12-03T13:03:34.291309", "lvl": "target", "msg": "[   12.857748] RAMDISK: Loading 12288KiB [1 disk] into ram disk... |\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b"}
- {"dt": "2024-12-03T13:03:34.345994", "lvl": "target", "msg": "[   12.864464] |\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\bdone."}
- {"dt": "2024-12-03T13:03:34.346609", "lvl": "target", "msg": "[   13.019341] EXT4-fs (ram0): mounted filesystem d291e3c8-a47c-4bba-b248-a917c9104949 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-12-03T13:03:34.346919", "lvl": "target", "msg": "[   13.031066] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2024-12-03T13:03:34.347193", "lvl": "target", "msg": "[   13.036939] devtmpfs: mounted"}
- {"dt": "2024-12-03T13:03:34.349455", "lvl": "target", "msg": "[   13.042419] Freeing unused kernel memory: 5568K"}
- {"dt": "2024-12-03T13:03:34.392902", "lvl": "target", "msg": "[   13.047035] Run /sbin/init as init process"}
- {"dt": "2024-12-03T13:03:34.393458", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-03T13:03:34.393762", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2024-12-03T13:03:34.394034", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2024-12-03T13:03:34.394288", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2024-12-03T13:03:34.394537", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2024-12-03T13:03:34.436003", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2024-12-03T13:03:34.436559", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-03T13:03:34.436912", "lvl": "target", "msg": "/sbin/init[1]: sta[   13.100967] nbd0: detected capacity change from 0 to 8962048"}
- {"dt": "2024-12-03T13:03:34.437201", "lvl": "target", "msg": "rting initrd script"}
- {"dt": "2024-12-03T13:03:34.437454", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2024-12-03T13:03:34.437703", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2024-12-03T13:03:34.437952", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2024-12-03T13:03:34.438198", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2024-12-03T13:03:34.438440", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2024-12-03T13:03:34.438678", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2024-12-03T13:03:34.439650", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2024-12-03T13:03:34.467781", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2024-12-03T13:03:34.468283", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.68.1:61952"}
- {"dt": "2024-12-03T13:03:34.470946", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2024-12-03T13:03:34.471352", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2024-12-03T13:03:34.950012", "lvl": "target", "msg": "[   13.603990] EXT4-fs (nbd0): mounted filesystem 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-12-03T13:03:34.950506", "lvl": "target", "msg": "/sbin/init[1]: Moving /dev to new rootfs"}
- {"dt": "2024-12-03T13:03:34.951535", "lvl": "target", "msg": "/sbin/init[1]: Moving /proc to new rootfs"}
- {"dt": "2024-12-03T13:03:34.951839", "lvl": "target", "msg": "/sbin/init[1]: Moving /sys to new rootfs"}
- {"dt": "2024-12-03T13:03:34.952103", "lvl": "target", "msg": "/sbin/init[1]: Moving /tmp to new rootfs"}
- {"dt": "2024-12-03T13:03:34.952357", "lvl": "target", "msg": "/sbin/init[1]: Moving /run to new rootfs"}
- {"dt": "2024-12-03T13:03:34.953289", "lvl": "target", "msg": "/sbin/init[1]: Switching to new rootfs"}
- {"dt": "2024-12-03T13:03:35.302291", "lvl": "target", "msg": "/sbin/init[1]: find_active_interface: first active interface is eth0"}
- {"dt": "2024-12-03T13:03:35.305452", "lvl": "target", "msg": "/sbin/init[1]: Adjusting Connman command line. Will be: 'connmand -r -n -I eth0,enp2s0,enp3s0,end0,enp0s2,enp0s1 '"}
- {"dt": "2024-12-03T13:03:35.385133", "lvl": "target", "msg": "/sbin/init[1]: Unmounting /tmp and /run"}
- {"dt": "2024-12-03T13:03:35.497749", "lvl": "target", "msg": "/sbin/init[1]: Exec'ing systemd"}
- {"dt": "2024-12-03T13:03:35.498278", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-03T13:03:35.498595", "lvl": "target", "msg": "      ___   ________       ____              __  _"}
- {"dt": "2024-12-03T13:03:35.498869", "lvl": "target", "msg": "     /   | / ____/ /      / __ )____  ____  / /_(_)___  ____ _"}
- {"dt": "2024-12-03T13:03:35.499126", "lvl": "target", "msg": "    / /| |/ / __/ /      / __  / __ \\/ __ \\/ __/ / __ \\/ __ `/"}
- {"dt": "2024-12-03T13:03:35.499377", "lvl": "target", "msg": "   / ___ / /_/ / /___   / /_/ / /_/ / /_/ / /_/ / / / / /_/ /   _     _     _"}
- {"dt": "2024-12-03T13:03:35.501206", "lvl": "target", "msg": "  /_/  |_\\____/_____/  /_____/\\____/\\____/\\__/_/_/ /_/\\__, /   (_)   (_)   (_)"}
- {"dt": "2024-12-03T13:03:35.515896", "lvl": "target", "msg": "_____________________________________________________/____/_____________________"}
- {"dt": "2024-12-03T13:03:36.351388", "lvl": "target", "msg": "[   15.029446] systemd[1]: System time before build time, advancing clock."}
- {"dt": "2024-12-03T13:03:36.508872", "lvl": "target", "msg": "[   15.184304] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-12-03T13:03:36.509387", "lvl": "target", "msg": "[   15.189639] Segment Routing with IPv6"}
- {"dt": "2024-12-03T13:03:36.512081", "lvl": "target", "msg": "[   15.193321] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-12-03T13:03:36.653288", "lvl": "target", "msg": "[   15.307158] systemd[1]: systemd 255.4^ running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA -SMACK +SECCOMP -GCRYPT -GNUTLS -OPENSSL +ACL +BLKID -CURL -ELFUTILS -FIDO2 -IDN2 -IDN +IPTC +KMOD -LIBCRYPTSETUP +LIBFDISK -PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 -BZIP2 -LZ4 -XZ -ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)"}
- {"dt": "2024-12-03T13:03:36.655924", "lvl": "target", "msg": "[   15.339131] systemd[1]: Detected architecture arm64."}
- {"dt": "2024-12-03T13:03:36.671505", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:03:36.671971", "lvl": "target", "msg": "Welcome to Automotive Grade Linux 18.0.2 (ricefish)!"}
- {"dt": "2024-12-03T13:03:36.672279", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:03:36.687615", "lvl": "target", "msg": "[   15.376261] systemd[1]: Hostname set to <raspberrypi5>."}
- {"dt": "2024-12-03T13:03:36.727461", "lvl": "target", "msg": "[   15.405676] systemd[1]: Initializing machine ID from random generator."}
- {"dt": "2024-12-03T13:03:37.229455", "lvl": "target", "msg": "[   15.908314] systemd[1]: Queued start job for default target Graphical Interface."}
- {"dt": "2024-12-03T13:03:37.283538", "lvl": "target", "msg": "[   15.965229] systemd[1]: Created slice Slice /system/getty."}
- {"dt": "2024-12-03T13:03:37.286716", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/getty."}
- {"dt": "2024-12-03T13:03:37.361214", "lvl": "target", "msg": "[   15.984923] systemd[1]: Created slice Slice /system/modprobe."}
- {"dt": "2024-12-03T13:03:37.361776", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/modprobe."}
- {"dt": "2024-12-03T13:03:37.362090", "lvl": "target", "msg": "[   16.004876] systemd[1]: Created slice Slice /system/serial-getty."}
- {"dt": "2024-12-03T13:03:37.362365", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/serial-getty."}
- {"dt": "2024-12-03T13:03:37.362619", "lvl": "target", "msg": "[   16.024866] systemd[1]: Created slice Slice /system/wireplumber."}
- {"dt": "2024-12-03T13:03:37.362866", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/wireplumber."}
- {"dt": "2024-12-03T13:03:37.363827", "lvl": "target", "msg": "[   16.044778] systemd[1]: Created slice User and Session Slice."}
- {"dt": "2024-12-03T13:03:37.364825", "lvl": "target", "msg": "[  OK  ] Created slice User and Session Slice."}
- {"dt": "2024-12-03T13:03:37.426510", "lvl": "target", "msg": "[   16.060724] systemd[1]: Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-12-03T13:03:37.427063", "lvl": "target", "msg": "[  OK  ] Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-12-03T13:03:37.427372", "lvl": "target", "msg": "[   16.084698] systemd[1]: Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-12-03T13:03:37.428373", "lvl": "target", "msg": "[  OK  ] Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-12-03T13:03:37.428657", "lvl": "target", "msg": "[   16.104680] systemd[1]: Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-12-03T13:03:37.429890", "lvl": "target", "msg": "         Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-12-03T13:03:37.510105", "lvl": "target", "msg": "[   16.124694] systemd[1]: Reached target Host and Network Name Lookups."}
- {"dt": "2024-12-03T13:03:37.510642", "lvl": "target", "msg": "[  OK  ] Reached target Host and Network Name Lookups."}
- {"dt": "2024-12-03T13:03:37.510944", "lvl": "target", "msg": "[   16.144671] systemd[1]: Reached target Path Units."}
- {"dt": "2024-12-03T13:03:37.511215", "lvl": "target", "msg": "[  OK  ] Reached target Path Units."}
- {"dt": "2024-12-03T13:03:37.511470", "lvl": "target", "msg": "[   16.160678] systemd[1]: Reached target Remote File Systems."}
- {"dt": "2024-12-03T13:03:37.511719", "lvl": "target", "msg": "[  OK  ] Reached target Remote File Systems."}
- {"dt": "2024-12-03T13:03:37.511962", "lvl": "target", "msg": "[   16.176673] systemd[1]: Reached target Slice Units."}
- {"dt": "2024-12-03T13:03:37.512202", "lvl": "target", "msg": "[  OK  ] Reached target Slice Units."}
- {"dt": "2024-12-03T13:03:37.512444", "lvl": "target", "msg": "[   16.192676] systemd[1]: Reached target Swaps."}
- {"dt": "2024-12-03T13:03:37.513411", "lvl": "target", "msg": "[  OK  ] Reached target Swaps."}
- {"dt": "2024-12-03T13:03:37.585158", "lvl": "target", "msg": "[   16.211233] systemd[1]: Listening on Process Core Dump Socket."}
- {"dt": "2024-12-03T13:03:37.585698", "lvl": "target", "msg": "[  OK  ] Listening on Process Core Dump Socket."}
- {"dt": "2024-12-03T13:03:37.586001", "lvl": "target", "msg": "[   16.228726] systemd[1]: Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-12-03T13:03:37.586276", "lvl": "target", "msg": "[  OK  ] Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-12-03T13:03:37.586530", "lvl": "target", "msg": "[   16.248852] systemd[1]: Listening on Journal Audit Socket."}
- {"dt": "2024-12-03T13:03:37.586778", "lvl": "target", "msg": "[  OK  ] Listening on Journal Audit Socket."}
- {"dt": "2024-12-03T13:03:37.587019", "lvl": "target", "msg": "[   16.264758] systemd[1]: Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-12-03T13:03:37.588438", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-12-03T13:03:37.658180", "lvl": "target", "msg": "[   16.284769] systemd[1]: Listening on Journal Socket."}
- {"dt": "2024-12-03T13:03:37.658683", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket."}
- {"dt": "2024-12-03T13:03:37.658978", "lvl": "target", "msg": "[   16.300794] systemd[1]: Listening on Network Service Netlink Socket."}
- {"dt": "2024-12-03T13:03:37.659245", "lvl": "target", "msg": "[  OK  ] Listening on Network Service Netlink Socket."}
- {"dt": "2024-12-03T13:03:37.659495", "lvl": "target", "msg": "[   16.320772] systemd[1]: Listening on udev Control Socket."}
- {"dt": "2024-12-03T13:03:37.659734", "lvl": "target", "msg": "[  OK  ] Listening on udev Control Socket."}
- {"dt": "2024-12-03T13:03:37.659969", "lvl": "target", "msg": "[   16.336738] systemd[1]: Listening on udev Kernel Socket."}
- {"dt": "2024-12-03T13:03:37.660222", "lvl": "target", "msg": "[  OK  ] Listening on udev Kernel Socket."}
- {"dt": "2024-12-03T13:03:37.661259", "lvl": "target", "msg": "[   16.352743] systemd[1]: Listening on User Database Manager Socket."}
- {"dt": "2024-12-03T13:03:37.698450", "lvl": "target", "msg": "[  OK  ] Listening on User Database Manager Socket."}
- {"dt": "2024-12-03T13:03:37.701500", "lvl": "target", "msg": "[   16.372765] systemd[1]: Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages)."}
- {"dt": "2024-12-03T13:03:37.773476", "lvl": "target", "msg": "[   16.408735] systemd[1]: Mounting POSIX Message Queue File System..."}
- {"dt": "2024-12-03T13:03:37.773967", "lvl": "target", "msg": "         Mounting POSIX Message Queue File System..."}
- {"dt": "2024-12-03T13:03:37.774264", "lvl": "target", "msg": "[   16.429280] systemd[1]: Mounting Kernel Debug File System..."}
- {"dt": "2024-12-03T13:03:37.774528", "lvl": "target", "msg": "         Mounting Kernel Debug Fi[   16.435637] systemd[1]: Mounting Kernel Trace File System..."}
- {"dt": "2024-12-03T13:03:37.774777", "lvl": "target", "msg": "le System..."}
- {"dt": "2024-12-03T13:03:37.775019", "lvl": "target", "msg": "         Mounting Kernel Trace File System..."}
- {"dt": "2024-12-03T13:03:37.775261", "lvl": "target", "msg": "[   16.454831] systemd[1]: Mounting Temporary Directory /tmp..."}
- {"dt": "2024-12-03T13:03:37.828450", "lvl": "target", "msg": "         Mounting Temporary Direc[   16.469840] systemd[1]: Starting Create List of Static Device Nodes..."}
- {"dt": "2024-12-03T13:03:37.829002", "lvl": "target", "msg": "tory /tmp..."}
- {"dt": "2024-12-03T13:03:37.829298", "lvl": "target", "msg": "         Starting Create List of [   16.489690] systemd[1]: Starting Load Kernel Module configfs..."}
- {"dt": "2024-12-03T13:03:37.829565", "lvl": "target", "msg": "Static Device Nodes..."}
- {"dt": "2024-12-03T13:03:37.831629", "lvl": "target", "msg": "         Starting Load Kernel Mod[   16.509544] systemd[1]: Starting Load Kernel Module drm..."}
- {"dt": "2024-12-03T13:03:37.832027", "lvl": "target", "msg": "ule configfs..."}
- {"dt": "2024-12-03T13:03:37.852570", "lvl": "target", "msg": "         Starting Load Kernel Mod[   16.529626] systemd[1]: Starting Load Kernel Module fuse..."}
- {"dt": "2024-12-03T13:03:37.853124", "lvl": "target", "msg": "ule drm..."}
- {"dt": "2024-12-03T13:03:37.873438", "lvl": "target", "msg": "         Starting Load Kernel Mod[   16.549598] systemd[1]: Starting SELinux autorelabel service loading..."}
- {"dt": "2024-12-03T13:03:37.873978", "lvl": "target", "msg": "ule fuse..."}
- {"dt": "2024-12-03T13:03:37.897179", "lvl": "target", "msg": "         Starting SELinux autorel[   16.573569] systemd[1]: Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-03T13:03:37.900295", "lvl": "target", "msg": "abel service loading..."}
- {"dt": "2024-12-03T13:03:37.900689", "lvl": "target", "msg": "[   16.590408] fuse: init (API version 7.39)"}
- {"dt": "2024-12-03T13:03:37.915998", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-03T13:03:37.951862", "lvl": "target", "msg": "[   16.620741] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/)."}
- {"dt": "2024-12-03T13:03:37.955019", "lvl": "target", "msg": "[   16.634677] systemd[1]: Starting Journal Service..."}
- {"dt": "2024-12-03T13:03:37.955497", "lvl": "target", "msg": "         Starting Journal Service..."}
- {"dt": "2024-12-03T13:03:38.082580", "lvl": "target", "msg": "[   16.732831] systemd[1]: Starting Load Kernel Modules..."}
- {"dt": "2024-12-03T13:03:38.083119", "lvl": "target", "msg": "         Starting Load Kernel Modules..."}
- {"dt": "2024-12-03T13:03:38.083430", "lvl": "target", "msg": "[   16.749579] systemd[1]: Starting Remount Root and Kernel File Systems..."}
- {"dt": "2024-12-03T13:03:38.083702", "lvl": "target", "msg": "         Starting Remount Root an[   16.756642] systemd-journald[195]: Collecting audit messages is enabled."}
- {"dt": "2024-12-03T13:03:38.085927", "lvl": "target", "msg": "d Kernel File Systems...[   16.758107] systemd[1]: Starting Coldplug All udev Devices..."}
- {"dt": "2024-12-03T13:03:38.086363", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:03:38.137501", "lvl": "target", "msg": "         Starting Coldplug All udev Devic[   16.786336] systemd[1]: Mounted POSIX Message Queue File System."}
- {"dt": "2024-12-03T13:03:38.138015", "lvl": "target", "msg": "es..."}
- {"dt": "2024-12-03T13:03:38.138326", "lvl": "target", "msg": "[  OK  ] Mounted POSIX Message Queue File System."}
- {"dt": "2024-12-03T13:03:38.138601", "lvl": "target", "msg": "[   16.816850] systemd[1]: Started Journal Service."}
- {"dt": "2024-12-03T13:03:38.140608", "lvl": "target", "msg": "[  OK  ] Started Journal Service."}
- {"dt": "2024-12-03T13:03:38.187451", "lvl": "target", "msg": "[   16.846115] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-12-03T13:03:38.187944", "lvl": "target", "msg": "[   16.856310] audit: type=1130 audit(1725908514.823:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.188261", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Debug Fil[   16.875273] snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored"}
- {"dt": "2024-12-03T13:03:38.206322", "lvl": "target", "msg": "[   16.876786] EXT4-fs (nbd0): re-mounted 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w. Quota mode: none."}
- {"dt": "2024-12-03T13:03:38.206790", "lvl": "target", "msg": "e System."}
- {"dt": "2024-12-03T13:03:38.245466", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Trace File System."}
- {"dt": "2024-12-03T13:03:38.245979", "lvl": "target", "msg": "[  OK  ] Mounted Temporary Directory /tmp."}
- {"dt": "2024-12-03T13:03:38.248561", "lvl": "target", "msg": "[  OK  ] Finished Create List of Static Device Nodes."}
- {"dt": "2024-12-03T13:03:38.297027", "lvl": "target", "msg": "[   16.945333] audit: type=1130 audit(1725908514.911:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kmod-static-nodes comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.297571", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module configfs."}
- {"dt": "2024-12-03T13:03:38.300446", "lvl": "target", "msg": "[   16.976722] audit: type=1130 audit(1725908514.943:4): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.328317", "lvl": "target", "msg": "[   16.995681] audit: type=1131 audit(1725908514.943:5): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.331719", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module drm."}
- {"dt": "2024-12-03T13:03:38.378744", "lvl": "target", "msg": "[   17.028709] audit: type=1130 audit(1725908514.995:6): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.379278", "lvl": "target", "msg": "[   17.047222] audit: type=1131 audit(1725908514.995:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.381902", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module fuse."}
- {"dt": "2024-12-03T13:03:38.431799", "lvl": "target", "msg": "[   17.076694] audit: type=1130 audit(1725908515.043:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@fuse comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.432334", "lvl": "target", "msg": "[   17.095292] audit: type=1131 audit(1725908515.043:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@fuse comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.432647", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-12-03T13:03:38.484955", "lvl": "target", "msg": "[   17.128685] audit: type=1130 audit(1725908515.095:10): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=selinux-autorelabel comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.485487", "lvl": "target", "msg": "[   17.147894] audit: type=1131 audit(1725908515.095:11): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=selinux-autorelabel comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-03T13:03:38.485798", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-12-03T13:03:38.488246", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Modules."}
- {"dt": "2024-12-03T13:03:38.504212", "lvl": "target", "msg": "[  OK  ] Finished Remount Root and Kernel File Systems."}
- {"dt": "2024-12-03T13:03:38.520121", "lvl": "target", "msg": "[  OK  ] Finished Coldplug All udev Devices."}
- {"dt": "2024-12-03T13:03:38.568043", "lvl": "target", "msg": "         Mounting FUSE Control File System..."}
- {"dt": "2024-12-03T13:03:38.584029", "lvl": "target", "msg": "         Mounting Kernel Configuration File System..."}
- {"dt": "2024-12-03T13:03:38.600023", "lvl": "target", "msg": "         Starting Flush Journal to Persistent Storage..."}
- {"dt": "2024-12-03T13:03:38.615504", "lvl": "target", "msg": "         Starting Apply Kernel Variables..."}
- {"dt": "2024-12-03T13:03:38.687282", "lvl": "target", "msg": "[   17.316265] systemd-journald[195]: Received client request to flush runtime journal."}
- {"dt": "2024-12-03T13:03:38.687799", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev gracefully..."}
- {"dt": "2024-12-03T13:03:38.688107", "lvl": "target", "msg": "[  OK  ] Mounted FUSE Control File System."}
- {"dt": "2024-12-03T13:03:38.688378", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Configuration File System."}
- {"dt": "2024-12-03T13:03:38.688633", "lvl": "target", "msg": "[  OK  ] Finished Flush Journal to Persistent Storage."}
- {"dt": "2024-12-03T13:03:38.690496", "lvl": "target", "msg": "[  OK  ] Finished Apply Kernel Variables."}
- {"dt": "2024-12-03T13:03:38.714741", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev gracefully."}
- {"dt": "2024-12-03T13:03:38.730469", "lvl": "target", "msg": "         Starting Create System Users..."}
- {"dt": "2024-12-03T13:03:38.826312", "lvl": "target", "msg": "         Starting User Database Manager..."}
- {"dt": "2024-12-03T13:03:38.842032", "lvl": "target", "msg": "[  OK  ] Started User Database Manager."}
- {"dt": "2024-12-03T13:03:38.858229", "lvl": "target", "msg": "[  OK  ] Finished Create System Users."}
- {"dt": "2024-12-03T13:03:38.931114", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev..."}
- {"dt": "2024-12-03T13:03:38.931637", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev."}
- {"dt": "2024-12-03T13:03:38.950167", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Local File Systems."}
- {"dt": "2024-12-03T13:03:38.982169", "lvl": "target", "msg": "         Mounting /var/volatile..."}
- {"dt": "2024-12-03T13:03:39.010436", "lvl": "target", "msg": "         Starting Rule-based Manager for Device Events and Files..."}
- {"dt": "2024-12-03T13:03:39.026346", "lvl": "target", "msg": "[  OK  ] Mounted /var/volatile."}
- {"dt": "2024-12-03T13:03:39.065182", "lvl": "target", "msg": "         Starting Load/Save OS Random Seed..."}
- {"dt": "2024-12-03T13:03:39.065363", "lvl": "target", "msg": "[  OK  ] Reached target Local File Systems."}
- {"dt": "2024-12-03T13:03:39.068241", "lvl": "target", "msg": "         Starting Rebuild Dynamic Linker Cache..."}
- {"dt": "2024-12-03T13:03:39.084218", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-12-03T13:03:39.100200", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-12-03T13:03:39.156001", "lvl": "target", "msg": "         Starting Create Volatile Files and Directories..."}
- {"dt": "2024-12-03T13:03:39.156184", "lvl": "target", "msg": "[  OK  ] Finished Load/Save OS Random Seed."}
- {"dt": "2024-12-03T13:03:39.159064", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-12-03T13:03:39.159201", "lvl": "target", "msg": "         Starting Generated file SELinux relabeling..."}
- {"dt": "2024-12-03T13:03:39.191018", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-12-03T13:03:39.222908", "lvl": "target", "msg": "[  OK  ] Finished Create Volatile Files and Directories."}
- {"dt": "2024-12-03T13:03:39.254865", "lvl": "target", "msg": "         Starting Load Audit Rules..."}
- {"dt": "2024-12-03T13:03:39.270782", "lvl": "target", "msg": "         Starting Rebuild Journal Catalog..."}
- {"dt": "2024-12-03T13:03:39.494766", "lvl": "target", "msg": "[  OK  ] Finished Generated file SELinux relabeling."}
- {"dt": "2024-12-03T13:03:39.558527", "lvl": "target", "msg": "[  OK  ] Finished Rebuild Journal Catalog."}
- {"dt": "2024-12-03T13:03:39.654252", "lvl": "target", "msg": "[  OK  ] Started Rule-based Manager for Device Events and Files."}
- {"dt": "2024-12-03T13:03:39.654876", "lvl": "target", "msg": "[  OK  ] Finished Load Audit Rules."}
- {"dt": "2024-12-03T13:03:39.718190", "lvl": "target", "msg": "         Starting Security Audit Logging Service..."}
- {"dt": "2024-12-03T13:03:39.813974", "lvl": "target", "msg": "         Starting Virtual Console Setup..."}
- {"dt": "2024-12-03T13:03:39.835083", "lvl": "target", "msg": "[   18.513485] input: pwr_button as /devices/platform/pwr_button/input/input0"}
- {"dt": "2024-12-03T13:03:39.926789", "lvl": "target", "msg": "[   18.585615] rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040"}
- {"dt": "2024-12-03T13:03:39.927275", "lvl": "target", "msg": "[   18.593468] rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1"}
- {"dt": "2024-12-03T13:03:39.928316", "lvl": "target", "msg": "[   18.601135] rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040"}
- {"dt": "2024-12-03T13:03:39.928625", "lvl": "target", "msg": "[   18.608944] rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2"}
- {"dt": "2024-12-03T13:03:39.928923", "lvl": "target", "msg": "[   18.616563] rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020"}
- {"dt": "2024-12-03T13:03:39.980633", "lvl": "target", "msg": "[   18.624377] rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3"}
- {"dt": "2024-12-03T13:03:39.981168", "lvl": "target", "msg": "[   18.632002] rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020"}
- {"dt": "2024-12-03T13:03:39.981487", "lvl": "target", "msg": "[   18.639808] rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4"}
- {"dt": "2024-12-03T13:03:39.981760", "lvl": "target", "msg": "[   18.648083] rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000"}
- {"dt": "2024-12-03T13:03:39.982027", "lvl": "target", "msg": "[   18.653087] macb 1f00100000.ethernet end0: renamed from eth0 (while UP)"}
- {"dt": "2024-12-03T13:03:39.983843", "lvl": "target", "msg": "[   18.659337] rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0"}
- {"dt": "2024-12-03T13:03:39.999689", "lvl": "target", "msg": "[   18.692515] mc: Linux media interface: v0.10"}
- {"dt": "2024-12-03T13:03:40.047502", "lvl": "target", "msg": "[  OK  ] Started Security Audit Logging Service."}
- {"dt": "2024-12-03T13:03:40.063585", "lvl": "target", "msg": "         Starting Record System Boot/Shutdown in UTMP..."}
- {"dt": "2024-12-03T13:03:40.166274", "lvl": "target", "msg": "[  OK  ] Finished Record System Boot/Shutdown in UTMP."}
- {"dt": "2024-12-03T13:03:40.169359", "lvl": "target", "msg": "[   18.852858] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-12-03T13:03:40.256122", "lvl": "target", "msg": "[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch."}
- {"dt": "2024-12-03T13:03:40.330221", "lvl": "target", "msg": "[   18.984255] pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-12-03T13:03:40.330719", "lvl": "target", "msg": "[   18.985368] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-12-03T13:03:40.331037", "lvl": "target", "msg": "[   18.992091] pispbe 1000880000.pisp_be: pispbe_probe: HW version:  0x02252700"}
- {"dt": "2024-12-03T13:03:40.331314", "lvl": "target", "msg": "[   19.010590] Bluetooth: Core ver 2.22"}
- {"dt": "2024-12-03T13:03:40.331587", "lvl": "target", "msg": "[   19.010596] rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-12-03T13:03:40.331847", "lvl": "target", "msg": "[   19.014342] NET: Registered PF_BLUETOOTH protocol family"}
- {"dt": "2024-12-03T13:03:40.374089", "lvl": "target", "msg": "[   19.016670] pispbe 1000880000.pisp_be: pispbe_probe: BatchStatus: 0x00000000"}
- {"dt": "2024-12-03T13:03:40.374629", "lvl": "target", "msg": "[   19.016674] pispbe 1000880000.pisp_be: pispbe_probe: Status:      0x00000000"}
- {"dt": "2024-12-03T13:03:40.374939", "lvl": "target", "msg": "[   19.016676] pispbe 1000880000.pisp_be: Register nodes for group 0"}
- {"dt": "2024-12-03T13:03:40.375206", "lvl": "target", "msg": "[   19.021923] pispbe 1000880000.pisp_be: input device node registered as /dev/video20"}
- {"dt": "2024-12-03T13:03:40.375463", "lvl": "target", "msg": "[   19.027395] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2024-12-03T13:03:40.375713", "lvl": "target", "msg": "[   19.035096] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video21"}
- {"dt": "2024-12-03T13:03:40.377285", "lvl": "target", "msg": "[   19.041806] Bluetooth: HCI socket layer initialized"}
- {"dt": "2024-12-03T13:03:40.417454", "lvl": "target", "msg": "[   19.047918] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video22"}
- {"dt": "2024-12-03T13:03:40.417942", "lvl": "target", "msg": "[   19.055603] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2024-12-03T13:03:40.418249", "lvl": "target", "msg": "[   19.057997] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2024-12-03T13:03:40.418523", "lvl": "target", "msg": "[   19.060594] Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'"}
- {"dt": "2024-12-03T13:03:40.418780", "lvl": "target", "msg": "[   19.060799] Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'"}
- {"dt": "2024-12-03T13:03:40.420674", "lvl": "target", "msg": "[   19.060990] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2024-12-03T13:03:40.461527", "lvl": "target", "msg": "[   19.061178] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'"}
- {"dt": "2024-12-03T13:03:40.462096", "lvl": "target", "msg": "[   19.062058] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video23"}
- {"dt": "2024-12-03T13:03:40.462421", "lvl": "target", "msg": "[   19.070093] Bluetooth: SCO socket layer initialized"}
- {"dt": "2024-12-03T13:03:40.462692", "lvl": "target", "msg": "[   19.074846] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video24"}
- {"dt": "2024-12-03T13:03:40.462946", "lvl": "target", "msg": "[   19.074876] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video25"}
- {"dt": "2024-12-03T13:03:40.464568", "lvl": "target", "msg": "[   19.074898] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video26"}
- {"dt": "2024-12-03T13:03:40.504918", "lvl": "target", "msg": "[   19.074922] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video27"}
- {"dt": "2024-12-03T13:03:40.505391", "lvl": "target", "msg": "[   19.083901] rpivid 1000800000.codec: Device registered as /dev/video19"}
- {"dt": "2024-12-03T13:03:40.505699", "lvl": "target", "msg": "[   19.088415] pispbe 1000880000.pisp_be: config device node registered as /dev/video28"}
- {"dt": "2024-12-03T13:03:40.505975", "lvl": "target", "msg": "[   19.092697] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2"}
- {"dt": "2024-12-03T13:03:40.506232", "lvl": "target", "msg": "[   19.092702] cfg80211: failed to load regulatory.db"}
- {"dt": "2024-12-03T13:03:40.508267", "lvl": "target", "msg": "[   19.126738] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2024-12-03T13:03:40.548879", "lvl": "target", "msg": "[   19.134638] pispbe 1000880000.pisp_be: Register nodes for group 1"}
- {"dt": "2024-12-03T13:03:40.550096", "lvl": "target", "msg": "[   19.139410] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2024-12-03T13:03:40.550447", "lvl": "target", "msg": "[   19.147278] pispbe 1000880000.pisp_be: input device node registered as /dev/video29"}
- {"dt": "2024-12-03T13:03:40.550730", "lvl": "target", "msg": "[   19.147315] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video30"}
- {"dt": "2024-12-03T13:03:40.550988", "lvl": "target", "msg": "[   19.147356] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video31"}
- {"dt": "2024-12-03T13:03:40.552256", "lvl": "target", "msg": "[   19.147406] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video32"}
- {"dt": "2024-12-03T13:03:40.592988", "lvl": "target", "msg": "[   19.155286] Bluetooth: HCI UART protocol BCSP registered"}
- {"dt": "2024-12-03T13:03:40.593459", "lvl": "target", "msg": "[   19.163374] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video33"}
- {"dt": "2024-12-03T13:03:40.593765", "lvl": "target", "msg": "[   19.163426] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video34"}
- {"dt": "2024-12-03T13:03:40.594031", "lvl": "target", "msg": "[   19.163467] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video35"}
- {"dt": "2024-12-03T13:03:40.594280", "lvl": "target", "msg": "[   19.171941] Bluetooth: HCI UART protocol Three-wire (H5) registered"}
- {"dt": "2024-12-03T13:03:40.596199", "lvl": "target", "msg": "[   19.178432] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video36"}
- {"dt": "2024-12-03T13:03:40.637010", "lvl": "target", "msg": "[   19.178533] pispbe 1000880000.pisp_be: config device node registered as /dev/video37"}
- {"dt": "2024-12-03T13:03:40.637469", "lvl": "target", "msg": "[   19.186504] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator"}
- {"dt": "2024-12-03T13:03:40.637771", "lvl": "target", "msg": "[   19.187100] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2024-12-03T13:03:40.638078", "lvl": "target", "msg": "[   19.314271] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6"}
- {"dt": "2024-12-03T13:03:40.638372", "lvl": "target", "msg": "[   19.323047] usbcore: registered new interface driver brcmfmac"}
- {"dt": "2024-12-03T13:03:40.670354", "lvl": "target", "msg": "[   19.328900] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2"}
- {"dt": "2024-12-03T13:03:40.670842", "lvl": "target", "msg": "[   19.328953] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator"}
- {"dt": "2024-12-03T13:03:40.673517", "lvl": "target", "msg": "[  OK  ] Finished Virtual Console Setup."}
- {"dt": "2024-12-03T13:03:40.940653", "lvl": "target", "msg": "[   19.588896] brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2)"}
- {"dt": "2024-12-03T13:03:40.941445", "lvl": "target", "msg": "[   19.599810] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b"}
- {"dt": "2024-12-03T13:03:40.942740", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-12-03T13:03:40.944125", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-12-03T13:03:40.959690", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/bthelper."}
- {"dt": "2024-12-03T13:03:40.991603", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-12-03T13:03:41.054368", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2024-12-03T13:03:41.054899", "lvl": "target", "msg": "[   19.697104] Bluetooth: hci0: BCM: chip id 107"}
- {"dt": "2024-12-03T13:03:41.055300", "lvl": "target", "msg": "[   19.703160] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-12-03T13:03:41.055630", "lvl": "target", "msg": "[   19.708834] Bluetooth: hci0: BCM4345C0"}
- {"dt": "2024-12-03T13:03:41.056854", "lvl": "target", "msg": "[   19.712590] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000"}
- {"dt": "2024-12-03T13:03:41.057236", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-12-03T13:03:41.057561", "lvl": "target", "msg": "[   19.727918] Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch"}
- {"dt": "2024-12-03T13:03:41.057975", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-03T13:03:41.094311", "lvl": "target", "msg": "         Starting Network Name Resolution..."}
- {"dt": "2024-12-03T13:03:41.094788", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-12-03T13:03:41.097532", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-12-03T13:03:41.097935", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-12-03T13:03:41.113437", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-12-03T13:03:41.257096", "lvl": "target", "msg": "[  OK  ] Started Network Name Resolution."}
- {"dt": "2024-12-03T13:03:41.758054", "lvl": "target", "msg": "[   20.421289] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-12-03T13:03:41.758725", "lvl": "target", "msg": "[   20.427302] Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190"}
- {"dt": "2024-12-03T13:03:41.759102", "lvl": "target", "msg": "[   20.433537] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382"}
- {"dt": "2024-12-03T13:03:41.761233", "lvl": "target", "msg": "[   20.439808] Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac)"}
- {"dt": "2024-12-03T13:03:43.528674", "lvl": "target", "msg": "[*     ] (1 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (6s / 1min 30s)"}
- {"dt": "2024-12-03T13:03:44.112245", "lvl": "target", "msg": "M[K[**    ] (1 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (6s / 1min 30s)"}
- {"dt": "2024-12-03T13:03:44.694916", "lvl": "target", "msg": "M[K[***   ] (1 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (7s / 1min 30s)"}
- {"dt": "2024-12-03T13:03:45.278635", "lvl": "target", "msg": "M[K[ ***  ] (2 of 2) A start job is running for\u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2024-12-03T13:03:45.861522", "lvl": "target", "msg": "M[K[  *** ] (2 of 2) A start job is running for\u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2024-12-03T13:03:45.909640", "lvl": "target", "msg": "M[K[  OK  ] Finished Rebuild Dynamic Linker Cache."}
- {"dt": "2024-12-03T13:03:45.957481", "lvl": "target", "msg": "[K         Starting Update is Completed..."}
- {"dt": "2024-12-03T13:03:45.973482", "lvl": "target", "msg": "[  OK  ] Finished Update is Completed."}
- {"dt": "2024-12-03T13:03:46.039272", "lvl": "target", "msg": "[  OK  ] Reached target System Initialization."}
- {"dt": "2024-12-03T13:03:46.039804", "lvl": "target", "msg": "[  OK  ] Started Daily Cleanup of Temporary Directories."}
- {"dt": "2024-12-03T13:03:46.040128", "lvl": "target", "msg": "[  OK  ] Reached target Timer Units."}
- {"dt": "2024-12-03T13:03:46.041194", "lvl": "target", "msg": "[  OK  ] Listening on agl-compositor, a Wayl\u2026ositor, as a systemd user service."}
- {"dt": "2024-12-03T13:03:46.042555", "lvl": "target", "msg": "[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket."}
- {"dt": "2024-12-03T13:03:46.058310", "lvl": "target", "msg": "[  OK  ] Listening on D-Bus System Message Bus Socket."}
- {"dt": "2024-12-03T13:03:46.095154", "lvl": "target", "msg": "[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets."}
- {"dt": "2024-12-03T13:03:46.095673", "lvl": "target", "msg": "[  OK  ] Listening on mpd.socket."}
- {"dt": "2024-12-03T13:03:46.139010", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Manager Socket..."}
- {"dt": "2024-12-03T13:03:46.142336", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Socket..."}
- {"dt": "2024-12-03T13:03:46.142872", "lvl": "target", "msg": "         Starting sshd.socket..."}
- {"dt": "2024-12-03T13:03:46.182949", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Manager Socket."}
- {"dt": "2024-12-03T13:03:46.183485", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Socket."}
- {"dt": "2024-12-03T13:03:46.186143", "lvl": "target", "msg": "[  OK  ] Listening on sshd.socket."}
- {"dt": "2024-12-03T13:03:46.202025", "lvl": "target", "msg": "[  OK  ] Reached target Socket Units."}
- {"dt": "2024-12-03T13:03:46.218058", "lvl": "target", "msg": "[  OK  ] Reached target Basic System."}
- {"dt": "2024-12-03T13:03:46.249984", "lvl": "target", "msg": "         Starting CAN interface helper..."}
- {"dt": "2024-12-03T13:03:46.265623", "lvl": "target", "msg": "         Starting D-Bus System Message Bus..."}
- {"dt": "2024-12-03T13:03:46.281535", "lvl": "target", "msg": "         Starting Raspberry Pi bluetooth helper..."}
- {"dt": "2024-12-03T13:03:46.297501", "lvl": "target", "msg": "         Starting IPv6 Packet Filtering Framework..."}
- {"dt": "2024-12-03T13:03:46.313470", "lvl": "target", "msg": "         Starting IPv4 Packet Filtering Framework..."}
- {"dt": "2024-12-03T13:03:46.329430", "lvl": "target", "msg": "         Starting Telephony service..."}
- {"dt": "2024-12-03T13:03:46.345520", "lvl": "target", "msg": "[  OK  ] Started PipeWire Multimedia Service."}
- {"dt": "2024-12-03T13:03:46.361349", "lvl": "target", "msg": "         Starting Authorization Manager..."}
- {"dt": "2024-12-03T13:03:46.377354", "lvl": "target", "msg": "         Starting User Login Management..."}
- {"dt": "2024-12-03T13:03:46.405290", "lvl": "target", "msg": "         Starting Disk Manager..."}
- {"dt": "2024-12-03T13:03:46.408471", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (audio)."}
- {"dt": "2024-12-03T13:03:46.436098", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (bluetooth)."}
- {"dt": "2024-12-03T13:03:46.439375", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (policy)."}
- {"dt": "2024-12-03T13:03:46.460397", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (video-capture)."}
- {"dt": "2024-12-03T13:03:46.461105", "lvl": "target", "msg": "         Starting OpenSSH Key Generation..."}
- {"dt": "2024-12-03T13:03:46.476184", "lvl": "target", "msg": "[  OK  ] Finished IPv6 Packet Filtering Framework."}
- {"dt": "2024-12-03T13:03:46.492257", "lvl": "target", "msg": "[  OK  ] Finished IPv4 Packet Filtering Framework."}
- {"dt": "2024-12-03T13:03:46.525982", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Network."}
- {"dt": "2024-12-03T13:03:46.526589", "lvl": "target", "msg": "[   25.197748] CAN device driver interface"}
- {"dt": "2024-12-03T13:03:46.529225", "lvl": "target", "msg": "         Starting Network Configuration..."}
- {"dt": "2024-12-03T13:03:46.554120", "lvl": "target", "msg": "[  OK  ] Finished Raspberry Pi bluetooth [   25.234209] vcan: Virtual CAN interface driver"}
- {"dt": "2024-12-03T13:03:46.554583", "lvl": "target", "msg": "helper."}
- {"dt": "2024-12-03T13:03:46.570127", "lvl": "target", "msg": "[  OK  ] Finished CAN interface helper."}
- {"dt": "2024-12-03T13:03:46.585939", "lvl": "target", "msg": "         Starting Bluetooth service..."}
- {"dt": "2024-12-03T13:03:46.617904", "lvl": "target", "msg": "[  OK  ] Started User Login Management."}
- {"dt": "2024-12-03T13:03:47.863444", "lvl": "target", "msg": "[  OK  ] Started Network Configuration."}
- {"dt": "2024-12-03T13:03:48.150767", "lvl": "target", "msg": "[  OK  ] Started D-Bus System Message Bus."}
- {"dt": "2024-12-03T13:03:48.182752", "lvl": "target", "msg": "         Starting Connection service..."}
- {"dt": "2024-12-03T13:03:48.198777", "lvl": "target", "msg": "[  OK  ] Started Telephony service."}
- {"dt": "2024-12-03T13:03:48.214611", "lvl": "target", "msg": "[  OK  ] Started Bluetooth service."}
- {"dt": "2024-12-03T13:03:48.230615", "lvl": "target", "msg": "[  OK  ] Reached target Bluetooth Support."}
- {"dt": "2024-12-03T13:03:48.404278", "lvl": "target", "msg": "[   27.073976] Bluetooth: BNEP (Ethernet Emulation) ver 1.3"}
- {"dt": "2024-12-03T13:03:48.404846", "lvl": "target", "msg": "[   27.079324] Bluetooth: BNEP filters: protocol multicast"}
- {"dt": "2024-12-03T13:03:48.405172", "lvl": "target", "msg": "[   27.084573] Bluetooth: BNEP socket layer initialized"}
- {"dt": "2024-12-03T13:03:48.407431", "lvl": "target", "msg": "[   27.090584] Bluetooth: MGMT ver 1.22"}
- {"dt": "2024-12-03T13:03:48.487217", "lvl": "target", "msg": "         Starting Hostname Service..."}
- {"dt": "2024-12-03T13:03:48.583064", "lvl": "target", "msg": "[  OK  ] Started Hostname Service."}
- {"dt": "2024-12-03T13:03:48.722603", "lvl": "target", "msg": "[   27.398355] Bluetooth: RFCOMM TTY layer initialized"}
- {"dt": "2024-12-03T13:03:48.723336", "lvl": "target", "msg": "[   27.403261] Bluetooth: RFCOMM socket layer initialized"}
- {"dt": "2024-12-03T13:03:48.725779", "lvl": "target", "msg": "[   27.408422] Bluetooth: RFCOMM ver 1.11"}
- {"dt": "2024-12-03T13:03:48.741632", "lvl": "target", "msg": "[  OK  ] Started Connection service."}
- {"dt": "2024-12-03T13:03:48.757575", "lvl": "target", "msg": "[  OK  ] Reached target Network."}
- {"dt": "2024-12-03T13:03:48.789635", "lvl": "target", "msg": "[  OK  ] Started agl-service-radio.service."}
- {"dt": "2024-12-03T13:03:48.819386", "lvl": "target", "msg": "         Starting Avahi mDNS/DNS-SD Stack..."}
- {"dt": "2024-12-03T13:03:48.822535", "lvl": "target", "msg": "[   27.499371] pipewire[468]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set"}
- {"dt": "2024-12-03T13:03:48.838631", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val databroker."}
- {"dt": "2024-12-03T13:03:48.854431", "lvl": "target", "msg": "[  OK  ] Started agl-service-audiomixer.service."}
- {"dt": "2024-12-03T13:03:48.870422", "lvl": "target", "msg": "[  OK  ] Started agl-service-hvac.service."}
- {"dt": "2024-12-03T13:03:48.886326", "lvl": "target", "msg": "         Starting agl-vss-helper.service..."}
- {"dt": "2024-12-03T13:03:48.902371", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val DBC feeder."}
- {"dt": "2024-12-03T13:03:48.918301", "lvl": "target", "msg": "         Starting Permit User Sessions..."}
- {"dt": "2024-12-03T13:03:48.998155", "lvl": "target", "msg": "[  OK  ] Finished Permit User Sessions."}
- {"dt": "2024-12-03T13:03:49.014070", "lvl": "target", "msg": "         Starting AGL compositor..."}
- {"dt": "2024-12-03T13:03:49.030047", "lvl": "target", "msg": "[  OK  ] Started Getty on tty1."}
- {"dt": "2024-12-03T13:03:49.046130", "lvl": "target", "msg": "[  OK  ] Started Serial Getty on ttyAMA10."}
- {"dt": "2024-12-03T13:03:49.062005", "lvl": "target", "msg": "[  OK  ] Reached target Login Prompts."}
- {"dt": "2024-12-03T13:03:49.078010", "lvl": "target", "msg": "[  OK  ] Started Avahi mDNS/DNS-SD Stack."}
- {"dt": "2024-12-03T13:03:49.636965", "lvl": "target", "msg": "         Starting WPA supplicant..."}
- {"dt": "2024-12-03T13:03:50.131968", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-12-03T13:03:50.147838", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-12-03T13:03:51.249624", "lvl": "target", "msg": "[  OK  ] Started WPA supplicant."}
- {"dt": "2024-12-03T13:03:51.308329", "lvl": "target", "msg": "[  OK  ] Created slice User Slice of UID 1001."}
- {"dt": "2024-12-03T13:03:51.311504", "lvl": "target", "msg": "         Starting User Runtime Directory /run/user/1001..."}
- {"dt": "2024-12-03T13:03:51.471045", "lvl": "target", "msg": "[  OK  ] Finished User Runtime Directory /run/user/1001."}
- {"dt": "2024-12-03T13:03:51.503035", "lvl": "target", "msg": "         Starting User Manager for UID 1001..."}
- {"dt": "2024-12-03T13:03:51.778552", "lvl": "target", "msg": "[   30.460149] can: controller area network core"}
- {"dt": "2024-12-03T13:03:51.781579", "lvl": "target", "msg": "[   30.464571] NET: Registered PF_CAN protocol family"}
- {"dt": "2024-12-03T13:03:51.813461", "lvl": "target", "msg": "[   30.497100] can: raw protocol"}
- {"dt": "2024-12-03T13:03:51.829495", "lvl": "target", "msg": "[  OK  ] Started Authorization Manager."}
- {"dt": "2024-12-03T13:03:51.861334", "lvl": "target", "msg": "         Starting applaunchd.service..."}
- {"dt": "2024-12-03T13:03:51.925280", "lvl": "target", "msg": "[  OK  ] Started Disk Manager."}
- {"dt": "2024-12-03T13:03:51.957190", "lvl": "target", "msg": "[  OK  ] Started Automount Disk Manager."}
- {"dt": "2024-12-03T13:03:51.973188", "lvl": "target", "msg": "[  OK  ] Finished OpenSSH Key Generation."}
- {"dt": "2024-12-03T13:03:52.484018", "lvl": "target", "msg": "[  OK  ] Started User Manager for UID 1001."}
- {"dt": "2024-12-03T13:03:52.659669", "lvl": "target", "msg": "[  OK  ] Started Session c1 of User agl-driver."}
- {"dt": "2024-12-03T13:03:52.851162", "lvl": "target", "msg": "[  OK  ] Started applaunchd.service."}
- {"dt": "2024-12-03T13:03:53.010880", "lvl": "target", "msg": "[FAILED] Failed to start AGL compositor."}
- {"dt": "2024-12-03T13:03:53.026812", "lvl": "target", "msg": "See 'systemctl status agl-compositor.service' for details."}
- {"dt": "2024-12-03T13:03:53.042729", "lvl": "target", "msg": "[DEPEND] Dependency failed for homescreen.service."}
- {"dt": "2024-12-03T13:03:53.058681", "lvl": "target", "msg": "[DEPEND] Dependency failed for launcher.service."}
- {"dt": "2024-12-03T13:03:55.127454", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:03:55.128003", "lvl": "target", "msg": "Automotive Grade Linux 18.0.2 raspberrypi5 ttyAMA10"}
- {"dt": "2024-12-03T13:03:55.128319", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:03:55.131660", "lvl": "debug", "msg": "Matched prompt #12: login:"}
- {"dt": "2024-12-03T13:03:55.132153", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "33.78", "extra": {"extra": []}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:03:55.132786", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2024-12-03T13:03:55.133116", "lvl": "input", "msg": "root\n"}
- {"dt": "2024-12-03T13:03:55.233837", "lvl": "target", "msg": "raspberrypi5 login: root"}
- {"dt": "2024-12-03T13:03:55.234483", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-03T13:03:55.234931", "lvl": "debug", "msg": "login-action: Wait for prompt ['raspberrypi5:~#', '/ #', 'Login incorrect', 'Login timed out'] (timeout 00:07:05)"}
- {"dt": "2024-12-03T13:03:55.242302", "lvl": "target", "msg": "root"}
- {"dt": "2024-12-03T13:03:58.516109", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-03T13:03:58.516805", "lvl": "debug", "msg": "end: 2.3.1 login-action (duration 00:00:37) [common]"}
- {"dt": "2024-12-03T13:03:58.517165", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "37.17", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:03:58.517785", "lvl": "debug", "msg": "end: 2.3 auto-login-action (duration 00:01:47) [common]"}
- {"dt": "2024-12-03T13:03:58.518192", "lvl": "debug", "msg": "start: 2.4 expect-shell-connection (timeout 00:07:02) [common]"}
- {"dt": "2024-12-03T13:03:58.518518", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-03T13:03:58.518793", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-03T13:03:58.519076", "lvl": "input", "msg": "\n"}
- {"dt": "2024-12-03T13:03:58.569637", "lvl": "target", "msg": "7[r[999;999H[6nroot@raspberrypi5:~# "}
- {"dt": "2024-12-03T13:03:58.570440", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:20:00)"}
- {"dt": "2024-12-03T13:03:58.570954", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2024-12-03T13:03:58.579645", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:03:58.596061", "lvl": "debug", "msg": "end: 2.4 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:03:58.596685", "lvl": "debug", "msg": "start: 2.5 overlay-unpack (timeout 00:07:02) [common]"}
- {"dt": "2024-12-03T13:03:58.597151", "lvl": "input", "msg": "rm overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-03T13:03:58.698072", "lvl": "target", "msg": "root@raspberrypi5:~# rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-03T13:03:58.707481", "lvl": "target", "msg": "rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-03T13:03:58.735582", "lvl": "target", "msg": "rm: cannot remove 'overlay-1.5.5.tar.gz': No such file or directory"}
- {"dt": "2024-12-03T13:03:58.736440", "lvl": "input", "msg": "wget http://192.168.66.1/tmp/38078/compress-overlay-gn0hca30/overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-03T13:03:58.837445", "lvl": "target", "msg": "root@raspberrypi5:~# wget http://192.168.66.1/tmp/38078/compress-overlay-gn0hca30/overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-03T13:03:58.846121", "lvl": "target", "msg": "wget http://192.168.66.1/tmp/38078/compress-overlay-gn0hca300/overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-03T13:03:58.889141", "lvl": "target", "msg": "Connecting to 192.168.66.1 (192.168.66.1:80)"}
- {"dt": "2024-12-03T13:03:58.889622", "lvl": "target", "msg": "saving to 'overlay-1.5.5.tar.gz'"}
- {"dt": "2024-12-03T13:03:58.889924", "lvl": "target", "msg": "overlay-1.5.5.tar.gz 100% |********************************|  8010  0:00:00 ETA"}
- {"dt": "2024-12-03T13:03:58.890197", "lvl": "target", "msg": "'overlay-1.5.5.tar.gz' saved"}
- {"dt": "2024-12-03T13:03:58.890703", "lvl": "input", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-03T13:03:58.991667", "lvl": "target", "msg": "root@raspberrypi5:~# tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-03T13:03:58.999854", "lvl": "target", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-03T13:03:59.063381", "lvl": "target", "msg": "x lava-38078/"}
- {"dt": "2024-12-03T13:03:59.063919", "lvl": "target", "msg": "x lava-38078/0/"}
- {"dt": "2024-12-03T13:03:59.103819", "lvl": "target", "msg": "x lava-38078/0/lava-test-runner.conf"}
- {"dt": "2024-12-03T13:03:59.104355", "lvl": "target", "msg": "x lava-38078/0/tests/"}
- {"dt": "2024-12-03T13:03:59.104657", "lvl": "target", "msg": "x lava-38078/0/tests/0_dmesg/"}
- {"dt": "2024-12-03T13:03:59.104999", "lvl": "target", "msg": "x lava-38078/0/tests/0_dmesg/inline/"}
- {"dt": "2024-12-03T13:03:59.105260", "lvl": "target", "msg": "x lava-38078/0/tests/0_dmesg/inline/dmesg.yaml"}
- {"dt": "2024-12-03T13:03:59.105508", "lvl": "target", "msg": "x lava-38078/0/tests/0_dmesg/run.sh"}
- {"dt": "2024-12-03T13:03:59.105746", "lvl": "target", "msg": "x lava-38078/0/tests/0_dmesg/testdef.yaml"}
- {"dt": "2024-12-03T13:03:59.105983", "lvl": "target", "msg": "x lava-38078/0/tests/0_dmesg/testdef_metadata"}
- {"dt": "2024-12-03T13:03:59.106218", "lvl": "target", "msg": "x lava-38078/0/tests/0_dmesg/uuid"}
- {"dt": "2024-12-03T13:03:59.106454", "lvl": "target", "msg": "x lava-38078/1/"}
- {"dt": "2024-12-03T13:03:59.106687", "lvl": "target", "msg": "x lava-38078/1/lava-test-runner.conf"}
- {"dt": "2024-12-03T13:03:59.106934", "lvl": "target", "msg": "x lava-38078/1/tests/"}
- {"dt": "2024-12-03T13:03:59.107174", "lvl": "target", "msg": "x lava-38078/1/tests/1_bootrr/"}
- {"dt": "2024-12-03T13:03:59.107692", "lvl": "target", "msg": "x lava-38078/1/tests/1_bootrr/inline/"}
- {"dt": "2024-12-03T13:03:59.147184", "lvl": "target", "msg": "x lava-38078/1/tests/1_bootrr/inline/bootrr.yaml"}
- {"dt": "2024-12-03T13:03:59.147721", "lvl": "target", "msg": "x lava-38078/1/tests/1_bootrr/run.sh"}
- {"dt": "2024-12-03T13:03:59.148029", "lvl": "target", "msg": "x lava-38078/1/tests/1_bootrr/testdef.yaml"}
- {"dt": "2024-12-03T13:03:59.148302", "lvl": "target", "msg": "x lava-38078/1/tests/1_bootrr/testdef_metadata"}
- {"dt": "2024-12-03T13:03:59.148557", "lvl": "target", "msg": "x lava-38078/1/tests/1_bootrr/uuid"}
- {"dt": "2024-12-03T13:03:59.148836", "lvl": "target", "msg": "x lava-38078/bin/"}
- {"dt": "2024-12-03T13:03:59.149086", "lvl": "target", "msg": "x lava-38078/bin/lava-add-keys"}
- {"dt": "2024-12-03T13:03:59.149326", "lvl": "target", "msg": "x lava-38078/bin/lava-add-sources"}
- {"dt": "2024-12-03T13:03:59.149564", "lvl": "target", "msg": "x lava-38078/bin/lava-background-process-start"}
- {"dt": "2024-12-03T13:03:59.149803", "lvl": "target", "msg": "x lava-38078/bin/lava-background-process-stop"}
- {"dt": "2024-12-03T13:03:59.150045", "lvl": "target", "msg": "x lava-38078/bin/lava-common-functions"}
- {"dt": "2024-12-03T13:03:59.150293", "lvl": "target", "msg": "x lava-38078/bin/lava-echo-ipv4"}
- {"dt": "2024-12-03T13:03:59.150817", "lvl": "target", "msg": "x lava-38078/bin/lava-install-packages"}
- {"dt": "2024-12-03T13:03:59.190449", "lvl": "target", "msg": "x lava-38078/bin/lava-installed-packages"}
- {"dt": "2024-12-03T13:03:59.190998", "lvl": "target", "msg": "x lava-38078/bin/lava-os-build"}
- {"dt": "2024-12-03T13:03:59.191303", "lvl": "target", "msg": "x lava-38078/bin/lava-probe-channel"}
- {"dt": "2024-12-03T13:03:59.191573", "lvl": "target", "msg": "x lava-38078/bin/lava-probe-ip"}
- {"dt": "2024-12-03T13:03:59.191829", "lvl": "target", "msg": "x lava-38078/bin/lava-target-ip"}
- {"dt": "2024-12-03T13:03:59.192078", "lvl": "target", "msg": "x lava-38078/bin/lava-target-mac"}
- {"dt": "2024-12-03T13:03:59.192324", "lvl": "target", "msg": "x lava-38078/bin/lava-target-storage"}
- {"dt": "2024-12-03T13:03:59.192564", "lvl": "target", "msg": "x lava-38078/bin/lava-test-case"}
- {"dt": "2024-12-03T13:03:59.192834", "lvl": "target", "msg": "x lava-38078/bin/lava-test-event"}
- {"dt": "2024-12-03T13:03:59.193074", "lvl": "target", "msg": "x lava-38078/bin/lava-test-feedback"}
- {"dt": "2024-12-03T13:03:59.193312", "lvl": "target", "msg": "x lava-38078/bin/lava-test-raise"}
- {"dt": "2024-12-03T13:03:59.193562", "lvl": "target", "msg": "x lava-38078/bin/lava-test-reference"}
- {"dt": "2024-12-03T13:03:59.193799", "lvl": "target", "msg": "x lava-38078/bin/lava-test-runner"}
- {"dt": "2024-12-03T13:03:59.194330", "lvl": "target", "msg": "x lava-38078/bin/lava-test-set"}
- {"dt": "2024-12-03T13:03:59.226171", "lvl": "target", "msg": "x lava-38078/bin/lava-test-shell"}
- {"dt": "2024-12-03T13:03:59.226699", "lvl": "target", "msg": "x lava-38078/environment"}
- {"dt": "2024-12-03T13:03:59.227010", "lvl": "target", "msg": "x lava-38078/lava"}
- {"dt": "2024-12-03T13:03:59.227281", "lvl": "target", "msg": "x lava-38078/lava.pub"}
- {"dt": "2024-12-03T13:03:59.227537", "lvl": "target", "msg": "x lava-38078/results/"}
- {"dt": "2024-12-03T13:03:59.227788", "lvl": "target", "msg": "x lava-38078/tests/"}
- {"dt": "2024-12-03T13:03:59.229614", "lvl": "debug", "msg": "end: 2.5 overlay-unpack (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:03:59.230146", "lvl": "debug", "msg": "start: 2.6 export-device-env (timeout 00:07:01) [common]"}
- {"dt": "2024-12-03T13:03:59.230579", "lvl": "debug", "msg": "end: 2.6 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-12-03T13:03:59.230976", "lvl": "info", "msg": "end: 2 minimal-boot (duration 00:01:55) [common]"}
- {"dt": "2024-12-03T13:03:59.231364", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-12-03T13:03:59.231746", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-12-03T13:03:59.232062", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-12-03T13:03:59.232377", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-12-03T13:03:59.333258", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-12-03T13:03:59.333961", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-12-03T13:03:59.341264", "lvl": "target", "msg": "#"}
- {"dt": "2024-12-03T13:03:59.357558", "lvl": "debug", "msg": "Using /lava-38078"}
- {"dt": "2024-12-03T13:03:59.358072", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-12-03T13:03:59.458926", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh"}
- {"dt": "2024-12-03T13:03:59.468989", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-12-03T13:03:59.485212", "lvl": "input", "msg": ". /lava-38078/environment\n"}
- {"dt": "2024-12-03T13:03:59.586245", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-38078/environment"}
- {"dt": "2024-12-03T13:03:59.596651", "lvl": "target", "msg": ". /lava-38078/environment"}
- {"dt": "2024-12-03T13:03:59.612947", "lvl": "input", "msg": "/lava-38078/bin/lava-test-runner /lava-38078/0\n"}
- {"dt": "2024-12-03T13:03:59.714001", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-38078/bin/lava-test-runner /lava-38078/0"}
- {"dt": "2024-12-03T13:03:59.714704", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-12-03T13:03:59.724417", "lvl": "target", "msg": "/lava-38078/bin/lava-test-runner /lava-38078/0"}
- {"dt": "2024-12-03T13:03:59.755952", "lvl": "target", "msg": "+ export TESTRUN_ID=0_dmesg"}
- {"dt": "2024-12-03T13:03:59.756469", "lvl": "target", "msg": "+ TESTRUN_ID=0_dmesg"}
- {"dt": "2024-12-03T13:03:59.796400", "lvl": "target", "msg": "+ cd /lava-38078/0/tests/0_dmesg"}
- {"dt": "2024-12-03T13:03:59.796936", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-12-03T13:03:59.797247", "lvl": "target", "msg": "+ UUID=38078_1.5.4.1"}
- {"dt": "2024-12-03T13:03:59.797519", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-03T13:03:59.797775", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_dmesg 38078_1.5.4.1>"}
- {"dt": "2024-12-03T13:03:59.798061", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-12-03T13:03:59.798307", "lvl": "target", "msg": "+ dmesg --level=warn --notime -x -k"}
- {"dt": "2024-12-03T13:03:59.798550", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-12-03T13:03:59.798793", "lvl": "target", "msg": "+ dmesg --level=err --notime -x -k"}
- {"dt": "2024-12-03T13:03:59.799036", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-03T13:03:59.799283", "lvl": "target", "msg": "+ dmesg --level=crit --notime -x -k"}
- {"dt": "2024-12-03T13:03:59.799532", "lvl": "target", "msg": "+ test -s dmesg.crit"}
- {"dt": "2024-12-03T13:03:59.799772", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-03T13:03:59.800012", "lvl": "target", "msg": "++ cat dmesg.crit"}
- {"dt": "2024-12-03T13:03:59.800250", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-03T13:03:59.800490", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-03T13:03:59.801023", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_dmesg 38078_1.5.4.1"}
- {"dt": "2024-12-03T13:03:59.801320", "lvl": "info", "msg": "Starting test lava.0_dmesg (38078_1.5.4.1)"}
- {"dt": "2024-12-03T13:03:59.801480", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-12-03T13:03:59.801587", "lvl": "target", "msg": "+ lava-test-case crit --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-03T13:03:59.839686", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-03T13:03:59.840225", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-03T13:03:59.840537", "lvl": "target", "msg": "+ dmesg --level=alert --notime -x -k"}
- {"dt": "2024-12-03T13:03:59.840844", "lvl": "target", "msg": "+ test -s dmesg.alert"}
- {"dt": "2024-12-03T13:03:59.841107", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-03T13:03:59.841361", "lvl": "target", "msg": "++ cat dmesg.alert"}
- {"dt": "2024-12-03T13:03:59.841607", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-03T13:03:59.841849", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-03T13:03:59.842088", "lvl": "target", "msg": "+ lava-test-case alert --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-03T13:03:59.842336", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-03T13:03:59.842588", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-03T13:03:59.842838", "lvl": "target", "msg": "+ dmesg --level=emerg --notime -x -k"}
- {"dt": "2024-12-03T13:03:59.843081", "lvl": "target", "msg": "+ test -s dmesg.emerg"}
- {"dt": "2024-12-03T13:03:59.843321", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-03T13:03:59.843828", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-03T13:03:59.844356", "lvl": "results", "msg": {"case": "crit", "definition": "0_dmesg", "endtc": ! "1342", "measurement": ! "0.0", "result": "pass", "starttc": ! "1342", "units": "lines"}}
- {"dt": "2024-12-03T13:03:59.845053", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-03T13:03:59.845420", "lvl": "results", "msg": {"case": "alert", "definition": "0_dmesg", "endtc": ! "1344", "measurement": ! "0.0", "result": "pass", "starttc": ! "1344", "units": "lines"}}
- {"dt": "2024-12-03T13:03:59.846019", "lvl": "target", "msg": "++ cat dmesg.emerg"}
- {"dt": "2024-12-03T13:03:59.846290", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-03T13:03:59.846541", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-03T13:03:59.881819", "lvl": "target", "msg": "+ lava-test-case emerg --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-03T13:03:59.882350", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-03T13:03:59.882661", "lvl": "target", "msg": "+ cat dmesg.emerg dmesg.alert dmesg.crit dmesg.err dmesg.warn"}
- {"dt": "2024-12-03T13:03:59.882936", "lvl": "target", "msg": "kern  :warn  : F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-12-03T13:03:59.883192", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-03T13:03:59.883445", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_dmesg 38078_1.5.4.1>"}
- {"dt": "2024-12-03T13:03:59.883954", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-03T13:03:59.884314", "lvl": "results", "msg": {"case": "emerg", "definition": "0_dmesg", "endtc": ! "1355", "measurement": ! "0.0", "result": "pass", "starttc": ! "1355", "units": "lines"}}
- {"dt": "2024-12-03T13:03:59.884992", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_dmesg 38078_1.5.4.1"}
- {"dt": "2024-12-03T13:03:59.885350", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-12-03T13:03:59.885609", "lvl": "info", "msg": "Ending test lava.0_dmesg (38078_1.5.4.1), duration 0.08"}
- {"dt": "2024-12-03T13:03:59.886018", "lvl": "results", "msg": {"case": "0_dmesg", "definition": "lava", "duration": "0.08", "namespace": "common", "path": "inline/dmesg.yaml", "repository": {"metadata": {"description": "baseline test plan", "environment": ["lava-test-shell"], "format": "Lava-Test Test Definition 1.0", "name": "baseline", "os": ["debian"], "scope": ["functional"]}, "run": {"steps": ["for level in warn err; do\n  dmesg --level=$level --notime -x -k > dmesg.$level\ndone\n", "for level in crit alert emerg; do\n  dmesg --level=$level --notime -x -k > dmesg.$level\n  test -s dmesg.$level && res=fail || res=pass\n  count=$(cat dmesg.$level | wc -l)\n  lava-test-case $level \\\n    --result $res \\\n    --measurement $count \\\n    --units lines\ndone\n", "cat dmesg.emerg dmesg.alert dmesg.crit dmesg.err dmesg.warn"]}}, "result": "pass", "revision": "unspecified", "uuid": "38078_1.5.4.1"}}
- {"dt": "2024-12-03T13:03:59.887057", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2024-12-03T13:03:59.887582", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-12-03T13:03:59.888011", "lvl": "debug", "msg": "alert: pass\ncrit: pass\nemerg: pass\n"}
- {"dt": "2024-12-03T13:03:59.888391", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:03:59.888766", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:03:59.889137", "lvl": "info", "msg": "start: 4 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-12-03T13:03:59.889492", "lvl": "debug", "msg": "start: 4.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-12-03T13:03:59.889762", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-12-03T13:03:59.890036", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-12-03T13:03:59.990888", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-12-03T13:03:59.991584", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-12-03T13:03:59.992085", "lvl": "debug", "msg": "Using /lava-38078"}
- {"dt": "2024-12-03T13:03:59.992394", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-12-03T13:04:00.093148", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-12-03T13:04:00.093956", "lvl": "target", "msg": "#"}
- {"dt": "2024-12-03T13:04:00.094558", "lvl": "input", "msg": ". /lava-38078/environment\n"}
- {"dt": "2024-12-03T13:04:00.195474", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh. /lava-38078/environment"}
- {"dt": "2024-12-03T13:04:00.196287", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:04:00.196954", "lvl": "input", "msg": "/lava-38078/bin/lava-test-runner /lava-38078/1\n"}
- {"dt": "2024-12-03T13:04:00.297892", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-38078/environment/lava-38078/bin/lava-test-runner /lava-38078/1"}
- {"dt": "2024-12-03T13:04:00.298527", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-12-03T13:04:00.299014", "lvl": "target", "msg": ""}
- {"dt": "2024-12-03T13:04:00.316181", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-38078/bin/lava-test-runner /lava-38078/1"}
- {"dt": "2024-12-03T13:04:00.362209", "lvl": "target", "msg": "+ export TESTRUN_ID=1_bootrr"}
- {"dt": "2024-12-03T13:04:00.362729", "lvl": "target", "msg": "+ TESTRUN_[   39.021519] <LAVA_SIGNAL_STARTRUN 1_bootrr 38078_1.5.4.5>"}
- {"dt": "2024-12-03T13:04:00.363035", "lvl": "target", "msg": "ID=1_bootrr"}
- {"dt": "2024-12-03T13:04:00.363301", "lvl": "target", "msg": "+ cd /lava-38078/1/t[   39.029863] <LAVA_SIGNAL_ENDRUN 1_bootrr 38078_1.5.4.5>"}
- {"dt": "2024-12-03T13:04:00.363559", "lvl": "target", "msg": "ests/1_bootrr"}
- {"dt": "2024-12-03T13:04:00.363799", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-12-03T13:04:00.364042", "lvl": "target", "msg": "+ UUID=38078_1.5.4.5"}
- {"dt": "2024-12-03T13:04:00.364282", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-03T13:04:00.364517", "lvl": "target", "msg": "+ export PATH=/opt/bootrr/helpers:/lava-38078/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-12-03T13:04:00.365058", "lvl": "debug", "msg": "Received signal: <STARTRUN> 1_bootrr 38078_1.5.4.5"}
- {"dt": "2024-12-03T13:04:00.365358", "lvl": "info", "msg": "Starting test lava.1_bootrr (38078_1.5.4.5)"}
- {"dt": "2024-12-03T13:04:00.365682", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-12-03T13:04:00.366071", "lvl": "debug", "msg": "Received signal: <ENDRUN> 1_bootrr 38078_1.5.4.5"}
- {"dt": "2024-12-03T13:04:00.366370", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-12-03T13:04:00.366593", "lvl": "info", "msg": "Ending test lava.1_bootrr (38078_1.5.4.5), duration 0.00"}
- {"dt": "2024-12-03T13:04:00.366679", "lvl": "results", "msg": {"case": "1_bootrr", "definition": "lava", "duration": "0.00", "namespace": "common", "path": "inline/bootrr.yaml", "repository": {"metadata": {"description": "baseline test plan", "environment": ["lava-test-shell"], "format": "Lava-Test Test Definition 1.0", "name": "baseline", "os": ["debian"], "scope": ["functional"]}, "run": {"steps": ["export PATH=/opt/bootrr/helpers:$PATH", "cd /opt/bootrr && sh helpers/bootrr-auto"]}}, "result": "pass", "revision": "unspecified", "uuid": "38078_1.5.4.5"}}
- {"dt": "2024-12-03T13:04:00.366906", "lvl": "target", "msg": "+ PATH=/opt/bootrr/helpers:/lava-38078/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-12-03T13:04:00.388829", "lvl": "target", "msg": "+ cd /opt/bootrr"}
- {"dt": "2024-12-03T13:04:00.389348", "lvl": "target", "msg": "/lava-38078/1/tests/1_bootrr/run.sh: line 15: cd: /opt/bootrr: No such file or directory"}
- {"dt": "2024-12-03T13:04:00.389648", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-03T13:04:00.390170", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-12-03T13:04:00.390607", "lvl": "debug", "msg": "end: 4.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:04:00.390968", "lvl": "info", "msg": "end: 4 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:04:00.391340", "lvl": "info", "msg": "start: 5 finalize (timeout 00:07:00) [common]"}
- {"dt": "2024-12-03T13:04:00.391733", "lvl": "debug", "msg": "start: 5.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-12-03T13:04:00.392258", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'off' '2'"}
- {"dt": "2024-12-03T13:04:01.430768", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-03T13:04:01.431418", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-12-03T13:04:01.432633", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-12-03T13:04:01.533514", "lvl": "debug", "msg": "end: 5.1 power-off (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:04:01.534110", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "1.14", "extra": {"status": "success"}, "level": "5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-03T13:04:01.534824", "lvl": "debug", "msg": "start: 5.2 read-feedback (timeout 00:06:59) [common]"}
- {"dt": "2024-12-03T13:04:01.535881", "lvl": "feedback", "msg": "<LAVA_TEST_RUNNER EXIT>", "ns": "common"}
- {"dt": "2024-12-03T13:04:01.536494", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-12-03T13:04:02.536708", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-12-03T13:04:02.537420", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-12-03T13:04:02.537811", "lvl": "target", "msg": "root@raspberrypi5:~# \0"}
- {"dt": "2024-12-03T13:04:02.638781", "lvl": "debug", "msg": "end: 5.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-12-03T13:04:02.639348", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2024-12-03T13:04:02.639691", "lvl": "debug", "msg": "clean NBD port 61952"}
- {"dt": "2024-12-03T13:04:02.751659", "lvl": "info", "msg": "end: 5 finalize (duration 00:00:02) [common]"}
- {"dt": "2024-12-03T13:04:02.752918", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-12-03T13:04:02.753443", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/initrd"}
- {"dt": "2024-12-03T13:04:02.756396", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/kernel"}
- {"dt": "2024-12-03T13:04:02.759103", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/dtb"}
- {"dt": "2024-12-03T13:04:02.759330", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38078/nbd-deploy-xy3o15y2/nbdroot"}
- {"dt": "2024-12-03T13:04:02.980598", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38078"}
- {"dt": "2024-12-03T13:04:02.982114", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38078"}
- {"dt": "2024-12-03T13:04:02.982271", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-12-03T13:04:02.982349", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
