- {"dt": "2024-12-01T09:52:05.852294", "lvl": "warning", "msg": "Action timeout for connect-device exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.852661", "lvl": "warning", "msg": "Action timeout for pdu-reboot exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.852837", "lvl": "warning", "msg": "Action timeout for reset-device exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.852982", "lvl": "warning", "msg": "Action timeout for login-action exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.853091", "lvl": "warning", "msg": "Action timeout for auto-login-action exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.853223", "lvl": "warning", "msg": "Action timeout for expect-shell-connection exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.853335", "lvl": "warning", "msg": "Action timeout for overlay-unpack exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.853446", "lvl": "warning", "msg": "Action timeout for export-device-env exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.853571", "lvl": "warning", "msg": "Action timeout for minimal-boot exceeds Job timeout"}
- {"dt": "2024-12-01T09:52:05.867318", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-12-01T09:52:05.867444", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-12-01T09:52:05.867597", "lvl": "info", "msg": "Start time: 2024-12-01 09:52:05.867587+00:00 (UTC)"}
- {"dt": "2024-12-01T09:52:05.867807", "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-01T09:52:06.488610", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image exists"}
- {"dt": "2024-12-01T09:52:06.706240", "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-01T09:52:06.908339", "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-01T09:52:07.114009", "lvl": "info", "msg": "validate duration: 1.25"}
- {"dt": "2024-12-01T09:52:07.114160", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-12-01T09:52:07.114337", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 00:10:00) [common]"}
- {"dt": "2024-12-01T09:52:07.114428", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2024-12-01T09:52:07.114506", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2024-12-01T09:52:07.114584", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2024-12-01T09:52:07.114748", "lvl": "debug", "msg": "Set_port 61999"}
- {"dt": "2024-12-01T09:52:07.114861", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-12-01T09:52:07.114961", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 00:10:00) [common]"}
- {"dt": "2024-12-01T09:52:07.115189", "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-01T09:52:07.115287", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-01T09:52:07.115372", "lvl": "debug", "msg": "total size: 2153804 (2\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.115455", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-12-01T09:52:07.417152", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.613793", "lvl": "debug", "msg": "progress   6\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.713141", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.730991", "lvl": "debug", "msg": "progress  18\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.812556", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.831055", "lvl": "debug", "msg": "progress  30\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.832020", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.832792", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.910930", "lvl": "debug", "msg": "progress  47\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.913988", "lvl": "debug", "msg": "progress  53\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.919958", "lvl": "debug", "msg": "progress  59\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.921432", "lvl": "debug", "msg": "progress  65\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.931910", "lvl": "debug", "msg": "progress  71\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.932580", "lvl": "debug", "msg": "progress  76\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:07.937382", "lvl": "debug", "msg": "progress  82\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:08.007883", "lvl": "debug", "msg": "progress  88\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:08.018450", "lvl": "debug", "msg": "progress  94\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:08.019194", "lvl": "debug", "msg": "progress 100\u202F% (2\u202FMB)"}
- {"dt": "2024-12-01T09:52:08.051431", "lvl": "info", "msg": "2\u202FMB downloaded in 0.94\u202Fs (2.19\u202FMB/s)"}
- {"dt": "2024-12-01T09:52:08.051748", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:52:08.051848", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.94", "extra": {"label": "initrd", "md5sum": "69fcb22a915d8fa6208b51a2d38a1bd0", "sha256sum": "59fb98aa51ec6733051adf76648cfc363ccc8367467a4fb8794cfaf7c3b33988", "sha512sum": "52fefc4f3925b72ed297cf2bb536aefa2358e2d66fefd61cede40b155efd2e9b101119081caf7f09fd61d798027cdc78cb63718fd91f573a718dfcb5894851c7", "size": ! "2153804"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:08.052044", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:52:08.052136", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:09:59) [common]"}
- {"dt": "2024-12-01T09:52:08.052228", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 00:09:59) [common]"}
- {"dt": "2024-12-01T09:52:08.052428", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image"}
- {"dt": "2024-12-01T09:52:08.052503", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/kernel/Image"}
- {"dt": "2024-12-01T09:52:08.052565", "lvl": "debug", "msg": "total size: 28887552 (27\u202FMB)"}
- {"dt": "2024-12-01T09:52:08.052629", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-01T09:52:08.355177", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:08.854480", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T09:52:08.951900", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.047108", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.059678", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.143810", "lvl": "debug", "msg": "progress  25\u202F% (6\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.158908", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.240188", "lvl": "debug", "msg": "progress  35\u202F% (9\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.257679", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.335951", "lvl": "debug", "msg": "progress  45\u202F% (12\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.356724", "lvl": "debug", "msg": "progress  50\u202F% (13\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.431691", "lvl": "debug", "msg": "progress  55\u202F% (15\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.462941", "lvl": "debug", "msg": "progress  60\u202F% (16\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.473795", "lvl": "debug", "msg": "progress  65\u202F% (17\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.562958", "lvl": "debug", "msg": "progress  70\u202F% (19\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.621762", "lvl": "debug", "msg": "progress  75\u202F% (20\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.660676", "lvl": "debug", "msg": "progress  80\u202F% (22\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.721848", "lvl": "debug", "msg": "progress  85\u202F% (23\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.760575", "lvl": "debug", "msg": "progress  90\u202F% (24\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.817624", "lvl": "debug", "msg": "progress  95\u202F% (26\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.860837", "lvl": "debug", "msg": "progress 100\u202F% (27\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.861304", "lvl": "info", "msg": "27\u202FMB downloaded in 1.81\u202Fs (15.23\u202FMB/s)"}
- {"dt": "2024-12-01T09:52:09.861659", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2024-12-01T09:52:09.861806", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.81", "extra": {"label": "kernel", "md5sum": "601b383c3c881d04e3368bd84040fdbd", "sha256sum": "99041f31b2ec83f4057caf62ca4bf1534d860cf2d8612fde079894451d55e486", "sha512sum": "e956efcf89178c3aa3fe2491edd04292d25136cf49bc45f7fdfcaa45b40768824dafc595be0d4dbccf066dc2d8dcfce3c6b446189612fa22ab6f3c465cab66c3", "size": ! "28887552"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:09.862112", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:02) [common]"}
- {"dt": "2024-12-01T09:52:09.862271", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:09:57) [common]"}
- {"dt": "2024-12-01T09:52:09.862422", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:09:57) [common]"}
- {"dt": "2024-12-01T09:52:09.862713", "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-01T09:52:09.862829", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/dtb/bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-01T09:52:09.862929", "lvl": "debug", "msg": "total size: 78059 (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:09.863026", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-01T09:52:10.162369", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:10.260000", "lvl": "debug", "msg": "progress  83\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:10.261569", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:10.262394", "lvl": "info", "msg": "0\u202FMB downloaded in 0.40\u202Fs (0.19\u202FMB/s)"}
- {"dt": "2024-12-01T09:52:10.263135", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:10.263491", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.40", "extra": {"label": "dtb", "md5sum": "e8c29afbbe377e6f0331d46da1c32f91", "sha256sum": "a91e4fa0dcc0ab08b873cfac3c0b7fc17c22ee715d941cbc915a44966c1e8c99", "sha512sum": "6e77dc7abf1cdc1c07a6f9e5f0168f52a9704b6d0142112e5b28cef3e166f8b93612a76b6341e5168e6c1e3d7ab7b4ed115d87749a7379c2d5174a78e19b46c3", "size": ! "78059"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:10.264251", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:10.264646", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:09:57) [common]"}
- {"dt": "2024-12-01T09:52:10.265088", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:09:57) [common]"}
- {"dt": "2024-12-01T09:52:10.265742", "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-01T09:52:10.265830", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-01T09:52:10.265909", "lvl": "debug", "msg": "total size: 493866112 (470\u202FMB)"}
- {"dt": "2024-12-01T09:52:10.265989", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-12-01T09:52:10.566993", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T09:52:14.954877", "lvl": "debug", "msg": "progress   5\u202F% (23\u202FMB)"}
- {"dt": "2024-12-01T09:52:19.565247", "lvl": "debug", "msg": "progress  10\u202F% (47\u202FMB)"}
- {"dt": "2024-12-01T09:52:22.073430", "lvl": "debug", "msg": "progress  15\u202F% (70\u202FMB)"}
- {"dt": "2024-12-01T09:52:23.699051", "lvl": "debug", "msg": "progress  20\u202F% (94\u202FMB)"}
- {"dt": "2024-12-01T09:52:25.394839", "lvl": "debug", "msg": "progress  25\u202F% (117\u202FMB)"}
- {"dt": "2024-12-01T09:52:27.276541", "lvl": "debug", "msg": "progress  30\u202F% (141\u202FMB)"}
- {"dt": "2024-12-01T09:52:29.246529", "lvl": "debug", "msg": "progress  35\u202F% (164\u202FMB)"}
- {"dt": "2024-12-01T09:52:31.374266", "lvl": "debug", "msg": "progress  40\u202F% (188\u202FMB)"}
- {"dt": "2024-12-01T09:52:33.109111", "lvl": "debug", "msg": "progress  45\u202F% (211\u202FMB)"}
- {"dt": "2024-12-01T09:52:35.044697", "lvl": "debug", "msg": "progress  50\u202F% (235\u202FMB)"}
- {"dt": "2024-12-01T09:52:36.975988", "lvl": "debug", "msg": "progress  55\u202F% (259\u202FMB)"}
- {"dt": "2024-12-01T09:52:39.147243", "lvl": "debug", "msg": "progress  60\u202F% (282\u202FMB)"}
- {"dt": "2024-12-01T09:52:40.894422", "lvl": "debug", "msg": "progress  65\u202F% (306\u202FMB)"}
- {"dt": "2024-12-01T09:52:42.616991", "lvl": "debug", "msg": "progress  70\u202F% (329\u202FMB)"}
- {"dt": "2024-12-01T09:52:44.965042", "lvl": "debug", "msg": "progress  75\u202F% (353\u202FMB)"}
- {"dt": "2024-12-01T09:52:47.105954", "lvl": "debug", "msg": "progress  80\u202F% (376\u202FMB)"}
- {"dt": "2024-12-01T09:52:48.949540", "lvl": "debug", "msg": "progress  85\u202F% (400\u202FMB)"}
- {"dt": "2024-12-01T09:52:51.112640", "lvl": "debug", "msg": "progress  90\u202F% (423\u202FMB)"}
- {"dt": "2024-12-01T09:52:52.971849", "lvl": "debug", "msg": "progress  95\u202F% (447\u202FMB)"}
- {"dt": "2024-12-01T09:52:57.410110", "lvl": "debug", "msg": "progress 100\u202F% (470\u202FMB)"}
- {"dt": "2024-12-01T09:52:58.833886", "lvl": "info", "msg": "470\u202FMB downloaded in 48.57\u202Fs (9.70\u202FMB/s)"}
- {"dt": "2024-12-01T09:52:58.834198", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:49) [common]"}
- {"dt": "2024-12-01T09:52:58.834288", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "48.57", "extra": {"label": "nbdroot", "md5sum": "4efb22dd71d11d782c04913029ebe146", "sha256sum": "b475a2953ee1a6be85506449e60e93d14579f8af2dc5577723d004a952eda229", "sha512sum": "888ffdcec6b22a5b3cd3e391eaa7a1bf5c6493efe72df9f895ac361c0117ba23335502223b5b769ad9e63ef740001581525ce5eaeaf2ac29e1b76eb53d8395b8", "size": ! "493866112"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:58.834479", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:49) [common]"}
- {"dt": "2024-12-01T09:52:58.834570", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.834822", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq"}
- {"dt": "2024-12-01T09:52:58.834994", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin"}
- {"dt": "2024-12-01T09:52:58.835112", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/tests"}
- {"dt": "2024-12-01T09:52:58.835222", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/results"}
- {"dt": "2024-12-01T09:52:58.835335", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-add-keys"}
- {"dt": "2024-12-01T09:52:58.835514", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-add-sources"}
- {"dt": "2024-12-01T09:52:58.835677", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-background-process-start"}
- {"dt": "2024-12-01T09:52:58.835834", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-background-process-stop"}
- {"dt": "2024-12-01T09:52:58.836003", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-common-functions"}
- {"dt": "2024-12-01T09:52:58.836164", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-echo-ipv4"}
- {"dt": "2024-12-01T09:52:58.836316", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-install-packages"}
- {"dt": "2024-12-01T09:52:58.836464", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-installed-packages"}
- {"dt": "2024-12-01T09:52:58.836612", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-os-build"}
- {"dt": "2024-12-01T09:52:58.836780", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-probe-channel"}
- {"dt": "2024-12-01T09:52:58.836950", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-probe-ip"}
- {"dt": "2024-12-01T09:52:58.837098", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-target-ip"}
- {"dt": "2024-12-01T09:52:58.837243", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-target-mac"}
- {"dt": "2024-12-01T09:52:58.837388", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-target-storage"}
- {"dt": "2024-12-01T09:52:58.837542", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-test-case"}
- {"dt": "2024-12-01T09:52:58.837693", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-test-event"}
- {"dt": "2024-12-01T09:52:58.837836", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-test-feedback"}
- {"dt": "2024-12-01T09:52:58.837979", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-test-raise"}
- {"dt": "2024-12-01T09:52:58.838130", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-test-reference"}
- {"dt": "2024-12-01T09:52:58.838274", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-test-runner"}
- {"dt": "2024-12-01T09:52:58.838419", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-test-set"}
- {"dt": "2024-12-01T09:52:58.838562", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-test-shell"}
- {"dt": "2024-12-01T09:52:58.838708", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-install-packages (oe)"}
- {"dt": "2024-12-01T09:52:58.838901", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/bin/lava-installed-packages (oe)"}
- {"dt": "2024-12-01T09:52:58.839075", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/environment"}
- {"dt": "2024-12-01T09:52:58.839223", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-12-01T09:52:58.839300", "lvl": "debug", "msg": "- LAVA_JOB_ID=38026"}
- {"dt": "2024-12-01T09:52:58.839366", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2024-12-01T09:52:58.839485", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.839810", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.839910", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.839980", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-12-01T09:52:58.840057", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.840142", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.840204", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-12-01T09:52:58.840277", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.840356", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.840429", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-12-01T09:52:58.840519", "lvl": "debug", "msg": "start: 1.5.4.1 inline-repo-action (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.840591", "lvl": "debug", "msg": "Using /lava-38026 at stage 0"}
- {"dt": "2024-12-01T09:52:58.841053", "lvl": "debug", "msg": "uuid=38026_1.5.4.1 testdef=None"}
- {"dt": "2024-12-01T09:52:58.841175", "lvl": "debug", "msg": "end: 1.5.4.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.841260", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.841900", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.841980", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38026_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:58.842143", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.843020", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.843109", "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": "38026_1.5.4.1", "uuid": "38026_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:58.843279", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.844123", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/0/tests/0_dmesg test_uuid 38026_1.5.4.1"}
- {"dt": "2024-12-01T09:52:58.844371", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.844474", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/0/tests/0_dmesg/run.sh", "from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38026_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:58.844685", "lvl": "debug", "msg": "start: 1.5.4.5 inline-repo-action (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.844784", "lvl": "debug", "msg": "Using /lava-38026 at stage 1"}
- {"dt": "2024-12-01T09:52:58.845182", "lvl": "debug", "msg": "uuid=38026_1.5.4.5 testdef=None"}
- {"dt": "2024-12-01T09:52:58.845278", "lvl": "debug", "msg": "end: 1.5.4.5 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.845361", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.845916", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.845990", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38026_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:58.846148", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.846923", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.847003", "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": "38026_1.5.4.5", "uuid": "38026_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:58.847175", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.847943", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/1/tests/1_bootrr test_uuid 38026_1.5.4.5"}
- {"dt": "2024-12-01T09:52:58.848136", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.848214", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/1/tests/1_bootrr/run.sh", "from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38026_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:52:58.848360", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-12-01T09:52:58.848424", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/0 for stage 0"}
- {"dt": "2024-12-01T09:52:58.848527", "lvl": "debug", "msg": "- 0_dmesg"}
- {"dt": "2024-12-01T09:52:58.848617", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38026/lava-overlay-a05tjpsq/lava-38026/1 for stage 1"}
- {"dt": "2024-12-01T09:52:58.848719", "lvl": "debug", "msg": "- 1_bootrr"}
- {"dt": "2024-12-01T09:52:58.848855", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.848941", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.858344", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.858502", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.858603", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.858700", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.858793", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.858885", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.858985", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.859072", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.859159", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.859230", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2024-12-01T09:52:58.859302", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61999, NBD-ROOT: 38026/nbd-deploy-qkca18sy/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-01T09:52:58.859750", "lvl": "debug", "msg": "nbd-server 61999 /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-01T09:52:58.862562", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-01T09:52:58.862731", "lvl": "debug", "msg": "output: ** (process:2811): WARNING **: 09:52:58.862: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2024-12-01T09:52:58.862831", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-01T09:52:58.862916", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2024-12-01T09:52:58.863043", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.863150", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:00:52) [common]"}
- {"dt": "2024-12-01T09:52:58.863260", "lvl": "info", "msg": "start: 2 minimal-boot (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.863366", "lvl": "debug", "msg": "start: 2.1 connect-device (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.863450", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63007'"}
- {"dt": "2024-12-01T09:52:58.867094", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-12-01T09:52:58.867581", "lvl": "debug", "msg": "end: 2.1 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:52:58.867744", "lvl": "debug", "msg": "start: 2.2 reset-device (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.867872", "lvl": "debug", "msg": "start: 2.2.1 pdu-reboot (timeout 00:09:08) [common]"}
- {"dt": "2024-12-01T09:52:58.868084", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'reset' '2'"}
- {"dt": "2024-12-01T09:52:59.903669", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-01T09:52:59.905016", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-12-01T09:53:03.939741", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-01T09:53:03.940359", "lvl": "debug", "msg": ">> Switched outlet 2 on\r"}
- {"dt": "2024-12-01T09:53:05.943157", "lvl": "debug", "msg": ">> export LANG='C'\r"}
- {"dt": "2024-12-01T09:53:05.943782", "lvl": "debug", "msg": ">> export LC_ALL='C.UTF-8'\r"}
- {"dt": "2024-12-01T09:53:05.944162", "lvl": "debug", "msg": ">> export PATH='/usr/local/bin:/usr/local/sbin:/bin:/usr/bin:/usr/sbin:/sbin'\r"}
- {"dt": "2024-12-01T09:53:05.944488", "lvl": "debug", "msg": ">> export PWD='/'\r"}
- {"dt": "2024-12-01T09:53:05.957440", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-12-01T09:53:05.959170", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp\r"}
- {"dt": "2024-12-01T09:53:05.969200", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-01T09:53:05.969406", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-01T09:53:05.969522", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-12-01T09:53:05.970785", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-12-01T09:53:05.981317", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/38027/tftp-deploy-oxbdq1ez/kernel/Image\r"}
- {"dt": "2024-12-01T09:53:05.981504", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/kernel/Image\r"}
- {"dt": "2024-12-01T09:53:05.982843", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=38027/tftp-deploy-oxbdq1ez/kernel/Image /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/kernel/Image\r"}
- {"dt": "2024-12-01T09:53:05.983822", "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-01T09:53:06.016260", "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-01T09:53:06.030364", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61999      0.0.0.0:*    users:((\"nbd-server\",pid=2812,fd=3))\r"}
- {"dt": "2024-12-01T09:53:06.032052", "lvl": "debug", "msg": ">> DEBUG: PID=2812\r"}
- {"dt": "2024-12-01T09:53:06.038569", "lvl": "debug", "msg": ">> NBDPORT=61999\r"}
- {"dt": "2024-12-01T09:53:06.040020", "lvl": "debug", "msg": ">> basename: extra operand \u2018/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\u2019\r"}
- {"dt": "2024-12-01T09:53:06.040170", "lvl": "debug", "msg": ">> Try 'basename --help' for more information.\r"}
- {"dt": "2024-12-01T09:53:06.040924", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-12-01T09:53:06.041058", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-12-01T09:53:06.041159", "lvl": "debug", "msg": ">> \t\tkernel 38027/tftp-deploy-oxbdq1ez/kernel/Image /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/kernel/Image\r"}
- {"dt": "2024-12-01T09:53:06.041251", "lvl": "debug", "msg": ">> \t\tinitramfs \r"}
- {"dt": "2024-12-01T09:53:06.041375", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-12-01T09:53:06.042126", "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-01T09:53:06.042486", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/dtb/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-12-01T09:53:06.044242", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy\r"}
- {"dt": "2024-12-01T09:53:06.045702", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-01T09:53:06.047592", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=38026/nbd-deploy-qkca18sy/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-01T09:53:06.048943", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/kernel/Image\r"}
- {"dt": "2024-12-01T09:53:06.050561", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=38026/nbd-deploy-qkca18sy/kernel/Image\r"}
- {"dt": "2024-12-01T09:53:06.103641", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61999      0.0.0.0:*    users:((\"nbd-server\",pid=2812,fd=3))\r"}
- {"dt": "2024-12-01T09:53:06.105621", "lvl": "debug", "msg": ">> DEBUG: PID=2812\r"}
- {"dt": "2024-12-01T09:53:06.114900", "lvl": "debug", "msg": ">> NBDPORT=61999\r"}
- {"dt": "2024-12-01T09:53:06.117641", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-12-01T09:53:06.117820", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-12-01T09:53:06.117943", "lvl": "debug", "msg": ">> \t\tkernel 38026/nbd-deploy-qkca18sy/kernel/Image\r"}
- {"dt": "2024-12-01T09:53:06.118138", "lvl": "debug", "msg": ">> \t\tinitramfs initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-01T09:53:06.118254", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-12-01T09:53:06.119700", "lvl": "debug", "msg": "Returned 0 in 7 seconds"}
- {"dt": "2024-12-01T09:53:06.220385", "lvl": "debug", "msg": "end: 2.2.1 pdu-reboot (duration 00:00:07) [common]"}
- {"dt": "2024-12-01T09:53:06.220921", "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-01T09:53:06.221631", "lvl": "debug", "msg": "end: 2.2 reset-device (duration 00:00:07) [common]"}
- {"dt": "2024-12-01T09:53:06.222073", "lvl": "debug", "msg": "start: 2.3 auto-login-action (timeout 00:09:01) [common]"}
- {"dt": "2024-12-01T09:53:06.222476", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-12-01T09:53:06.222824", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2024-12-01T09:53:06.223847", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-12-01T09:53:06.224183", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-12-01T09:53:06.224450", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-12-01T09:53:06.224712", "lvl": "target", "msg": "\0"}
- {"dt": "2024-12-01T09:53:06.224898", "lvl": "target", "msg": "RPi: BOOTSYS release VERSION:5f2f71fa DATE: 2023/09/25 TIME: 11:44:03"}
- {"dt": "2024-12-01T09:53:06.224959", "lvl": "target", "msg": "BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1695638643 serial a18d1bdb boardrev d04170 stc 1605773"}
- {"dt": "2024-12-01T09:53:06.225018", "lvl": "target", "msg": "AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-12-01T09:53:06.225076", "lvl": "target", "msg": "RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-01T09:53:06.225133", "lvl": "target", "msg": "PM_RSTS: 0x00001000"}
- {"dt": "2024-12-01T09:53:06.225191", "lvl": "target", "msg": "part 00000000 reset_info 00000000"}
- {"dt": "2024-12-01T09:53:06.225247", "lvl": "target", "msg": "PMIC reset-event 00000000 rtc 00000001 alarm 00000000 enabled 0"}
- {"dt": "2024-12-01T09:53:06.225304", "lvl": "target", "msg": "uSD voltage 3.3V"}
- {"dt": "2024-12-01T09:53:06.225361", "lvl": "target", "msg": "Initialising SDRAM 'Micron' 32Gb x2 total-size: 64 Gbit 4267"}
- {"dt": "2024-12-01T09:53:06.225419", "lvl": "target", "msg": "DDR 4267 1 0 64 152"}
- {"dt": "2024-12-01T09:53:07.915793", "lvl": "target", "msg": "RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-01T09:53:08.410960", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:53:08.506851", "lvl": "target", "msg": "RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-01T09:53:08.507415", "lvl": "target", "msg": "RP1_BOOT: fw size 25968"}
- {"dt": "2024-12-01T09:53:08.857625", "lvl": "target", "msg": "PCI2 init"}
- {"dt": "2024-12-01T09:53:08.857797", "lvl": "target", "msg": "PCI2 reset"}
- {"dt": "2024-12-01T09:53:08.923469", "lvl": "target", "msg": "PCIe scan 00001de4:00000001"}
- {"dt": "2024-12-01T09:53:08.923655", "lvl": "target", "msg": "RP1_CHIP_INFO 20001927"}
- {"dt": "2024-12-01T09:53:08.923761", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:53:08.923846", "lvl": "target", "msg": "RPi: BOOTLOADER release VERSION:5f2f71fa DATE: 2023/09/25 TIME: 11:44:03"}
- {"dt": "2024-12-01T09:53:08.926680", "lvl": "target", "msg": "BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1695638643 serial a18d1bdb boardrev d04170 stc 4571844"}
- {"dt": "2024-12-01T09:53:08.926864", "lvl": "target", "msg": "AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-12-01T09:53:08.967350", "lvl": "target", "msg": " status"}
- {"dt": "2024-12-01T09:53:08.967577", "lvl": "target", "msg": "USB_PD CONFIG 0 41"}
- {"dt": "2024-12-01T09:53:08.967704", "lvl": "target", "msg": "Boot mode: SD (01) order 24"}
- {"dt": "2024-12-01T09:53:08.967807", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-01T09:53:08.970637", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-01T09:53:09.066879", "lvl": "target", "msg": "EMMC"}
- {"dt": "2024-12-01T09:53:09.162756", "lvl": "target", "msg": "SD retry 1 oc 0"}
- {"dt": "2024-12-01T09:53:09.192945", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-01T09:53:09.287613", "lvl": "target", "msg": "SD retry 2 oc 0"}
- {"dt": "2024-12-01T09:53:09.315595", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-01T09:53:09.411255", "lvl": "target", "msg": "SDV1"}
- {"dt": "2024-12-01T09:53:09.507077", "lvl": "target", "msg": "SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-12-01T09:53:09.546884", "lvl": "target", "msg": "USB-PD: src-cap PDO object1 0x0a11912c"}
- {"dt": "2024-12-01T09:53:09.547444", "lvl": "target", "msg": "Current 3000 mA"}
- {"dt": "2024-12-01T09:53:09.547752", "lvl": "target", "msg": "Voltage 5000 mV"}
- {"dt": "2024-12-01T09:53:09.548019", "lvl": "target", "msg": "USB-PD: src-cap PDO object2 0x0012d12c"}
- {"dt": "2024-12-01T09:53:09.548274", "lvl": "target", "msg": "Current 3000 mA"}
- {"dt": "2024-12-01T09:53:09.548517", "lvl": "target", "msg": "Voltage 9000 mV"}
- {"dt": "2024-12-01T09:53:09.548792", "lvl": "target", "msg": "USB-PD: src-cap PDO object3 0x0014b12c"}
- {"dt": "2024-12-01T09:53:09.549042", "lvl": "target", "msg": "Current 3000 mA"}
- {"dt": "2024-12-01T09:53:09.549280", "lvl": "target", "msg": "Voltage 15000 mV"}
- {"dt": "2024-12-01T09:53:09.549521", "lvl": "target", "msg": "USB-PD: src-cap PDO object4 0x00164145"}
- {"dt": "2024-12-01T09:53:09.549773", "lvl": "target", "msg": "Current 3250 mA"}
- {"dt": "2024-12-01T09:53:09.550021", "lvl": "target", "msg": "Voltage 20000 mV"}
- {"dt": "2024-12-01T09:53:09.550561", "lvl": "target", "msg": "Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-12-01T09:53:09.550855", "lvl": "target", "msg": "Retry SD 1"}
- {"dt": "2024-12-01T09:53:09.583724", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-01T09:53:09.586876", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-01T09:53:09.682731", "lvl": "target", "msg": "EMMC"}
- {"dt": "2024-12-01T09:53:09.778583", "lvl": "target", "msg": "SD retry 1 oc 0"}
- {"dt": "2024-12-01T09:53:09.808806", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-01T09:53:09.904399", "lvl": "target", "msg": "SD retry 2 oc 0"}
- {"dt": "2024-12-01T09:53:09.931101", "lvl": "target", "msg": "SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-01T09:53:10.027148", "lvl": "target", "msg": "SDV1"}
- {"dt": "2024-12-01T09:53:10.122776", "lvl": "target", "msg": "SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-12-01T09:53:10.140651", "lvl": "target", "msg": "Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-12-01T09:53:10.144084", "lvl": "target", "msg": "Boot mode: USB-MSD (04) order 2"}
- {"dt": "2024-12-01T09:53:10.191648", "lvl": "target", "msg": "XHCI-STOP"}
- {"dt": "2024-12-01T09:53:10.192202", "lvl": "target", "msg": "xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-01T09:53:10.192514", "lvl": "target", "msg": "USBSTS 1"}
- {"dt": "2024-12-01T09:53:10.192821", "lvl": "target", "msg": "xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-01T09:53:10.193083", "lvl": "target", "msg": "xHC0 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-01T09:53:10.193331", "lvl": "target", "msg": "XHCI-STOP"}
- {"dt": "2024-12-01T09:53:10.193575", "lvl": "target", "msg": "xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-01T09:53:10.193874", "lvl": "target", "msg": "USBSTS 1"}
- {"dt": "2024-12-01T09:53:10.194115", "lvl": "target", "msg": "xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-01T09:53:10.194744", "lvl": "target", "msg": "xHC1 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-01T09:53:35.186988", "lvl": "target", "msg": "XHCI-STOP"}
- {"dt": "2024-12-01T09:53:35.215582", "lvl": "target", "msg": "xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-01T09:53:35.216071", "lvl": "target", "msg": "USBSTS 0"}
- {"dt": "2024-12-01T09:53:35.216372", "lvl": "target", "msg": "XHCI-STOP"}
- {"dt": "2024-12-01T09:53:35.216637", "lvl": "target", "msg": "xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-01T09:53:35.216932", "lvl": "target", "msg": "USBSTS 0"}
- {"dt": "2024-12-01T09:53:35.217179", "lvl": "target", "msg": "USB MSD stopped. Timeout: 25 seconds"}
- {"dt": "2024-12-01T09:53:35.218744", "lvl": "target", "msg": "Boot mode: NETWORK (02) order 0"}
- {"dt": "2024-12-01T09:53:35.298641", "lvl": "target", "msg": "NETWORK: d8:3a:dd:e5:72:37 wait for link TFTP: 192.168.68.1"}
- {"dt": "2024-12-01T09:53:38.220897", "lvl": "target", "msg": "LINK STATUS: speed: 1000 full duplex"}
- {"dt": "2024-12-01T09:53:38.221442", "lvl": "target", "msg": "Link ready"}
- {"dt": "2024-12-01T09:53:38.282562", "lvl": "target", "msg": "RX: 0 IP: 0 IPV4: 0 MAC: 0 UDP: 0 UDP RECV: 0 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.266404", "lvl": "target", "msg": "DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-01T09:53:46.306550", "lvl": "target", "msg": "YI_ADDR 192.168.68.231"}
- {"dt": "2024-12-01T09:53:46.307118", "lvl": "target", "msg": "SI_ADDR 192.168.68.1"}
- {"dt": "2024-12-01T09:53:46.307452", "lvl": "target", "msg": "OPTIONS:-"}
- {"dt": "2024-12-01T09:53:46.307744", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv OFFER (2) expect OFFER"}
- {"dt": "2024-12-01T09:53:46.308025", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1"}
- {"dt": "2024-12-01T09:53:46.308288", "lvl": "target", "msg": "\top: 51 len:   4 "}
- {"dt": "2024-12-01T09:53:46.308547", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0"}
- {"dt": "2024-12-01T09:53:46.308839", "lvl": "target", "msg": "\top:  3 len:   4 192.168.68.1"}
- {"dt": "2024-12-01T09:53:46.309108", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-01T09:53:46.309369", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:53:46.309646", "lvl": "target", "msg": "NET 192.168.68.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.68.1"}
- {"dt": "2024-12-01T09:53:46.309951", "lvl": "target", "msg": "DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-01T09:53:46.310218", "lvl": "target", "msg": "YI_ADDR 192.168.68.231"}
- {"dt": "2024-12-01T09:53:46.310471", "lvl": "target", "msg": "SI_ADDR 192.168.68.1"}
- {"dt": "2024-12-01T09:53:46.310722", "lvl": "target", "msg": "OPTIONS:-"}
- {"dt": "2024-12-01T09:53:46.311276", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv ACK (5) expect ACK"}
- {"dt": "2024-12-01T09:53:46.311551", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1"}
- {"dt": "2024-12-01T09:53:46.349670", "lvl": "target", "msg": "\top: 51 len:   4 "}
- {"dt": "2024-12-01T09:53:46.350222", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0"}
- {"dt": "2024-12-01T09:53:46.350530", "lvl": "target", "msg": "\top:  3 len:   4 192.168.68.1"}
- {"dt": "2024-12-01T09:53:46.350804", "lvl": "target", "msg": "\top:  6 len:   4 10.1.1.1"}
- {"dt": "2024-12-01T09:53:46.351062", "lvl": "target", "msg": "\top: 150 len:   4 "}
- {"dt": "2024-12-01T09:53:46.351310", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-01T09:53:46.351549", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:53:46.351794", "lvl": "target", "msg": "\top: 15 len:  11 "}
- {"dt": "2024-12-01T09:53:46.352033", "lvl": "target", "msg": "ARP 192.168.68.1 70:70:fc:05:68:c7"}
- {"dt": "2024-12-01T09:53:46.352274", "lvl": "target", "msg": "NET 192.168.68.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.68.1"}
- {"dt": "2024-12-01T09:53:46.352851", "lvl": "target", "msg": "RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.353149", "lvl": "target", "msg": "TFTP_GET: 70:70:fc:05:68:c7 192.168.68.1 a18d1bdb/config.txt"}
- {"dt": "2024-12-01T09:53:46.353404", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:53:46.353707", "lvl": "target", "msg": "RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.393173", "lvl": "target", "msg": "TFTP 1: File not found "}
- {"dt": "2024-12-01T09:53:46.393766", "lvl": "target", "msg": "RX: 4 IP: 0 IPV4: 3 MAC: 3 UDP: 3 UDP RECV: 3 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.394171", "lvl": "target", "msg": "BMD \\\"a18d1bdb/config.txt\\\" not found"}
- {"dt": "2024-12-01T09:53:46.394463", "lvl": "target", "msg": "TFTP_GET: 70:70:fc:05:68:c7 192.168.68.1 config.txt"}
- {"dt": "2024-12-01T09:53:46.394734", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:53:46.394989", "lvl": "target", "msg": "RX: 4 IP: 0 IPV4: 3 MAC: 3 UDP: 3 UDP RECV: 3 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.395238", "lvl": "target", "msg": "TFTP: complete 137"}
- {"dt": "2024-12-01T09:53:46.395483", "lvl": "target", "msg": "RX: 6 IP: 0 IPV4: 5 MAC: 5 UDP: 5 UDP RECV: 5 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.395727", "lvl": "target", "msg": "Read config.txt bytes      137 hnd 0x0"}
- {"dt": "2024-12-01T09:53:46.395980", "lvl": "target", "msg": "TFTP_GET: 70:70:fc:05:68:c7 192.168.68.1 pieeprom.sig"}
- {"dt": "2024-12-01T09:53:46.396239", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:53:46.429780", "lvl": "target", "msg": "RX: 6 IP: 0 IPV4: 5 MAC: 5 UDP: 5 UDP RECV: 5 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.430335", "lvl": "target", "msg": "TFTP 1: File not found "}
- {"dt": "2024-12-01T09:53:46.430649", "lvl": "target", "msg": "RX: 7 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.430922", "lvl": "target", "msg": "Read pieeprom.upd failed"}
- {"dt": "2024-12-01T09:53:46.431187", "lvl": "target", "msg": "usb_max_current_enable default 0 max-current 3000"}
- {"dt": "2024-12-01T09:53:46.432896", "lvl": "target", "msg": "TFTP_GET: 70:70:fc:05:68:c7 192.168.68.1 bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-01T09:53:46.433310", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:53:46.479594", "lvl": "target", "msg": "RX: 7 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.552530", "lvl": "target", "msg": "TFTP: complete 78059"}
- {"dt": "2024-12-01T09:53:46.553071", "lvl": "target", "msg": "RX: 62 IP: 0 IPV4: 61 MAC: 61 UDP: 61 UDP RECV: 61 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-01T09:53:46.553387", "lvl": "target", "msg": "Read bcm2712-rpi-5-b.dtb bytes    78059 hnd 0x0"}
- {"dt": "2024-12-01T09:53:46.553663", "lvl": "target", "msg": "dt-match: compatible: raspberrypi,5-model-b match: brcm,bcm2712"}
- {"dt": "2024-12-01T09:53:46.553926", "lvl": "target", "msg": "dt-match: compatible: brcm,bcm2712 match: brcm,bcm2712"}
- {"dt": "2024-12-01T09:53:46.554178", "lvl": "target", "msg": "PM_RSTS 00001000"}
- {"dt": "2024-12-01T09:53:46.555699", "lvl": "target", "msg": "Selecting USB low current limit"}
- {"dt": "2024-12-01T09:53:46.556114", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:12.544193", "lvl": "target", "msg": "NOTICE:  BL31: v2.6(release):v2.6-239-g2a9ede0bd"}
- {"dt": "2024-12-01T09:54:12.547384", "lvl": "target", "msg": "NOTICE:  BL31: Built : 14:26:57, Jun 22 2023"}
- {"dt": "2024-12-01T09:54:12.739849", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x414fd0b1]"}
- {"dt": "2024-12-01T09:54:12.740397", "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-01T09:54:12.740723", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-12-01T09:54:12.741051", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-12-01T09:54:12.741316", "lvl": "target", "msg": "[    0.000000] Machine model: Raspberry Pi 5 Model B Rev 1.0"}
- {"dt": "2024-12-01T09:54:12.741570", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-12-01T09:54:12.742340", "lvl": "debug", "msg": "start: 2.3.1 login-action (timeout 00:07:54) [common]"}
- {"dt": "2024-12-01T09:54:12.742730", "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-01T09:54:12.743041", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-12-01T09:54:12.743385", "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-01T09:54:12.743713", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-12-01T09:54:12.743969", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-12-01T09:54:12.744245", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-12-01T09:54:12.744491", "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-01T09:54:12.744805", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:07:54)"}
- {"dt": "2024-12-01T09:54:12.782967", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000002000000, size 64 MiB"}
- {"dt": "2024-12-01T09:54:12.783532", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool"}
- {"dt": "2024-12-01T09:54:12.783850", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000002000000..0x0000000005ffffff (65536 KiB) map reusable linux,cma"}
- {"dt": "2024-12-01T09:54:12.784460", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0"}
- {"dt": "2024-12-01T09:54:12.784780", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x000000003fd16180..0x000000003fd1627a (0 KiB) nomap non-reusable nvram@0"}
- {"dt": "2024-12-01T09:54:12.786311", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-12-01T09:54:12.826447", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000000000000-0x00000000ffffffff]"}
- {"dt": "2024-12-01T09:54:12.826998", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-12-01T09:54:12.827308", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]"}
- {"dt": "2024-12-01T09:54:12.827581", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-12-01T09:54:12.827837", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-12-01T09:54:12.828086", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000007ffff]"}
- {"dt": "2024-12-01T09:54:12.828333", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000080000-0x000000003f7fffff]"}
- {"dt": "2024-12-01T09:54:12.828577", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x00000001ffffffff]"}
- {"dt": "2024-12-01T09:54:12.869674", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]"}
- {"dt": "2024-12-01T09:54:12.870223", "lvl": "target", "msg": "[    0.000000] On node 0, zone DMA: 512 pages in unavailable ranges"}
- {"dt": "2024-12-01T09:54:12.870536", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-12-01T09:54:12.870811", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-12-01T09:54:12.871069", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-12-01T09:54:12.871318", "lvl": "target", "msg": "[    0.000000] psci: MIGRATE_INFO_TYPE not supported."}
- {"dt": "2024-12-01T09:54:12.871567", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.2"}
- {"dt": "2024-12-01T09:54:12.872838", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 14 pages/cpu s184936 r8192 d36248 u229376"}
- {"dt": "2024-12-01T09:54:12.913140", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-12-01T09:54:12.913687", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-12-01T09:54:12.913996", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Hardware dirty bit management"}
- {"dt": "2024-12-01T09:54:12.914274", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-12-01T09:54:12.914533", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-BHB"}
- {"dt": "2024-12-01T09:54:12.914783", "lvl": "target", "msg": "[    0.000000] CPU features: kernel page table isolation forced ON by KASLR"}
- {"dt": "2024-12-01T09:54:12.915032", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)"}
- {"dt": "2024-12-01T09:54:12.916255", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-12-01T09:54:12.999715", "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=61999 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-01T09:54:13.000293", "lvl": "target", "msg": "[    0.000000] Unknown kernel command line parameters \\\"verbose earlyprintk\\\", will be passed to user space."}
- {"dt": "2024-12-01T09:54:13.000610", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 9, 8388608 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.000925", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 8, 4194304 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.001190", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 521728"}
- {"dt": "2024-12-01T09:54:13.002906", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-12-01T09:54:13.043045", "lvl": "target", "msg": "[    0.000000] software IO TLB: area num 4."}
- {"dt": "2024-12-01T09:54:13.043591", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000fbffc000-0x00000000ffffc000] (64MB)"}
- {"dt": "2024-12-01T09:54:13.043902", "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-01T09:54:13.044203", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1"}
- {"dt": "2024-12-01T09:54:13.044466", "lvl": "target", "msg": "[    0.000000] ftrace: allocating 49047 entries in 48 pages"}
- {"dt": "2024-12-01T09:54:13.046262", "lvl": "target", "msg": "[    0.000000] ftrace: allocated 48 pages with 2 groups"}
- {"dt": "2024-12-01T09:54:13.086315", "lvl": "target", "msg": "[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-12-01T09:54:13.086868", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-12-01T09:54:13.087176", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-12-01T09:54:13.087448", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4."}
- {"dt": "2024-12-01T09:54:13.087704", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2024-12-01T09:54:13.087952", "lvl": "target", "msg": "[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-12-01T09:54:13.088197", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-12-01T09:54:13.089447", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-12-01T09:54:13.129618", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4"}
- {"dt": "2024-12-01T09:54:13.130168", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-12-01T09:54:13.130483", "lvl": "target", "msg": "[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-12-01T09:54:13.130763", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-12-01T09:54:13.131026", "lvl": "target", "msg": "[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-12-01T09:54:13.131283", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys)."}
- {"dt": "2024-12-01T09:54:13.132724", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns"}
- {"dt": "2024-12-01T09:54:13.172924", "lvl": "target", "msg": "[    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns"}
- {"dt": "2024-12-01T09:54:13.173479", "lvl": "target", "msg": "[    0.000167] Console: colour dummy device 80x25"}
- {"dt": "2024-12-01T09:54:13.173789", "lvl": "target", "msg": "[    0.000183] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)"}
- {"dt": "2024-12-01T09:54:13.174064", "lvl": "target", "msg": "[    0.000187] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-12-01T09:54:13.174319", "lvl": "target", "msg": "[    0.000210] LSM: initializing lsm=capability,integrity"}
- {"dt": "2024-12-01T09:54:13.174568", "lvl": "target", "msg": "[    0.000271] Mount-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.216151", "lvl": "target", "msg": "[    0.000296] Mountpoint-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.216698", "lvl": "target", "msg": "[    0.000635] cgroup: Disabling memory control group subsystem"}
- {"dt": "2024-12-01T09:54:13.217102", "lvl": "target", "msg": "[    0.001018] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-01T09:54:13.217541", "lvl": "target", "msg": "[    0.001039] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-01T09:54:13.217869", "lvl": "target", "msg": "[    0.001061] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-01T09:54:13.218172", "lvl": "target", "msg": "[    0.001114] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-12-01T09:54:13.219278", "lvl": "target", "msg": "[    0.001115] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-12-01T09:54:13.259558", "lvl": "target", "msg": "[    0.001698] EFI services will not be available."}
- {"dt": "2024-12-01T09:54:13.260087", "lvl": "target", "msg": "[    0.001753] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-12-01T09:54:13.260397", "lvl": "target", "msg": "[    0.001905] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-12-01T09:54:13.260673", "lvl": "target", "msg": "[    0.001946] CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1]"}
- {"dt": "2024-12-01T09:54:13.260973", "lvl": "target", "msg": "[    0.002115] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-12-01T09:54:13.261226", "lvl": "target", "msg": "[    0.002149] CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1]"}
- {"dt": "2024-12-01T09:54:13.261477", "lvl": "target", "msg": "[    0.002308] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-12-01T09:54:13.261719", "lvl": "target", "msg": "[    0.002338] CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1]"}
- {"dt": "2024-12-01T09:54:13.262693", "lvl": "target", "msg": "[    0.002368] smp: Brought up 1 node, 4 CPUs"}
- {"dt": "2024-12-01T09:54:13.302869", "lvl": "target", "msg": "[    0.002371] SMP: Total of 4 processors activated."}
- {"dt": "2024-12-01T09:54:13.303416", "lvl": "target", "msg": "[    0.002373] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-12-01T09:54:13.303727", "lvl": "target", "msg": "[    0.002375] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-12-01T09:54:13.304005", "lvl": "target", "msg": "[    0.002377] CPU features: detected: Common not Private translations"}
- {"dt": "2024-12-01T09:54:13.304264", "lvl": "target", "msg": "[    0.002378] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-12-01T09:54:13.304514", "lvl": "target", "msg": "[    0.002380] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-12-01T09:54:13.305999", "lvl": "target", "msg": "[    0.002382] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-12-01T09:54:13.346186", "lvl": "target", "msg": "[    0.002383] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-12-01T09:54:13.346717", "lvl": "target", "msg": "[    0.002385] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-12-01T09:54:13.347026", "lvl": "target", "msg": "[    0.002387] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-12-01T09:54:13.347306", "lvl": "target", "msg": "[    0.002419] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-12-01T09:54:13.347569", "lvl": "target", "msg": "[    0.002420] alternatives: applying system-wide alternatives"}
- {"dt": "2024-12-01T09:54:13.347818", "lvl": "target", "msg": "[    0.004275] devtmpfs: initialized"}
- {"dt": "2024-12-01T09:54:13.348064", "lvl": "target", "msg": "[    0.007055] Enabled cp15_barrier support"}
- {"dt": "2024-12-01T09:54:13.348305", "lvl": "target", "msg": "[    0.007060] Enabled setend support"}
- {"dt": "2024-12-01T09:54:13.389538", "lvl": "target", "msg": "[    0.007159] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-12-01T09:54:13.390074", "lvl": "target", "msg": "[    0.007165] futex hash table entries: 1024 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.390382", "lvl": "target", "msg": "[    0.007436] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-12-01T09:54:13.390657", "lvl": "target", "msg": "[    0.007555] DMI not present or invalid."}
- {"dt": "2024-12-01T09:54:13.390917", "lvl": "target", "msg": "[    0.007709] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-12-01T09:54:13.391412", "lvl": "target", "msg": "[    0.008264] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-12-01T09:54:13.392675", "lvl": "target", "msg": "[    0.008308] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-12-01T09:54:13.432801", "lvl": "target", "msg": "[    0.008355] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-12-01T09:54:13.433333", "lvl": "target", "msg": "[    0.008370] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-12-01T09:54:13.433641", "lvl": "target", "msg": "[    0.008415] audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-12-01T09:54:13.433914", "lvl": "target", "msg": "[    0.008538] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-12-01T09:54:13.434169", "lvl": "target", "msg": "[    0.008548] cpuidle: using governor menu"}
- {"dt": "2024-12-01T09:54:13.434418", "lvl": "target", "msg": "[    0.008625] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-12-01T09:54:13.436119", "lvl": "target", "msg": "[    0.008654] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-12-01T09:54:13.494231", "lvl": "target", "msg": "[    0.008960] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-12-01T09:54:13.494770", "lvl": "target", "msg": "[    0.009730] bcm2835-mbox 107c013880.mailbox: mailbox enabled"}
- {"dt": "2024-12-01T09:54:13.495081", "lvl": "target", "msg": "[    0.009924] 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2"}
- {"dt": "2024-12-01T09:54:13.495358", "lvl": "target", "msg": "[    0.009933] printk: console [ttyAMA10] enabled"}
- {"dt": "2024-12-01T09:54:13.495616", "lvl": "target", "msg": "[    0.774116] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-09-25T10:44:03, variant start_cd"}
- {"dt": "2024-12-01T09:54:13.497345", "lvl": "target", "msg": "[    0.788167] raspberrypi-firmware soc:firmware: Firmware hash is 5f2f71fa00000000000000000000000000000000"}
- {"dt": "2024-12-01T09:54:13.542866", "lvl": "target", "msg": "[    0.803807] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-12-01T09:54:13.543393", "lvl": "target", "msg": "[    0.810888] Modules: 0 pages in range for non-PLT usage"}
- {"dt": "2024-12-01T09:54:13.543701", "lvl": "target", "msg": "[    0.810889] Modules: 129224 pages in range for PLT usage"}
- {"dt": "2024-12-01T09:54:13.543975", "lvl": "target", "msg": "[    0.820718] bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1"}
- {"dt": "2024-12-01T09:54:13.544230", "lvl": "target", "msg": "[    0.833698] iommu: Default domain type: Translated"}
- {"dt": "2024-12-01T09:54:13.544477", "lvl": "target", "msg": "[    0.838517] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-12-01T09:54:13.544721", "lvl": "target", "msg": "[    0.844810] SCSI subsystem initialized"}
- {"dt": "2024-12-01T09:54:13.545975", "lvl": "target", "msg": "[    0.848608] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-12-01T09:54:13.596587", "lvl": "target", "msg": "[    0.854126] usbcore: registered new interface driver hub"}
- {"dt": "2024-12-01T09:54:13.597148", "lvl": "target", "msg": "[    0.859468] usbcore: registered new device driver usb"}
- {"dt": "2024-12-01T09:54:13.597459", "lvl": "target", "msg": "[    0.864628] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-12-01T09:54:13.597730", "lvl": "target", "msg": "[    0.869609] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-12-01T09:54:13.597991", "lvl": "target", "msg": "[    0.878779] PTP clock support registered"}
- {"dt": "2024-12-01T09:54:13.598242", "lvl": "target", "msg": "[    0.882763] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-12-01T09:54:13.598491", "lvl": "target", "msg": "[    0.889112] vgaarb: loaded"}
- {"dt": "2024-12-01T09:54:13.599752", "lvl": "target", "msg": "[    0.891946] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-12-01T09:54:13.840064", "lvl": "target", "msg": "[    1.105930] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-12-01T09:54:13.840599", "lvl": "target", "msg": "[    1.109882] VFS: Dquot-cache hash table entries: 2048 (order 0, 16384 bytes)"}
- {"dt": "2024-12-01T09:54:13.840951", "lvl": "target", "msg": "[    1.116986] FS-Cache: Loaded"}
- {"dt": "2024-12-01T09:54:13.841230", "lvl": "target", "msg": "[    1.119910] CacheFiles: Loaded"}
- {"dt": "2024-12-01T09:54:13.841488", "lvl": "target", "msg": "[    1.124523] NET: Registered PF_INET protocol family"}
- {"dt": "2024-12-01T09:54:13.841741", "lvl": "target", "msg": "[    1.129568] IP idents hash table entries: 131072 (order: 6, 1048576 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.841989", "lvl": "target", "msg": "[    1.139647] tcp_listen_portaddr_hash hash table entries: 4096 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.885745", "lvl": "target", "msg": "[    1.148253] Table-perturb hash table entries: 65536 (order: 4, 262144 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.886302", "lvl": "target", "msg": "[    1.156030] TCP established hash table entries: 65536 (order: 5, 524288 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.886615", "lvl": "target", "msg": "[    1.164242] TCP bind hash table entries: 65536 (order: 7, 2097152 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.886888", "lvl": "target", "msg": "[    1.173352] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-12-01T09:54:13.887148", "lvl": "target", "msg": "[    1.180068] MPTCP token hash table entries: 8192 (order: 3, 196608 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.888116", "lvl": "target", "msg": "[    1.187691] UDP hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.929682", "lvl": "target", "msg": "[    1.194600] UDP-Lite hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-01T09:54:13.930232", "lvl": "target", "msg": "[    1.202004] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-12-01T09:54:13.930542", "lvl": "target", "msg": "[    1.207836] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-12-01T09:54:13.930818", "lvl": "target", "msg": "[    1.213779] RPC: Registered udp transport module."}
- {"dt": "2024-12-01T09:54:13.931076", "lvl": "target", "msg": "[    1.218496] RPC: Registered tcp transport module."}
- {"dt": "2024-12-01T09:54:13.931324", "lvl": "target", "msg": "[    1.223214] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-12-01T09:54:13.931569", "lvl": "target", "msg": "[    1.228722] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-12-01T09:54:13.932838", "lvl": "target", "msg": "[    1.235188] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-12-01T09:54:13.973724", "lvl": "target", "msg": "[    1.239343] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-12-01T09:54:13.974278", "lvl": "target", "msg": "[    1.244793] rootfs image is not initramfs (invalid magic at start of compressed archive); looks like an initrd"}
- {"dt": "2024-12-01T09:54:13.974592", "lvl": "target", "msg": "[    1.254875] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2024-12-01T09:54:13.974867", "lvl": "target", "msg": "[    1.259213] kvm [1]: GICV region size/alignment is unsafe, using trapping (reduced performance)"}
- {"dt": "2024-12-01T09:54:13.975127", "lvl": "target", "msg": "[    1.268048] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-12-01T09:54:13.975374", "lvl": "target", "msg": "[    1.269077] Freeing initrd memory: 12288K"}
- {"dt": "2024-12-01T09:54:13.975618", "lvl": "target", "msg": "[    1.276037] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-12-01T09:54:13.976878", "lvl": "target", "msg": "[    1.281603] Initialise system trusted keyrings"}
- {"dt": "2024-12-01T09:54:14.017387", "lvl": "target", "msg": "[    1.286124] workingset: timestamp_bits=46 max_order=19 bucket_order=0"}
- {"dt": "2024-12-01T09:54:14.017937", "lvl": "target", "msg": "[    1.292601] zbud: loaded"}
- {"dt": "2024-12-01T09:54:14.018246", "lvl": "target", "msg": "[    1.295302] NFS: Registering the id_resolver key type"}
- {"dt": "2024-12-01T09:54:14.018522", "lvl": "target", "msg": "[    1.300375] Key type id_resolver registered"}
- {"dt": "2024-12-01T09:54:14.018779", "lvl": "target", "msg": "[    1.304569] Key type id_legacy registered"}
- {"dt": "2024-12-01T09:54:14.019027", "lvl": "target", "msg": "[    1.308592] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-12-01T09:54:14.019276", "lvl": "target", "msg": "[    1.315318] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-12-01T09:54:14.020550", "lvl": "target", "msg": "[    1.322778] F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-12-01T09:54:14.061058", "lvl": "target", "msg": "[    1.328347] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-12-01T09:54:14.061616", "lvl": "target", "msg": "[    1.333155] Key type asymmetric registered"}
- {"dt": "2024-12-01T09:54:14.061922", "lvl": "target", "msg": "[    1.337261] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-12-01T09:54:14.062191", "lvl": "target", "msg": "[    1.342169] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2024-12-01T09:54:14.062449", "lvl": "target", "msg": "[    1.349620] io scheduler mq-deadline registered"}
- {"dt": "2024-12-01T09:54:14.062696", "lvl": "target", "msg": "[    1.354165] io scheduler kyber registered"}
- {"dt": "2024-12-01T09:54:14.062937", "lvl": "target", "msg": "[    1.358192] io scheduler bfq registered"}
- {"dt": "2024-12-01T09:54:14.063179", "lvl": "target", "msg": "[    1.362259] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 27)"}
- {"dt": "2024-12-01T09:54:14.100571", "lvl": "target", "msg": "[    1.370013] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 28)"}
- {"dt": "2024-12-01T09:54:14.101153", "lvl": "target", "msg": "[    1.377747] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 29)"}
- {"dt": "2024-12-01T09:54:14.101640", "lvl": "target", "msg": "[    1.385485] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 30)"}
- {"dt": "2024-12-01T09:54:14.103724", "lvl": "target", "msg": "[    1.395228] bcm2708_fb soc:fb: FB found 1 display(s)"}
- {"dt": "2024-12-01T09:54:14.126411", "lvl": "target", "msg": "[    1.410158] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-12-01T09:54:14.129470", "lvl": "target", "msg": "[    1.419855] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1920x1080"}
- {"dt": "2024-12-01T09:54:14.191601", "lvl": "target", "msg": "[    1.458881] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled"}
- {"dt": "2024-12-01T09:54:14.191803", "lvl": "target", "msg": "[    1.465594] 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART"}
- {"dt": "2024-12-01T09:54:14.191931", "lvl": "target", "msg": "[    1.476212] serial serial0: tty port ttyS0 registered"}
- {"dt": "2024-12-01T09:54:14.192048", "lvl": "target", "msg": "[    1.481562] iproc-rng200 107d208000.rng: hwrng registered"}
- {"dt": "2024-12-01T09:54:14.192153", "lvl": "target", "msg": "[    1.487028] vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB)"}
- {"dt": "2024-12-01T09:54:14.194753", "lvl": "target", "msg": "[    1.495550] bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe"}
- {"dt": "2024-12-01T09:54:14.243606", "lvl": "target", "msg": "[    1.505066] brd: module loaded"}
- {"dt": "2024-12-01T09:54:14.244115", "lvl": "target", "msg": "[    1.509528] loop: module loaded"}
- {"dt": "2024-12-01T09:54:14.244421", "lvl": "target", "msg": "[    1.518016] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver"}
- {"dt": "2024-12-01T09:54:14.244699", "lvl": "target", "msg": "[    1.525435] Loading iSCSI transport class v2.0-870."}
- {"dt": "2024-12-01T09:54:14.244985", "lvl": "target", "msg": "[    1.531191] usbcore: registered new device driver r8152-cfgselector"}
- {"dt": "2024-12-01T09:54:14.245236", "lvl": "target", "msg": "[    1.537492] usbcore: registered new interface driver r8152"}
- {"dt": "2024-12-01T09:54:14.245483", "lvl": "target", "msg": "[    1.543001] usbcore: registered new interface driver lan78xx"}
- {"dt": "2024-12-01T09:54:14.246767", "lvl": "target", "msg": "[    1.548741] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)"}
- {"dt": "2024-12-01T09:54:14.294850", "lvl": "target", "msg": "[    1.554651] usbcore: registered new interface driver uas"}
- {"dt": "2024-12-01T09:54:14.295358", "lvl": "target", "msg": "[    1.559986] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-12-01T09:54:14.295668", "lvl": "target", "msg": "[    1.566046] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-12-01T09:54:14.295943", "lvl": "target", "msg": "[    1.572601] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-12-01T09:54:14.296196", "lvl": "target", "msg": "[    1.578632] usbcore: registered new interface driver cp210x"}
- {"dt": "2024-12-01T09:54:14.296442", "lvl": "target", "msg": "[    1.584225] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2024-12-01T09:54:14.296685", "lvl": "target", "msg": "[    1.590257] mousedev: PS/2 mouse device common for all mice"}
- {"dt": "2024-12-01T09:54:14.342500", "lvl": "target", "msg": "[    1.603679] rpi-rtc soc:rpi_rtc: registered as rtc0"}
- {"dt": "2024-12-01T09:54:14.343018", "lvl": "target", "msg": "[    1.610030] rpi-rtc soc:rpi_rtc: setting system clock to 1970-01-01T00:01:09 UTC (69)"}
- {"dt": "2024-12-01T09:54:14.343328", "lvl": "target", "msg": "[    1.617947] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-12-01T09:54:14.343597", "lvl": "target", "msg": "[    1.624372] bcm2835-wdt bcm2835-wdt: Poweroff handler already present!"}
- {"dt": "2024-12-01T09:54:14.343850", "lvl": "target", "msg": "[    1.630928] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer"}
- {"dt": "2024-12-01T09:54:14.344102", "lvl": "target", "msg": "[    1.639738] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-12-01T09:54:14.344347", "lvl": "target", "msg": "[    1.645948] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-12-01T09:54:14.386524", "lvl": "target", "msg": "[    1.650338] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-12-01T09:54:14.387057", "lvl": "target", "msg": "[    1.656164] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-12-01T09:54:14.387365", "lvl": "target", "msg": "[    1.662219] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ...."}
- {"dt": "2024-12-01T09:54:14.387637", "lvl": "target", "msg": "[    1.668694] hid: raw HID events driver (C) Jiri Kosina"}
- {"dt": "2024-12-01T09:54:14.387893", "lvl": "target", "msg": "[    1.673873] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-12-01T09:54:14.388142", "lvl": "target", "msg": "[    1.679463] usbhid: USB HID core driver"}
- {"dt": "2024-12-01T09:54:14.388386", "lvl": "target", "msg": "[    1.683536] hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available"}
- {"dt": "2024-12-01T09:54:14.389674", "lvl": "target", "msg": "[    1.691853] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-12-01T09:54:14.435667", "lvl": "target", "msg": "[    1.698170] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-12-01T09:54:14.436198", "lvl": "target", "msg": "[    1.703257] Key type dns_resolver registered"}
- {"dt": "2024-12-01T09:54:14.436507", "lvl": "target", "msg": "[    1.710922] registered taskstats version 1"}
- {"dt": "2024-12-01T09:54:14.436819", "lvl": "target", "msg": "[    1.715081] Loading compiled-in X.509 certificates"}
- {"dt": "2024-12-01T09:54:14.437089", "lvl": "target", "msg": "[    1.721626] Key type .fscrypt registered"}
- {"dt": "2024-12-01T09:54:14.437338", "lvl": "target", "msg": "[    1.725562] Key type fscrypt-provisioning registered"}
- {"dt": "2024-12-01T09:54:14.437586", "lvl": "target", "msg": "[    1.731182] brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges:"}
- {"dt": "2024-12-01T09:54:14.438848", "lvl": "target", "msg": "[    1.738185] brcm-pcie 1000120000.pcie:   No bus range found for /axi/pcie@120000, using [bus 00-ff]"}
- {"dt": "2024-12-01T09:54:14.480504", "lvl": "target", "msg": "[    1.747291] brcm-pcie 1000120000.pcie:      MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000"}
- {"dt": "2024-12-01T09:54:14.481072", "lvl": "target", "msg": "[    1.755658] brcm-pcie 1000120000.pcie:      MEM 0x1c00000000..0x1effffffff -> 0x0400000000"}
- {"dt": "2024-12-01T09:54:14.481386", "lvl": "target", "msg": "[    1.763958] brcm-pcie 1000120000.pcie:   IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000"}
- {"dt": "2024-12-01T09:54:14.481662", "lvl": "target", "msg": "[    1.772256] brcm-pcie 1000120000.pcie:   IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000"}
- {"dt": "2024-12-01T09:54:14.481922", "lvl": "target", "msg": "[    1.781719] brcm-pcie 1000120000.pcie: Forcing gen 2"}
- {"dt": "2024-12-01T09:54:14.483707", "lvl": "target", "msg": "[    1.786967] brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-12-01T09:54:14.524467", "lvl": "target", "msg": "[    1.793432] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-12-01T09:54:14.525046", "lvl": "target", "msg": "[    1.798936] pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb])"}
- {"dt": "2024-12-01T09:54:14.525364", "lvl": "target", "msg": "[    1.809500] pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff])"}
- {"dt": "2024-12-01T09:54:14.525643", "lvl": "target", "msg": "[    1.820684] pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400"}
- {"dt": "2024-12-01T09:54:14.525908", "lvl": "target", "msg": "[    1.826730] pci 0000:00:00.0: PME# supported from D0 D3hot"}
- {"dt": "2024-12-01T09:54:14.542865", "lvl": "target", "msg": "[    1.832811] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring"}
- {"dt": "2024-12-01T09:54:14.688834", "lvl": "target", "msg": "[    1.947954] brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC)"}
- {"dt": "2024-12-01T09:54:14.689373", "lvl": "target", "msg": "[    1.954608] pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000"}
- {"dt": "2024-12-01T09:54:14.689688", "lvl": "target", "msg": "[    1.960644] pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff]"}
- {"dt": "2024-12-01T09:54:14.689960", "lvl": "target", "msg": "[    1.966937] pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff]"}
- {"dt": "2024-12-01T09:54:14.690215", "lvl": "target", "msg": "[    1.973229] pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff]"}
- {"dt": "2024-12-01T09:54:14.690465", "lvl": "target", "msg": "[    1.979576] pci 0000:01:00.0: supports D1"}
- {"dt": "2024-12-01T09:54:14.692020", "lvl": "target", "msg": "[    1.983594] pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold"}
- {"dt": "2024-12-01T09:54:14.732329", "lvl": "target", "msg": "[    1.999952] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01"}
- {"dt": "2024-12-01T09:54:14.732917", "lvl": "target", "msg": "[    2.006595] pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-12-01T09:54:14.733235", "lvl": "target", "msg": "[    2.013757] pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff]"}
- {"dt": "2024-12-01T09:54:14.733511", "lvl": "target", "msg": "[    2.020919] pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff]"}
- {"dt": "2024-12-01T09:54:14.733770", "lvl": "target", "msg": "[    2.028082] pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff]"}
- {"dt": "2024-12-01T09:54:14.734023", "lvl": "target", "msg": "[    2.035244] pci 0000:00:00.0: PCI bridge to [bus 01]"}
- {"dt": "2024-12-01T09:54:14.775846", "lvl": "target", "msg": "[    2.040223] pci 0000:00:00.0:   bridge window [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-12-01T09:54:14.776388", "lvl": "target", "msg": "[    2.047385] pci 0000:00:00.0: Max Payload Size set to  256/ 512 (was  128), Max Read Rq  512"}
- {"dt": "2024-12-01T09:54:14.776697", "lvl": "target", "msg": "[    2.055860] pci 0000:01:00.0: Max Payload Size set to  256/ 256 (was  128), Max Read Rq  512"}
- {"dt": "2024-12-01T09:54:14.777008", "lvl": "target", "msg": "[    2.064381] pcieport 0000:00:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-12-01T09:54:14.777269", "lvl": "target", "msg": "[    2.070523] pcieport 0000:00:00.0: PME: Signaling with IRQ 38"}
- {"dt": "2024-12-01T09:54:14.777519", "lvl": "target", "msg": "[    2.076327] pcieport 0000:00:00.0: AER: enabled with IRQ 38"}
- {"dt": "2024-12-01T09:54:14.807272", "lvl": "target", "msg": "[    2.081967] rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200"}
- {"dt": "2024-12-01T09:54:14.807826", "lvl": "target", "msg": "[    2.091050] rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200"}
- {"dt": "2024-12-01T09:54:14.808138", "lvl": "target", "msg": "[    2.100311] rp1 0000:01:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-12-01T09:54:14.810428", "lvl": "target", "msg": "[    2.106770] rp1 0000:01:00.0: chip_id 0x20001927"}
- {"dt": "2024-12-01T09:54:14.870161", "lvl": "target", "msg": "[    2.133929] macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 106 (d8:3a:dd:e5:72:37)"}
- {"dt": "2024-12-01T09:54:14.870710", "lvl": "target", "msg": "[    2.147213] dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels"}
- {"dt": "2024-12-01T09:54:14.871024", "lvl": "target", "msg": "[    2.155728] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-12-01T09:54:14.871298", "lvl": "target", "msg": "[    2.160799] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-12-01T09:54:14.871557", "lvl": "target", "msg": "[    2.168480] xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-12-01T09:54:14.873317", "lvl": "target", "msg": "[    2.177481] xhci-hcd xhci-hcd.0: irq 131, io mem 0x1f00200000"}
- {"dt": "2024-12-01T09:54:14.913328", "lvl": "target", "msg": "[    2.183304] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-12-01T09:54:14.913867", "lvl": "target", "msg": "[    2.188371] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-12-01T09:54:14.914184", "lvl": "target", "msg": "[    2.195619] xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-01T09:54:14.914459", "lvl": "target", "msg": "[    2.201776] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-12-01T09:54:14.914722", "lvl": "target", "msg": "[    2.210074] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-01T09:54:14.914974", "lvl": "target", "msg": "[    2.217326] usb usb1: Product: xHCI Host Controller"}
- {"dt": "2024-12-01T09:54:14.957156", "lvl": "target", "msg": "[    2.222217] usb usb1: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-01T09:54:14.957700", "lvl": "target", "msg": "[    2.228244] usb usb1: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-12-01T09:54:14.958010", "lvl": "target", "msg": "[    2.232790] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-12-01T09:54:14.958284", "lvl": "target", "msg": "[    2.236558] hub 1-0:1.0: 2 ports detected"}
- {"dt": "2024-12-01T09:54:14.958540", "lvl": "target", "msg": "[    2.240680] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-12-01T09:54:14.958795", "lvl": "target", "msg": "[    2.248978] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-01T09:54:14.959040", "lvl": "target", "msg": "[    2.256228] usb usb2: Product: xHCI Host Controller"}
- {"dt": "2024-12-01T09:54:14.960291", "lvl": "target", "msg": "[    2.261119] usb usb2: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-01T09:54:15.001483", "lvl": "target", "msg": "[    2.267155] usb usb2: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-12-01T09:54:15.002023", "lvl": "target", "msg": "[    2.271755] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-12-01T09:54:15.002331", "lvl": "target", "msg": "[    2.275522] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T09:54:15.002603", "lvl": "target", "msg": "[    2.279657] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-12-01T09:54:15.002859", "lvl": "target", "msg": "[    2.284831] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-12-01T09:54:15.003113", "lvl": "target", "msg": "[    2.292509] xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-12-01T09:54:15.003363", "lvl": "target", "msg": "[    2.301606] xhci-hcd xhci-hcd.1: irq 136, io mem 0x1f00300000"}
- {"dt": "2024-12-01T09:54:15.004701", "lvl": "target", "msg": "[    2.307428] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-12-01T09:54:15.045192", "lvl": "target", "msg": "[    2.312495] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-12-01T09:54:15.045750", "lvl": "target", "msg": "[    2.319840] xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-01T09:54:15.046059", "lvl": "target", "msg": "[    2.325975] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-12-01T09:54:15.046337", "lvl": "target", "msg": "[    2.334367] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-01T09:54:15.046595", "lvl": "target", "msg": "[    2.341615] usb usb3: Product: xHCI Host Controller"}
- {"dt": "2024-12-01T09:54:15.046846", "lvl": "target", "msg": "[    2.346506] usb usb3: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-01T09:54:15.048363", "lvl": "target", "msg": "[    2.352627] usb usb3: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-12-01T09:54:15.088896", "lvl": "target", "msg": "[    2.357148] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-12-01T09:54:15.089441", "lvl": "target", "msg": "[    2.360916] hub 3-0:1.0: 2 ports detected"}
- {"dt": "2024-12-01T09:54:15.089749", "lvl": "target", "msg": "[    2.365019] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-12-01T09:54:15.090025", "lvl": "target", "msg": "[    2.373415] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-01T09:54:15.090283", "lvl": "target", "msg": "[    2.380685] usb usb4: Product: xHCI Host Controller"}
- {"dt": "2024-12-01T09:54:15.090537", "lvl": "target", "msg": "[    2.385576] usb usb4: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-01T09:54:15.090784", "lvl": "target", "msg": "[    2.391756] usb usb4: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-12-01T09:54:15.092014", "lvl": "target", "msg": "[    2.396280] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-12-01T09:54:15.133591", "lvl": "target", "msg": "[    2.400046] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T09:54:15.134134", "lvl": "target", "msg": "[    2.404636] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-01T09:54:15.134449", "lvl": "target", "msg": "[    2.412907] platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-12-01T09:54:15.134723", "lvl": "target", "msg": "[    2.421137] platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-12-01T09:54:15.134982", "lvl": "target", "msg": "[    2.429193] platform 1000800000.codec: Adding to iommu group 0"}
- {"dt": "2024-12-01T09:54:15.136729", "lvl": "target", "msg": "[    2.435051] platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-12-01T09:54:15.177883", "lvl": "target", "msg": "[    2.443448] platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-12-01T09:54:15.178458", "lvl": "target", "msg": "[    2.451669] platform 1000880000.pisp_be: Adding to iommu group 0"}
- {"dt": "2024-12-01T09:54:15.178772", "lvl": "target", "msg": "[    2.457899] platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-12-01T09:54:15.179044", "lvl": "target", "msg": "[    2.465758] platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-12-01T09:54:15.179302", "lvl": "target", "msg": "[    2.473981] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-01T09:54:15.181023", "lvl": "target", "msg": "[    2.480960] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-01T09:54:15.227306", "lvl": "target", "msg": "[    2.489415] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-01T09:54:15.227853", "lvl": "target", "msg": "[    2.496445] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-01T09:54:15.228167", "lvl": "target", "msg": "[    2.504890] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-01T09:54:15.228445", "lvl": "target", "msg": "[    2.511921] sdhci-brcmstb 1000fff000.mmc: Got CD GPIO"}
- {"dt": "2024-12-01T09:54:15.228707", "lvl": "target", "msg": "[    2.517192] mmc0: CQHCI version 5.10"}
- {"dt": "2024-12-01T09:54:15.229001", "lvl": "target", "msg": "[    2.517252] mmc1: CQHCI version 5.10"}
- {"dt": "2024-12-01T09:54:15.230502", "lvl": "target", "msg": "[    2.517373] of_cfs_init"}
- {"dt": "2024-12-01T09:54:15.230898", "lvl": "target", "msg": "[    2.517410] of_cfs_init: OK"}
- {"dt": "2024-12-01T09:54:15.267266", "lvl": "target", "msg": "[    2.556562] mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit"}
- {"dt": "2024-12-01T09:54:15.470861", "lvl": "target", "msg": "[    2.716489] mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit"}
- {"dt": "2024-12-01T09:54:15.471408", "lvl": "target", "msg": "[    2.731425] macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL)"}
- {"dt": "2024-12-01T09:54:15.471777", "lvl": "target", "msg": "[    2.742350] macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode"}
- {"dt": "2024-12-01T09:54:15.472087", "lvl": "target", "msg": "[    2.752566] pps pps0: new PPS source ptp0"}
- {"dt": "2024-12-01T09:54:15.472387", "lvl": "target", "msg": "[    2.756624] macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered."}
- {"dt": "2024-12-01T09:54:15.473977", "lvl": "target", "msg": "[    2.756998] mmc1: new ultra high speed DDR50 SDIO card at address 0001"}
- {"dt": "2024-12-01T09:54:18.554696", "lvl": "target", "msg": "[    5.845077] macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-12-01T09:54:18.621390", "lvl": "target", "msg": "[    5.875946] Sending DHCP requests ., OK"}
- {"dt": "2024-12-01T09:54:18.621550", "lvl": "target", "msg": "[    5.895801] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.68.231"}
- {"dt": "2024-12-01T09:54:18.621635", "lvl": "target", "msg": "[    5.903755] IP-Config: Complete:"}
- {"dt": "2024-12-01T09:54:18.621699", "lvl": "target", "msg": "[    5.906990]      device=eth0, hwaddr=d8:3a:dd:e5:72:37, ipaddr=192.168.68.231, mask=255.255.255.0, gw=192.168.68.1"}
- {"dt": "2024-12-01T09:54:18.621761", "lvl": "target", "msg": "[    5.917383]      host=192.168.68.231, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-12-01T09:54:18.624548", "lvl": "target", "msg": "[    5.924370]      bootserver=192.168.68.1, rootserver=192.168.68.1, rootpath="}
- {"dt": "2024-12-01T09:54:18.624681", "lvl": "target", "msg": "[    5.924372]      nameserver0=10.1.1.1"}
- {"dt": "2024-12-01T09:54:18.662392", "lvl": "target", "msg": "[    5.935229] clk: Disabling unused clocks"}
- {"dt": "2024-12-01T09:54:18.662504", "lvl": "target", "msg": "[    5.948539] ALSA device list:"}
- {"dt": "2024-12-01T09:54:18.662575", "lvl": "target", "msg": "[    5.951512]   No soundcards found."}
- {"dt": "2024-12-01T09:54:18.662638", "lvl": "target", "msg": "[    5.954977] uart-pl011 107d001000.serial: no DMA platform data"}
- {"dt": "2024-12-01T09:54:18.665475", "lvl": "target", "msg": "[    5.960845] Waiting 7 sec before mounting root device..."}
- {"dt": "2024-12-01T09:54:25.836643", "lvl": "target", "msg": "[   13.139981] RAMDISK: ext2 filesystem found at block 0"}
- {"dt": "2024-12-01T09:54:25.976766", "lvl": "target", "msg": "[   13.145057] 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-01T09:54:26.038082", "lvl": "target", "msg": "[   13.154816] |\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\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-01T09:54:26.038681", "lvl": "target", "msg": "[   13.312069] EXT4-fs (ram0): mounted filesystem d291e3c8-a47c-4bba-b248-a917c9104949 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-12-01T09:54:26.039002", "lvl": "target", "msg": "[   13.323798] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2024-12-01T09:54:26.039278", "lvl": "target", "msg": "[   13.329684] devtmpfs: mounted"}
- {"dt": "2024-12-01T09:54:26.039535", "lvl": "target", "msg": "[   13.336262] Freeing unused kernel memory: 5568K"}
- {"dt": "2024-12-01T09:54:26.041236", "lvl": "target", "msg": "[   13.340861] Run /sbin/init as init process"}
- {"dt": "2024-12-01T09:54:26.081449", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-01T09:54:26.082002", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2024-12-01T09:54:26.082318", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2024-12-01T09:54:26.082596", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2024-12-01T09:54:26.082860", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2024-12-01T09:54:26.083118", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2024-12-01T09:54:26.124328", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-01T09:54:26.124921", "lvl": "target", "msg": "/sbin/init[1]: sta[   13.396256] nbd0: detected capacity change from 0 to 8962048"}
- {"dt": "2024-12-01T09:54:26.125277", "lvl": "target", "msg": "rting initrd script"}
- {"dt": "2024-12-01T09:54:26.125555", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2024-12-01T09:54:26.125818", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2024-12-01T09:54:26.126070", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2024-12-01T09:54:26.126319", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2024-12-01T09:54:26.126560", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2024-12-01T09:54:26.126799", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2024-12-01T09:54:26.127040", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2024-12-01T09:54:26.128022", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2024-12-01T09:54:26.150714", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.68.1:61999"}
- {"dt": "2024-12-01T09:54:26.151252", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2024-12-01T09:54:26.153809", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2024-12-01T09:54:27.627571", "lvl": "target", "msg": "[   14.892686] EXT4-fs (nbd0): mounted filesystem 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-12-01T09:54:27.628411", "lvl": "target", "msg": "/sbin/init[1]: Moving /dev to new rootfs"}
- {"dt": "2024-12-01T09:54:27.629014", "lvl": "target", "msg": "/sbin/init[1]: Moving /proc to new rootfs"}
- {"dt": "2024-12-01T09:54:27.630397", "lvl": "target", "msg": "/sbin/init[1]: Moving /sys to new rootfs"}
- {"dt": "2024-12-01T09:54:27.631000", "lvl": "target", "msg": "/sbin/init[1]: Moving /tmp to new rootfs"}
- {"dt": "2024-12-01T09:54:27.631509", "lvl": "target", "msg": "/sbin/init[1]: Moving /run to new rootfs"}
- {"dt": "2024-12-01T09:54:27.631913", "lvl": "target", "msg": "/sbin/init[1]: Switching to new rootfs"}
- {"dt": "2024-12-01T09:54:27.988988", "lvl": "target", "msg": "/sbin/init[1]: find_active_interface: first active interface is eth0"}
- {"dt": "2024-12-01T09:54:27.992063", "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-01T09:54:28.071760", "lvl": "target", "msg": "/sbin/init[1]: Unmounting /tmp and /run"}
- {"dt": "2024-12-01T09:54:28.151619", "lvl": "target", "msg": "/sbin/init[1]: Exec'ing systemd"}
- {"dt": "2024-12-01T09:54:28.191856", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-01T09:54:28.192383", "lvl": "target", "msg": "      ___   ________       ____              __  _"}
- {"dt": "2024-12-01T09:54:28.192764", "lvl": "target", "msg": "     /   | / ____/ /      / __ )____  ____  / /_(_)___  ____ _"}
- {"dt": "2024-12-01T09:54:28.193054", "lvl": "target", "msg": "    / /| |/ / __/ /      / __  / __ \\/ __ \\/ __/ / __ \\/ __ `/"}
- {"dt": "2024-12-01T09:54:28.194032", "lvl": "target", "msg": "   / ___ / /_/ / /___   / /_/ / /_/ / /_/ / /_/ / / / / /_/ /   _     _     _"}
- {"dt": "2024-12-01T09:54:28.194322", "lvl": "target", "msg": "  /_/  |_\\____/_____/  /_____/\\____/\\____/\\__/_/_/ /_/\\__, /   (_)   (_)   (_)"}
- {"dt": "2024-12-01T09:54:28.210518", "lvl": "target", "msg": "_____________________________________________________/____/_____________________"}
- {"dt": "2024-12-01T09:54:28.841088", "lvl": "target", "msg": "[   16.130738] systemd[1]: System time before build time, advancing clock."}
- {"dt": "2024-12-01T09:54:29.009044", "lvl": "target", "msg": "[   16.295802] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-12-01T09:54:29.009558", "lvl": "target", "msg": "[   16.301221] Segment Routing with IPv6"}
- {"dt": "2024-12-01T09:54:29.012282", "lvl": "target", "msg": "[   16.304927] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-12-01T09:54:29.155487", "lvl": "target", "msg": "[   16.420818] 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-01T09:54:29.158153", "lvl": "target", "msg": "[   16.452799] systemd[1]: Detected architecture arm64."}
- {"dt": "2024-12-01T09:54:29.173618", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:29.174144", "lvl": "target", "msg": "Welcome to Automotive Grade Linux 18.0.2 (ricefish)!"}
- {"dt": "2024-12-01T09:54:29.174539", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:29.189647", "lvl": "target", "msg": "[   16.491648] systemd[1]: Hostname set to <raspberrypi5>."}
- {"dt": "2024-12-01T09:54:29.231473", "lvl": "target", "msg": "[   16.521245] systemd[1]: Initializing machine ID from random generator."}
- {"dt": "2024-12-01T09:54:29.781091", "lvl": "target", "msg": "[   17.070901] systemd[1]: Queued start job for default target Graphical Interface."}
- {"dt": "2024-12-01T09:54:29.884850", "lvl": "target", "msg": "[   17.116810] systemd[1]: Created slice Slice /system/getty."}
- {"dt": "2024-12-01T09:54:29.885331", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/getty."}
- {"dt": "2024-12-01T09:54:29.885648", "lvl": "target", "msg": "[   17.136351] systemd[1]: Created slice Slice /system/modprobe."}
- {"dt": "2024-12-01T09:54:29.885923", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/modprobe."}
- {"dt": "2024-12-01T09:54:29.886896", "lvl": "target", "msg": "[   17.156297] systemd[1]: Created slice Slice /system/serial-getty."}
- {"dt": "2024-12-01T09:54:29.887179", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/serial-getty."}
- {"dt": "2024-12-01T09:54:29.887430", "lvl": "target", "msg": "[   17.176279] systemd[1]: Created slice Slice /system/wireplumber."}
- {"dt": "2024-12-01T09:54:29.888384", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/wireplumber."}
- {"dt": "2024-12-01T09:54:29.953793", "lvl": "target", "msg": "[   17.196138] systemd[1]: Created slice User and Session Slice."}
- {"dt": "2024-12-01T09:54:29.954263", "lvl": "target", "msg": "[  OK  ] Created slice User and Session Slice."}
- {"dt": "2024-12-01T09:54:29.955312", "lvl": "target", "msg": "[   17.216043] systemd[1]: Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-12-01T09:54:29.955623", "lvl": "target", "msg": "[  OK  ] Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-12-01T09:54:29.955892", "lvl": "target", "msg": "[   17.240016] systemd[1]: Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-12-01T09:54:29.956146", "lvl": "target", "msg": "[  OK  ] Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-12-01T09:54:29.957160", "lvl": "target", "msg": "[   17.259980] systemd[1]: Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-12-01T09:54:30.026266", "lvl": "target", "msg": "         Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-12-01T09:54:30.026742", "lvl": "target", "msg": "[   17.280001] systemd[1]: Reached target Host and Network Name Lookups."}
- {"dt": "2024-12-01T09:54:30.027060", "lvl": "target", "msg": "[  OK  ] Reached target Host and Network Name Lookups."}
- {"dt": "2024-12-01T09:54:30.027338", "lvl": "target", "msg": "[   17.299969] systemd[1]: Reached target Path Units."}
- {"dt": "2024-12-01T09:54:30.027596", "lvl": "target", "msg": "[  OK  ] Reached target Path Units."}
- {"dt": "2024-12-01T09:54:30.027851", "lvl": "target", "msg": "[   17.315982] systemd[1]: Reached target Remote File Systems."}
- {"dt": "2024-12-01T09:54:30.028098", "lvl": "target", "msg": "[  OK  ] Reached target Remote File Systems."}
- {"dt": "2024-12-01T09:54:30.029546", "lvl": "target", "msg": "[   17.331971] systemd[1]: Reached target Slice Units."}
- {"dt": "2024-12-01T09:54:30.054833", "lvl": "target", "msg": "[  OK  ] Reached target Slice Units."}
- {"dt": "2024-12-01T09:54:30.055392", "lvl": "target", "msg": "[   17.347979] systemd[1]: Reached target Swaps."}
- {"dt": "2024-12-01T09:54:30.058161", "lvl": "target", "msg": "[  OK  ] Reached target Swaps."}
- {"dt": "2024-12-01T09:54:30.129503", "lvl": "target", "msg": "[   17.367053] systemd[1]: Listening on Process Core Dump Socket."}
- {"dt": "2024-12-01T09:54:30.130036", "lvl": "target", "msg": "[  OK  ] Listening on Process Core Dump Socket."}
- {"dt": "2024-12-01T09:54:30.130360", "lvl": "target", "msg": "[   17.384050] systemd[1]: Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-12-01T09:54:30.130642", "lvl": "target", "msg": "[  OK  ] Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-12-01T09:54:30.130906", "lvl": "target", "msg": "[   17.404253] systemd[1]: Listening on Journal Audit Socket."}
- {"dt": "2024-12-01T09:54:30.131157", "lvl": "target", "msg": "[  OK  ] Listening on Journal Audit Socket."}
- {"dt": "2024-12-01T09:54:30.131404", "lvl": "target", "msg": "[   17.420098] systemd[1]: Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-12-01T09:54:30.132406", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-12-01T09:54:30.202479", "lvl": "target", "msg": "[   17.440122] systemd[1]: Listening on Journal Socket."}
- {"dt": "2024-12-01T09:54:30.202932", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket."}
- {"dt": "2024-12-01T09:54:30.203246", "lvl": "target", "msg": "[   17.456157] systemd[1]: Listening on Network Service Netlink Socket."}
- {"dt": "2024-12-01T09:54:30.203523", "lvl": "target", "msg": "[  OK  ] Listening on Network Service Netlink Socket."}
- {"dt": "2024-12-01T09:54:30.203780", "lvl": "target", "msg": "[   17.476129] systemd[1]: Listening on udev Control Socket."}
- {"dt": "2024-12-01T09:54:30.204035", "lvl": "target", "msg": "[  OK  ] Listening on udev Control Socket."}
- {"dt": "2024-12-01T09:54:30.204279", "lvl": "target", "msg": "[   17.492080] systemd[1]: Listening on udev Kernel Socket."}
- {"dt": "2024-12-01T09:54:30.204523", "lvl": "target", "msg": "[  OK  ] Listening on udev Kernel Socket."}
- {"dt": "2024-12-01T09:54:30.205584", "lvl": "target", "msg": "[   17.508081] systemd[1]: Listening on User Database Manager Socket."}
- {"dt": "2024-12-01T09:54:30.237741", "lvl": "target", "msg": "[  OK  ] Listening on User Database Manager Socket."}
- {"dt": "2024-12-01T09:54:30.240873", "lvl": "target", "msg": "[   17.528113] systemd[1]: Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages)."}
- {"dt": "2024-12-01T09:54:30.293600", "lvl": "target", "msg": "[   17.560062] systemd[1]: Mounting POSIX Message Queue File System..."}
- {"dt": "2024-12-01T09:54:30.294076", "lvl": "target", "msg": "         Mounting POSIX Message Queue File System..."}
- {"dt": "2024-12-01T09:54:30.294420", "lvl": "target", "msg": "[   17.580824] systemd[1]: Mounting Kernel Debug File System..."}
- {"dt": "2024-12-01T09:54:30.295887", "lvl": "target", "msg": "         Mounting Kernel Debug Fi[   17.587514] systemd[1]: Mounting Kernel Trace File System..."}
- {"dt": "2024-12-01T09:54:30.296326", "lvl": "target", "msg": "le System..."}
- {"dt": "2024-12-01T09:54:30.332397", "lvl": "target", "msg": "         Mounting Kernel Trace File System..."}
- {"dt": "2024-12-01T09:54:30.332895", "lvl": "target", "msg": "[   17.610237] systemd[1]: Mounting Temporary Directory /tmp..."}
- {"dt": "2024-12-01T09:54:30.335594", "lvl": "target", "msg": "         Mounting Temporary Direc[   17.625311] systemd[1]: Starting Create List of Static Device Nodes..."}
- {"dt": "2024-12-01T09:54:30.336029", "lvl": "target", "msg": "tory /tmp..."}
- {"dt": "2024-12-01T09:54:30.416373", "lvl": "target", "msg": "         Starting Create List of [   17.649262] systemd[1]: Starting Load Kernel Module configfs..."}
- {"dt": "2024-12-01T09:54:30.416893", "lvl": "target", "msg": "Static Device Nodes..."}
- {"dt": "2024-12-01T09:54:30.417246", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.669180] systemd[1]: Starting Load Kernel Module drm..."}
- {"dt": "2024-12-01T09:54:30.417526", "lvl": "target", "msg": "ule configfs..."}
- {"dt": "2024-12-01T09:54:30.417792", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.689134] systemd[1]: Starting Load Kernel Module fuse..."}
- {"dt": "2024-12-01T09:54:30.418051", "lvl": "target", "msg": "ule drm..."}
- {"dt": "2024-12-01T09:54:30.419706", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.709116] systemd[1]: Starting SELinux autorelabel service loading..."}
- {"dt": "2024-12-01T09:54:30.420160", "lvl": "target", "msg": "ule fuse..."}
- {"dt": "2024-12-01T09:54:30.451377", "lvl": "target", "msg": "         Starting SELinux autorel[   17.729046] systemd[1]: Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-01T09:54:30.451850", "lvl": "target", "msg": "abel service loading..."}
- {"dt": "2024-12-01T09:54:30.453895", "lvl": "target", "msg": "[   17.748139] fuse: init (API version 7.39)"}
- {"dt": "2024-12-01T09:54:30.483317", "lvl": "target", "msg": "         Starting SELinux init fo[   17.764108] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/)."}
- {"dt": "2024-12-01T09:54:30.483825", "lvl": "target", "msg": "r /dev service loading..."}
- {"dt": "2024-12-01T09:54:30.501813", "lvl": "target", "msg": "[   17.793633] systemd[1]: Starting Journal Service..."}
- {"dt": "2024-12-01T09:54:30.502308", "lvl": "target", "msg": "         Starting Journal Service..."}
- {"dt": "2024-12-01T09:54:30.541104", "lvl": "target", "msg": "[   17.824713] systemd[1]: Starting Load Kernel Modules..."}
- {"dt": "2024-12-01T09:54:30.544233", "lvl": "target", "msg": "         Starting Load Kernel Modules...[   17.831704] systemd[1]: Starting Remount Root and Kernel File Systems..."}
- {"dt": "2024-12-01T09:54:30.544692", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:30.624567", "lvl": "target", "msg": "         Starting Remount Root an[   17.853321] systemd[1]: Starting Coldplug All udev Devices..."}
- {"dt": "2024-12-01T09:54:30.625135", "lvl": "target", "msg": "d Kernel File Systems..."}
- {"dt": "2024-12-01T09:54:30.625490", "lvl": "target", "msg": "         Starting Coldplug All udev Devic[   17.873573] systemd[1]: Mounted POSIX Message Queue File System."}
- {"dt": "2024-12-01T09:54:30.625772", "lvl": "target", "msg": "es..."}
- {"dt": "2024-12-01T09:54:30.626031", "lvl": "target", "msg": "[  OK  ] Mounted POSIX Message Queue File System."}
- {"dt": "2024-12-01T09:54:30.626284", "lvl": "target", "msg": "[   17.904175] systemd[1]: Mounted Kernel Debug File System."}
- {"dt": "2024-12-01T09:54:30.626532", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Debug File System."}
- {"dt": "2024-12-01T09:54:30.626776", "lvl": "target", "msg": "[   17.920136] systemd[1]: Mounted Kernel Trace File System."}
- {"dt": "2024-12-01T09:54:30.627844", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Trace File System."}
- {"dt": "2024-12-01T09:54:30.657821", "lvl": "target", "msg": "[   17.936704] systemd[1]: Mounted Temporary Directory /tmp."}
- {"dt": "2024-12-01T09:54:30.658296", "lvl": "target", "msg": "[  OK  ] Mounted Temporary Directory /tmp."}
- {"dt": "2024-12-01T09:54:30.660560", "lvl": "target", "msg": "[   17.948080] EXT4-fs (nbd0): re-mounted 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w. Quota mode: none."}
- {"dt": "2024-12-01T09:54:30.678767", "lvl": "target", "msg": "[   17.968909] systemd[1]: Finished Create List of Static Device Nodes."}
- {"dt": "2024-12-01T09:54:30.681987", "lvl": "target", "msg": "[  OK  ] Finished Create List of Static Device Nodes."}
- {"dt": "2024-12-01T09:54:30.705745", "lvl": "target", "msg": "[   17.996019] systemd-journald[195]: Collecting audit messages is enabled."}
- {"dt": "2024-12-01T09:54:30.754340", "lvl": "target", "msg": "[   18.024357] audit: type=1130 audit(1725908514.891:2): 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-01T09:54:30.754910", "lvl": "target", "msg": "[   18.024706] systemd[1]: modprobe@configfs.service: Deactivated successfully."}
- {"dt": "2024-12-01T09:54:30.755345", "lvl": "target", "msg": "[   18.050622] systemd[1]: Finished Load Kernel Module configfs."}
- {"dt": "2024-12-01T09:54:30.755735", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module configfs."}
- {"dt": "2024-12-01T09:54:30.800452", "lvl": "target", "msg": "[   18.059030] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-12-01T09:54:30.800998", "lvl": "target", "msg": "[   18.070907] snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored"}
- {"dt": "2024-12-01T09:54:30.801393", "lvl": "target", "msg": "[   18.080078] audit: type=1130 audit(1725908514.947:3): 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-01T09:54:30.801714", "lvl": "target", "msg": "[   18.080407] systemd[1]: modprobe@drm.service: Deactivated successfully."}
- {"dt": "2024-12-01T09:54:30.832395", "lvl": "target", "msg": "[   18.099041] audit: type=1131 audit(1725908514.947: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-01T09:54:30.832907", "lvl": "target", "msg": "[   18.124919] systemd[1]: Finished Load Kernel Module drm."}
- {"dt": "2024-12-01T09:54:30.835018", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module drm."}
- {"dt": "2024-12-01T09:54:30.886232", "lvl": "target", "msg": "[   18.144444] systemd[1]: Started Journal Service."}
- {"dt": "2024-12-01T09:54:30.886704", "lvl": "target", "msg": "[   18.149105] audit: type=1130 audit(1725908515.011:5): 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-01T09:54:30.889468", "lvl": "target", "msg": "[   18.167658] audit: type=1131 audit(1725908515.011: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-01T09:54:30.889905", "lvl": "target", "msg": "[  OK  ] Started Journal Service."}
- {"dt": "2024-12-01T09:54:30.938691", "lvl": "target", "msg": "[   18.200051] audit: type=1130 audit(1725908515.067:7): 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-01T09:54:30.939897", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module fuse."}
- {"dt": "2024-12-01T09:54:30.942058", "lvl": "target", "msg": "[   18.231991] audit: type=1130 audit(1725908515.099: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-01T09:54:30.976119", "lvl": "target", "msg": "[   18.250594] audit: type=1131 audit(1725908515.099: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-01T09:54:30.976597", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-12-01T09:54:31.027998", "lvl": "target", "msg": "[   18.287985] audit: type=1130 audit(1725908515.155: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-01T09:54:31.028503", "lvl": "target", "msg": "[   18.307197] audit: type=1131 audit(1725908515.155: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-01T09:54:31.030506", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-12-01T09:54:31.069867", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Modules."}
- {"dt": "2024-12-01T09:54:31.070368", "lvl": "target", "msg": "[  OK  ] Finished Remount Root and Kernel File Systems."}
- {"dt": "2024-12-01T09:54:31.070726", "lvl": "target", "msg": "[  OK  ] Finished Coldplug All udev Devices."}
- {"dt": "2024-12-01T09:54:31.104889", "lvl": "target", "msg": "         Mounting FUSE Control File System..."}
- {"dt": "2024-12-01T09:54:31.170663", "lvl": "target", "msg": "         Mounting Kernel Configuration File System..."}
- {"dt": "2024-12-01T09:54:31.171126", "lvl": "target", "msg": "         Starting Flush Journal to Persistent Storage..."}
- {"dt": "2024-12-01T09:54:31.171438", "lvl": "target", "msg": "         Starting Apply Kernel Variables..."}
- {"dt": "2024-12-01T09:54:31.171714", "lvl": "target", "msg": "[   18.455988] systemd-journald[195]: Received client request to flush runtime journal."}
- {"dt": "2024-12-01T09:54:31.172375", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev gracefully..."}
- {"dt": "2024-12-01T09:54:31.238535", "lvl": "target", "msg": "[  OK  ] Mounted FUSE Control File System."}
- {"dt": "2024-12-01T09:54:31.238998", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Configuration File System."}
- {"dt": "2024-12-01T09:54:31.239318", "lvl": "target", "msg": "[  OK  ] Finished Flush Journal to Persistent Storage."}
- {"dt": "2024-12-01T09:54:31.239590", "lvl": "target", "msg": "[  OK  ] Finished Apply Kernel Variables."}
- {"dt": "2024-12-01T09:54:31.241719", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev gracefully."}
- {"dt": "2024-12-01T09:54:31.273590", "lvl": "target", "msg": "         Starting Create System Users..."}
- {"dt": "2024-12-01T09:54:31.369381", "lvl": "target", "msg": "         Starting User Database Manager..."}
- {"dt": "2024-12-01T09:54:31.385334", "lvl": "target", "msg": "[  OK  ] Started User Database Manager."}
- {"dt": "2024-12-01T09:54:31.401352", "lvl": "target", "msg": "[  OK  ] Finished Create System Users."}
- {"dt": "2024-12-01T09:54:31.433227", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev..."}
- {"dt": "2024-12-01T09:54:31.449261", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev."}
- {"dt": "2024-12-01T09:54:31.471138", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Local File Systems."}
- {"dt": "2024-12-01T09:54:31.503154", "lvl": "target", "msg": "         Mounting /var/volatile..."}
- {"dt": "2024-12-01T09:54:31.528124", "lvl": "target", "msg": "         Starting Rule-based Manager for Device Events and Files..."}
- {"dt": "2024-12-01T09:54:31.528599", "lvl": "target", "msg": "[  OK  ] Mounted /var/volatile."}
- {"dt": "2024-12-01T09:54:31.543943", "lvl": "target", "msg": "         Starting Load/Save OS Random Seed..."}
- {"dt": "2024-12-01T09:54:31.585860", "lvl": "target", "msg": "[  OK  ] Reached target Local File Systems."}
- {"dt": "2024-12-01T09:54:31.586320", "lvl": "target", "msg": "         Starting Rebuild Dynamic Linker Cache..."}
- {"dt": "2024-12-01T09:54:31.588081", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-12-01T09:54:31.604974", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-12-01T09:54:31.619900", "lvl": "target", "msg": "         Starting Create Volatile Files and Directories..."}
- {"dt": "2024-12-01T09:54:31.636951", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-12-01T09:54:31.652982", "lvl": "target", "msg": "[  OK  ] Finished Load/Save OS Random Seed."}
- {"dt": "2024-12-01T09:54:31.709686", "lvl": "target", "msg": "         Starting Generated file SELinux relabeling..."}
- {"dt": "2024-12-01T09:54:31.712913", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-12-01T09:54:31.728807", "lvl": "target", "msg": "[  OK  ] Finished Create Volatile Files and Directories."}
- {"dt": "2024-12-01T09:54:31.756502", "lvl": "target", "msg": "         Starting Load Audit Rules..."}
- {"dt": "2024-12-01T09:54:31.759717", "lvl": "target", "msg": "         Starting Rebuild Journal Catalog..."}
- {"dt": "2024-12-01T09:54:32.015215", "lvl": "target", "msg": "[  OK  ] Finished Generated file SELinux relabeling."}
- {"dt": "2024-12-01T09:54:32.031092", "lvl": "target", "msg": "[  OK  ] Finished Rebuild Journal Catalog."}
- {"dt": "2024-12-01T09:54:32.158931", "lvl": "target", "msg": "[  OK  ] Finished Load Audit Rules."}
- {"dt": "2024-12-01T09:54:32.190880", "lvl": "target", "msg": "         Starting Security Audit Logging Service..."}
- {"dt": "2024-12-01T09:54:32.281740", "lvl": "target", "msg": "[  OK  ] Started Rule-based Manager for Device Events and Files."}
- {"dt": "2024-12-01T09:54:32.282256", "lvl": "target", "msg": "[  OK  ] Started Security Audit Logging Service."}
- {"dt": "2024-12-01T09:54:32.345575", "lvl": "target", "msg": "         Starting Record System Boot/Shutdown in UTMP..."}
- {"dt": "2024-12-01T09:54:32.377432", "lvl": "target", "msg": "[  OK  ] Finished Record System Boot/Shutdown in UTMP."}
- {"dt": "2024-12-01T09:54:32.505220", "lvl": "target", "msg": "[   19.795084] input: pwr_button as /devices/platform/pwr_button/input/input0"}
- {"dt": "2024-12-01T09:54:32.552544", "lvl": "target", "msg": "[   19.821732] rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040"}
- {"dt": "2024-12-01T09:54:32.553072", "lvl": "target", "msg": "[   19.830204] rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1"}
- {"dt": "2024-12-01T09:54:32.553400", "lvl": "target", "msg": "[   19.837907] rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040"}
- {"dt": "2024-12-01T09:54:32.554402", "lvl": "target", "msg": "[   19.845857] rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2"}
- {"dt": "2024-12-01T09:54:32.554699", "lvl": "target", "msg": "[   19.853556] rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020"}
- {"dt": "2024-12-01T09:54:32.615040", "lvl": "target", "msg": "[   19.861397] rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3"}
- {"dt": "2024-12-01T09:54:32.615799", "lvl": "target", "msg": "[   19.869026] rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020"}
- {"dt": "2024-12-01T09:54:32.616289", "lvl": "target", "msg": "[   19.876835] rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4"}
- {"dt": "2024-12-01T09:54:32.616805", "lvl": "target", "msg": "[   19.880559] macb 1f00100000.ethernet end0: renamed from eth0 (while UP)"}
- {"dt": "2024-12-01T09:54:32.617249", "lvl": "target", "msg": "[   19.885421] rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000"}
- {"dt": "2024-12-01T09:54:32.618660", "lvl": "target", "msg": "[   19.908086] rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0"}
- {"dt": "2024-12-01T09:54:32.650076", "lvl": "target", "msg": "[   19.950423] mc: Linux media interface: v0.10"}
- {"dt": "2024-12-01T09:54:32.698064", "lvl": "target", "msg": "         Starting Virtual Console Setup..."}
- {"dt": "2024-12-01T09:54:32.713955", "lvl": "target", "msg": "[  OK  ] Stopped Virtual Console Setup."}
- {"dt": "2024-12-01T09:54:32.729954", "lvl": "target", "msg": "         Starting Virtual Console Setup..."}
- {"dt": "2024-12-01T09:54:32.757949", "lvl": "target", "msg": "[   20.047647] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-12-01T09:54:32.816326", "lvl": "target", "msg": "[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch."}
- {"dt": "2024-12-01T09:54:32.929865", "lvl": "target", "msg": "[   20.195939] Bluetooth: Core ver 2.22"}
- {"dt": "2024-12-01T09:54:32.930417", "lvl": "target", "msg": "[   20.199746] NET: Registered PF_BLUETOOTH protocol family"}
- {"dt": "2024-12-01T09:54:32.930767", "lvl": "target", "msg": "[   20.205192] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2024-12-01T09:54:32.931892", "lvl": "target", "msg": "[   20.211851] Bluetooth: HCI socket layer initialized"}
- {"dt": "2024-12-01T09:54:32.932259", "lvl": "target", "msg": "[   20.216906] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2024-12-01T09:54:32.932618", "lvl": "target", "msg": "[   20.222238] Bluetooth: SCO socket layer initialized"}
- {"dt": "2024-12-01T09:54:32.933777", "lvl": "target", "msg": "[   20.230268] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-12-01T09:54:32.974279", "lvl": "target", "msg": "[   20.235837] pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-12-01T09:54:32.974754", "lvl": "target", "msg": "[   20.248685] pispbe 1000880000.pisp_be: pispbe_probe: HW version:  0x02252700"}
- {"dt": "2024-12-01T09:54:32.975068", "lvl": "target", "msg": "[   20.250367] rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-12-01T09:54:32.975342", "lvl": "target", "msg": "[   20.250743] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2024-12-01T09:54:32.975606", "lvl": "target", "msg": "[   20.250747] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2024-12-01T09:54:32.975857", "lvl": "target", "msg": "[   20.250748] Bluetooth: HCI UART protocol BCSP registered"}
- {"dt": "2024-12-01T09:54:32.976902", "lvl": "target", "msg": "[   20.250769] Bluetooth: HCI UART protocol Three-wire (H5) registered"}
- {"dt": "2024-12-01T09:54:33.017922", "lvl": "target", "msg": "[   20.250820] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2024-12-01T09:54:33.018408", "lvl": "target", "msg": "[   20.250905] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator"}
- {"dt": "2024-12-01T09:54:33.018728", "lvl": "target", "msg": "[   20.250969] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator"}
- {"dt": "2024-12-01T09:54:33.019717", "lvl": "target", "msg": "[   20.255960] pispbe 1000880000.pisp_be: pispbe_probe: BatchStatus: 0x00000000"}
- {"dt": "2024-12-01T09:54:33.020056", "lvl": "target", "msg": "[   20.263357] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2024-12-01T09:54:33.021162", "lvl": "target", "msg": "[   20.267748] pispbe 1000880000.pisp_be: pispbe_probe: Status:      0x00000000"}
- {"dt": "2024-12-01T09:54:33.061982", "lvl": "target", "msg": "[   20.267750] pispbe 1000880000.pisp_be: Register nodes for group 0"}
- {"dt": "2024-12-01T09:54:33.062439", "lvl": "target", "msg": "[   20.267819] pispbe 1000880000.pisp_be: input device node registered as /dev/video20"}
- {"dt": "2024-12-01T09:54:33.062742", "lvl": "target", "msg": "[   20.267845] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video21"}
- {"dt": "2024-12-01T09:54:33.063014", "lvl": "target", "msg": "[   20.276596] Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'"}
- {"dt": "2024-12-01T09:54:33.063272", "lvl": "target", "msg": "[   20.278368] rpivid 1000800000.codec: Device registered as /dev/video19"}
- {"dt": "2024-12-01T09:54:33.065180", "lvl": "target", "msg": "[   20.278391] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video22"}
- {"dt": "2024-12-01T09:54:33.105633", "lvl": "target", "msg": "[   20.278412] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video23"}
- {"dt": "2024-12-01T09:54:33.106118", "lvl": "target", "msg": "[   20.278430] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video24"}
- {"dt": "2024-12-01T09:54:33.107209", "lvl": "target", "msg": "[   20.278448] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video25"}
- {"dt": "2024-12-01T09:54:33.107531", "lvl": "target", "msg": "[   20.278464] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video26"}
- {"dt": "2024-12-01T09:54:33.107814", "lvl": "target", "msg": "[   20.278483] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video27"}
- {"dt": "2024-12-01T09:54:33.149739", "lvl": "target", "msg": "[   20.278500] pispbe 1000880000.pisp_be: config device node registered as /dev/video28"}
- {"dt": "2024-12-01T09:54:33.150222", "lvl": "target", "msg": "[   20.278710] pispbe 1000880000.pisp_be: Register nodes for group 1"}
- {"dt": "2024-12-01T09:54:33.150548", "lvl": "target", "msg": "[   20.278736] pispbe 1000880000.pisp_be: input device node registered as /dev/video29"}
- {"dt": "2024-12-01T09:54:33.150849", "lvl": "target", "msg": "[   20.278753] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video30"}
- {"dt": "2024-12-01T09:54:33.151194", "lvl": "target", "msg": "[   20.278769] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video31"}
- {"dt": "2024-12-01T09:54:33.153001", "lvl": "target", "msg": "[   20.278787] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video32"}
- {"dt": "2024-12-01T09:54:33.194028", "lvl": "target", "msg": "[   20.278805] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video33"}
- {"dt": "2024-12-01T09:54:33.194441", "lvl": "target", "msg": "[   20.278822] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video34"}
- {"dt": "2024-12-01T09:54:33.194727", "lvl": "target", "msg": "[   20.278841] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video35"}
- {"dt": "2024-12-01T09:54:33.194994", "lvl": "target", "msg": "[   20.278862] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video36"}
- {"dt": "2024-12-01T09:54:33.195257", "lvl": "target", "msg": "[   20.278884] pispbe 1000880000.pisp_be: config device node registered as /dev/video37"}
- {"dt": "2024-12-01T09:54:33.221003", "lvl": "target", "msg": "[   20.285145] Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'"}
- {"dt": "2024-12-01T09:54:33.221546", "lvl": "target", "msg": "[   20.511184] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2024-12-01T09:54:33.224199", "lvl": "target", "msg": "[   20.517376] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'"}
- {"dt": "2024-12-01T09:54:33.263918", "lvl": "target", "msg": "[   20.546342] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2"}
- {"dt": "2024-12-01T09:54:33.264456", "lvl": "target", "msg": "[   20.555623] cfg80211: failed to load regulatory.db"}
- {"dt": "2024-12-01T09:54:33.267056", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-12-01T09:54:33.331103", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-12-01T09:54:33.331745", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/bthelper."}
- {"dt": "2024-12-01T09:54:33.332632", "lvl": "target", "msg": "[   20.609312] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6"}
- {"dt": "2024-12-01T09:54:33.333117", "lvl": "target", "msg": "[   20.618120] usbcore: registered new interface driver brcmfmac"}
- {"dt": "2024-12-01T09:54:33.333495", "lvl": "target", "msg": "[   20.624463] Bluetooth: hci0: BCM: chip id 107"}
- {"dt": "2024-12-01T09:54:33.333885", "lvl": "target", "msg": "[   20.629041] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-12-01T09:54:33.377714", "lvl": "target", "msg": "[   20.633619] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2"}
- {"dt": "2024-12-01T09:54:33.378272", "lvl": "target", "msg": "[   20.634639] Bluetooth: hci0: BCM4345C0"}
- {"dt": "2024-12-01T09:54:33.378849", "lvl": "target", "msg": "[   20.649118] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000"}
- {"dt": "2024-12-01T09:54:33.379190", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-12-01T09:54:33.379512", "lvl": "target", "msg": "[   20.668639] Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch"}
- {"dt": "2024-12-01T09:54:33.380902", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2024-12-01T09:54:33.396797", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-12-01T09:54:33.412682", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-01T09:54:33.428556", "lvl": "target", "msg": "         Starting Network Name Resolution..."}
- {"dt": "2024-12-01T09:54:33.444518", "lvl": "target", "msg": "[  OK  ] Finished Virtual Console Setup."}
- {"dt": "2024-12-01T09:54:33.496326", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-12-01T09:54:33.496856", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-12-01T09:54:33.497179", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-12-01T09:54:33.499488", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-12-01T09:54:33.611152", "lvl": "target", "msg": "[   20.892325] brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2)"}
- {"dt": "2024-12-01T09:54:33.614364", "lvl": "target", "msg": "[   20.900203] 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-01T09:54:33.661291", "lvl": "target", "msg": "[  OK  ] Started Network Name Resolution."}
- {"dt": "2024-12-01T09:54:34.106115", "lvl": "target", "msg": "[   21.380615] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-12-01T09:54:34.106659", "lvl": "target", "msg": "[   21.386583] Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190"}
- {"dt": "2024-12-01T09:54:34.106975", "lvl": "target", "msg": "[   21.392792] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382"}
- {"dt": "2024-12-01T09:54:34.109359", "lvl": "target", "msg": "[   21.399030] Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac)"}
- {"dt": "2024-12-01T09:54:35.924845", "lvl": "target", "msg": "[*     ] (1 of 2) A start job is running for\u2026namic Linker Cache (2s / no limit)"}
- {"dt": "2024-12-01T09:54:36.506742", "lvl": "target", "msg": "M[K[**    ] (1 of 2) A start job is running for\u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2024-12-01T09:54:37.091654", "lvl": "target", "msg": "M[K[***   ] (1 of 2) A start job is running for\u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2024-12-01T09:54:37.674583", "lvl": "target", "msg": "M[K[ ***  ] (2 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (7s / 1min 30s)"}
- {"dt": "2024-12-01T09:54:38.088588", "lvl": "target", "msg": "M[K[  OK  ] Finished Rebuild Dynamic Linker Cache."}
- {"dt": "2024-12-01T09:54:38.158332", "lvl": "target", "msg": "[K         Starting Update is Completed..."}
- {"dt": "2024-12-01T09:54:38.158806", "lvl": "target", "msg": "[  OK  ] Finished Update is Completed."}
- {"dt": "2024-12-01T09:54:38.159129", "lvl": "target", "msg": "[  OK  ] Reached target System Initialization."}
- {"dt": "2024-12-01T09:54:38.159403", "lvl": "target", "msg": "[  OK  ] Started Daily Cleanup of Temporary Directories."}
- {"dt": "2024-12-01T09:54:38.161561", "lvl": "target", "msg": "[  OK  ] Reached target Timer Units."}
- {"dt": "2024-12-01T09:54:38.197237", "lvl": "target", "msg": "[  OK  ] Listening on agl-compositor, a Wayl\u2026ositor, as a systemd user service."}
- {"dt": "2024-12-01T09:54:38.199580", "lvl": "target", "msg": "[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket."}
- {"dt": "2024-12-01T09:54:38.215328", "lvl": "target", "msg": "[  OK  ] Listening on D-Bus System Message Bus Socket."}
- {"dt": "2024-12-01T09:54:38.259426", "lvl": "target", "msg": "[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets."}
- {"dt": "2024-12-01T09:54:38.259950", "lvl": "target", "msg": "[  OK  ] Listening on mpd.socket."}
- {"dt": "2024-12-01T09:54:38.287253", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Manager Socket..."}
- {"dt": "2024-12-01T09:54:38.303274", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Socket..."}
- {"dt": "2024-12-01T09:54:38.303729", "lvl": "target", "msg": "         Starting sshd.socket..."}
- {"dt": "2024-12-01T09:54:38.339033", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Manager Socket."}
- {"dt": "2024-12-01T09:54:38.339513", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Socket."}
- {"dt": "2024-12-01T09:54:38.341961", "lvl": "target", "msg": "[  OK  ] Listening on sshd.socket."}
- {"dt": "2024-12-01T09:54:38.357925", "lvl": "target", "msg": "[  OK  ] Reached target Socket Units."}
- {"dt": "2024-12-01T09:54:38.373889", "lvl": "target", "msg": "[  OK  ] Reached target Basic System."}
- {"dt": "2024-12-01T09:54:38.414051", "lvl": "target", "msg": "         Starting CAN interface helper..."}
- {"dt": "2024-12-01T09:54:38.414526", "lvl": "target", "msg": "         Starting D-Bus System Message Bus..."}
- {"dt": "2024-12-01T09:54:38.417148", "lvl": "target", "msg": "         Starting Raspberry Pi bluetooth helper..."}
- {"dt": "2024-12-01T09:54:38.433090", "lvl": "target", "msg": "         Starting IPv6 Packet Filtering Framework..."}
- {"dt": "2024-12-01T09:54:38.449106", "lvl": "target", "msg": "         Starting IPv4 Packet Filtering Framework..."}
- {"dt": "2024-12-01T09:54:38.474881", "lvl": "target", "msg": "         Starting Telephony service..."}
- {"dt": "2024-12-01T09:54:38.475362", "lvl": "target", "msg": "[  OK  ] Started PipeWire Multimedia Service."}
- {"dt": "2024-12-01T09:54:38.478029", "lvl": "target", "msg": "         Starting Authorization Manager..."}
- {"dt": "2024-12-01T09:54:38.493919", "lvl": "target", "msg": "         Starting User Login Management..."}
- {"dt": "2024-12-01T09:54:38.509924", "lvl": "target", "msg": "         Starting Disk Manager..."}
- {"dt": "2024-12-01T09:54:38.528647", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (audio)."}
- {"dt": "2024-12-01T09:54:38.547365", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (bluetooth)."}
- {"dt": "2024-12-01T09:54:38.563716", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (policy)."}
- {"dt": "2024-12-01T09:54:38.587748", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (video-capture)."}
- {"dt": "2024-12-01T09:54:38.640462", "lvl": "target", "msg": "         Starting OpenSSH Key Generation..."}
- {"dt": "2024-12-01T09:54:38.641026", "lvl": "target", "msg": "[  OK  ] Finished IPv6 Packet Filtering Framework."}
- {"dt": "2024-12-01T09:54:38.641374", "lvl": "target", "msg": "[  OK  ] Finished IPv4 Packet Filtering Framework."}
- {"dt": "2024-12-01T09:54:38.643824", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Network."}
- {"dt": "2024-12-01T09:54:38.644255", "lvl": "target", "msg": "         Starting Network Configuration..."}
- {"dt": "2024-12-01T09:54:38.675568", "lvl": "target", "msg": "[  OK  ] Finished Raspberry Pi bluetooth helper."}
- {"dt": "2024-12-01T09:54:38.698371", "lvl": "target", "msg": "         Starting Bluetooth service..."}
- {"dt": "2024-12-01T09:54:38.701531", "lvl": "target", "msg": "[   25.994764] CAN device driver interface"}
- {"dt": "2024-12-01T09:54:38.733447", "lvl": "target", "msg": "[   26.030044] vcan: Virtual CAN interface driver"}
- {"dt": "2024-12-01T09:54:38.749314", "lvl": "target", "msg": "[  OK  ] Finished CAN interface helper."}
- {"dt": "2024-12-01T09:54:38.781369", "lvl": "target", "msg": "[  OK  ] Started User Login Management."}
- {"dt": "2024-12-01T09:54:40.043097", "lvl": "target", "msg": "[  OK  ] Started Network Configuration."}
- {"dt": "2024-12-01T09:54:40.346600", "lvl": "target", "msg": "[  OK  ] Started D-Bus System Message Bus."}
- {"dt": "2024-12-01T09:54:40.394453", "lvl": "target", "msg": "         Starting Connection service..."}
- {"dt": "2024-12-01T09:54:40.410365", "lvl": "target", "msg": "[  OK  ] Started Telephony service."}
- {"dt": "2024-12-01T09:54:40.437179", "lvl": "target", "msg": "[  OK  ] Started Bluetooth service."}
- {"dt": "2024-12-01T09:54:40.440415", "lvl": "target", "msg": "[  OK  ] Reached target Bluetooth Support."}
- {"dt": "2024-12-01T09:54:40.615938", "lvl": "target", "msg": "[   27.893275] Bluetooth: BNEP (Ethernet Emulation) ver 1.3"}
- {"dt": "2024-12-01T09:54:40.616475", "lvl": "target", "msg": "[   27.898616] Bluetooth: BNEP filters: protocol multicast"}
- {"dt": "2024-12-01T09:54:40.616819", "lvl": "target", "msg": "[   27.903869] Bluetooth: BNEP socket layer initialized"}
- {"dt": "2024-12-01T09:54:40.619219", "lvl": "target", "msg": "[   27.911798] Bluetooth: MGMT ver 1.22"}
- {"dt": "2024-12-01T09:54:40.666944", "lvl": "target", "msg": "         Starting Hostname Service..."}
- {"dt": "2024-12-01T09:54:40.762704", "lvl": "target", "msg": "[  OK  ] Started Hostname Service."}
- {"dt": "2024-12-01T09:54:40.907442", "lvl": "target", "msg": "[   28.194906] Bluetooth: RFCOMM TTY layer initialized"}
- {"dt": "2024-12-01T09:54:40.907987", "lvl": "target", "msg": "[   28.199817] Bluetooth: RFCOMM socket layer initialized"}
- {"dt": "2024-12-01T09:54:40.910660", "lvl": "target", "msg": "[   28.204987] Bluetooth: RFCOMM ver 1.11"}
- {"dt": "2024-12-01T09:54:40.942390", "lvl": "target", "msg": "[  OK  ] Started Connection service."}
- {"dt": "2024-12-01T09:54:40.957438", "lvl": "target", "msg": "[  OK  ] Reached target Network."}
- {"dt": "2024-12-01T09:54:41.013249", "lvl": "target", "msg": "[  OK  ] Started agl-service-radi[   28.301736] pipewire[475]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set"}
- {"dt": "2024-12-01T09:54:41.016521", "lvl": "target", "msg": "o.service."}
- {"dt": "2024-12-01T09:54:41.016994", "lvl": "target", "msg": "         Starting Avahi mDNS/DNS-SD Stack..."}
- {"dt": "2024-12-01T09:54:41.063232", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val databroker."}
- {"dt": "2024-12-01T09:54:41.080264", "lvl": "target", "msg": "[  OK  ] Started agl-service-audiomixer.service."}
- {"dt": "2024-12-01T09:54:41.096073", "lvl": "target", "msg": "[  OK  ] Started agl-service-hvac.service."}
- {"dt": "2024-12-01T09:54:41.112056", "lvl": "target", "msg": "         Starting agl-vss-helper.service..."}
- {"dt": "2024-12-01T09:54:41.128219", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val DBC feeder."}
- {"dt": "2024-12-01T09:54:41.160061", "lvl": "target", "msg": "         Starting Permit User Sessions..."}
- {"dt": "2024-12-01T09:54:41.223961", "lvl": "target", "msg": "[  OK  ] Started Avahi mDNS/DNS-SD Stack."}
- {"dt": "2024-12-01T09:54:41.239854", "lvl": "target", "msg": "[  OK  ] Finished Permit User Sessions."}
- {"dt": "2024-12-01T09:54:41.271776", "lvl": "target", "msg": "         Starting AGL compositor..."}
- {"dt": "2024-12-01T09:54:41.287885", "lvl": "target", "msg": "[  OK  ] Started Getty on tty1."}
- {"dt": "2024-12-01T09:54:41.303728", "lvl": "target", "msg": "[  OK  ] Started Serial Getty on ttyAMA10."}
- {"dt": "2024-12-01T09:54:41.304201", "lvl": "target", "msg": "[  OK  ] Reached target Login Prompts."}
- {"dt": "2024-12-01T09:54:41.942586", "lvl": "target", "msg": "         Starting WPA supplicant..."}
- {"dt": "2024-12-01T09:54:42.277930", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-12-01T09:54:42.293821", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-12-01T09:54:43.587276", "lvl": "target", "msg": "[  OK  ] Started WPA supplicant."}
- {"dt": "2024-12-01T09:54:43.635074", "lvl": "target", "msg": "[  OK  ] Created slice User Slice of UID 1001."}
- {"dt": "2024-12-01T09:54:43.651081", "lvl": "target", "msg": "         Starting User Runtime Directory /run/user/1001..."}
- {"dt": "2024-12-01T09:54:43.810812", "lvl": "target", "msg": "[  OK  ] Finished User Runtime Directory /run/user/1001."}
- {"dt": "2024-12-01T09:54:43.842742", "lvl": "target", "msg": "         Starting User Manager for UID 1001..."}
- {"dt": "2024-12-01T09:54:44.090149", "lvl": "target", "msg": "[  OK  ] Started Authorization Ma[   31.378212] can: controller area network core"}
- {"dt": "2024-12-01T09:54:44.090699", "lvl": "target", "msg": "nager."}
- {"dt": "2024-12-01T09:54:44.093363", "lvl": "target", "msg": "[   31.384801] NET: Registered PF_CAN protocol family"}
- {"dt": "2024-12-01T09:54:44.108280", "lvl": "target", "msg": "[   31.403653] can: raw protocol"}
- {"dt": "2024-12-01T09:54:44.125236", "lvl": "target", "msg": "         Starting applaunchd.service..."}
- {"dt": "2024-12-01T09:54:44.172115", "lvl": "target", "msg": "[  OK  ] Started Disk Manager."}
- {"dt": "2024-12-01T09:54:44.221097", "lvl": "target", "msg": "[  OK  ] Started Automount Disk Manager."}
- {"dt": "2024-12-01T09:54:44.236931", "lvl": "target", "msg": "[  OK  ] Finished OpenSSH Key Generation."}
- {"dt": "2024-12-01T09:54:44.789767", "lvl": "target", "msg": "[  OK  ] Started User Manager for UID 1001."}
- {"dt": "2024-12-01T09:54:44.792973", "lvl": "target", "msg": "[  OK  ] Started Session c1 of User agl-driver."}
- {"dt": "2024-12-01T09:54:45.112442", "lvl": "target", "msg": "[FAILED] Failed to start AGL compositor."}
- {"dt": "2024-12-01T09:54:45.128189", "lvl": "target", "msg": "See 'systemctl status agl-compositor.service' for details."}
- {"dt": "2024-12-01T09:54:45.155001", "lvl": "target", "msg": "[DEPEND] Dependency failed for homescreen.service."}
- {"dt": "2024-12-01T09:54:45.158166", "lvl": "target", "msg": "[DEPEND] Dependency failed for launcher.service."}
- {"dt": "2024-12-01T09:54:45.253942", "lvl": "target", "msg": "[  OK  ] Started applaunchd.service."}
- {"dt": "2024-12-01T09:54:47.371736", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:47.372279", "lvl": "target", "msg": "Automotive Grade Linux 18.0.2 raspberrypi5 ttyAMA10"}
- {"dt": "2024-12-01T09:54:47.372599", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:47.375919", "lvl": "debug", "msg": "Matched prompt #12: login:"}
- {"dt": "2024-12-01T09:54:47.376414", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "34.63", "extra": {"extra": []}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:54:47.377094", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2024-12-01T09:54:47.377483", "lvl": "input", "msg": "root\n"}
- {"dt": "2024-12-01T09:54:47.478205", "lvl": "target", "msg": "raspberrypi5 login: root"}
- {"dt": "2024-12-01T09:54:47.478862", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-01T09:54:47.479265", "lvl": "debug", "msg": "login-action: Wait for prompt ['raspberrypi5:~#', '/ #', 'Login incorrect', 'Login timed out'] (timeout 00:07:20)"}
- {"dt": "2024-12-01T09:54:47.486666", "lvl": "target", "msg": "root"}
- {"dt": "2024-12-01T09:54:50.935918", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-01T09:54:50.936579", "lvl": "debug", "msg": "end: 2.3.1 login-action (duration 00:00:38) [common]"}
- {"dt": "2024-12-01T09:54:50.937001", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "38.19", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:54:50.937652", "lvl": "debug", "msg": "end: 2.3 auto-login-action (duration 00:01:45) [common]"}
- {"dt": "2024-12-01T09:54:50.938053", "lvl": "debug", "msg": "start: 2.4 expect-shell-connection (timeout 00:07:16) [common]"}
- {"dt": "2024-12-01T09:54:50.938394", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-01T09:54:50.938688", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-01T09:54:50.938968", "lvl": "input", "msg": "\n"}
- {"dt": "2024-12-01T09:54:50.989368", "lvl": "target", "msg": "7[r[999;999H[6nroot@raspberrypi5:~# "}
- {"dt": "2024-12-01T09:54:50.990089", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:20:00)"}
- {"dt": "2024-12-01T09:54:50.990738", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2024-12-01T09:54:50.999681", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:51.015893", "lvl": "debug", "msg": "end: 2.4 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:54:51.016553", "lvl": "debug", "msg": "start: 2.5 overlay-unpack (timeout 00:07:16) [common]"}
- {"dt": "2024-12-01T09:54:51.017118", "lvl": "input", "msg": "rm overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-01T09:54:51.117808", "lvl": "target", "msg": "root@raspberrypi5:~# rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-01T09:54:51.127255", "lvl": "target", "msg": "rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-01T09:54:51.155204", "lvl": "target", "msg": "rm: cannot remove 'overlay-1.5.5.tar.gz': No such file or directory"}
- {"dt": "2024-12-01T09:54:51.155993", "lvl": "input", "msg": "wget http://192.168.66.1/tmp/38026/compress-overlay-w7g0cbim/overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-01T09:54:51.256713", "lvl": "target", "msg": "root@raspberrypi5:~# wget http://192.168.66.1/tmp/38026/compress-overlay-w7g0cbim/overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-01T09:54:51.265121", "lvl": "target", "msg": "wget http://192.168.66.1/tmp/38026/compress-overlay-w7g0cbimm/overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-01T09:54:51.309025", "lvl": "target", "msg": "Connecting to 192.168.66.1 (192.168.66.1:80)"}
- {"dt": "2024-12-01T09:54:51.309505", "lvl": "target", "msg": "saving to 'overlay-1.5.5.tar.gz'"}
- {"dt": "2024-12-01T09:54:51.309818", "lvl": "target", "msg": "overlay-1.5.5.tar.gz 100% |********************************|  8043  0:00:00 ETA"}
- {"dt": "2024-12-01T09:54:51.310101", "lvl": "target", "msg": "'overlay-1.5.5.tar.gz' saved"}
- {"dt": "2024-12-01T09:54:51.310612", "lvl": "input", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-01T09:54:51.411249", "lvl": "target", "msg": "root@raspberrypi5:~# tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-01T09:54:51.418648", "lvl": "target", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-01T09:54:51.482575", "lvl": "target", "msg": "x lava-38026/"}
- {"dt": "2024-12-01T09:54:51.483052", "lvl": "target", "msg": "x lava-38026/0/"}
- {"dt": "2024-12-01T09:54:51.522877", "lvl": "target", "msg": "x lava-38026/0/lava-test-runner.conf"}
- {"dt": "2024-12-01T09:54:51.523391", "lvl": "target", "msg": "x lava-38026/0/tests/"}
- {"dt": "2024-12-01T09:54:51.523789", "lvl": "target", "msg": "x lava-38026/0/tests/0_dmesg/"}
- {"dt": "2024-12-01T09:54:51.524146", "lvl": "target", "msg": "x lava-38026/0/tests/0_dmesg/inline/"}
- {"dt": "2024-12-01T09:54:51.524435", "lvl": "target", "msg": "x lava-38026/0/tests/0_dmesg/inline/dmesg.yaml"}
- {"dt": "2024-12-01T09:54:51.525127", "lvl": "target", "msg": "x lava-38026/0/tests/0_dmesg/run.sh"}
- {"dt": "2024-12-01T09:54:51.525459", "lvl": "target", "msg": "x lava-38026/0/tests/0_dmesg/testdef.yaml"}
- {"dt": "2024-12-01T09:54:51.525802", "lvl": "target", "msg": "x lava-38026/0/tests/0_dmesg/testdef_metadata"}
- {"dt": "2024-12-01T09:54:51.526135", "lvl": "target", "msg": "x lava-38026/0/tests/0_dmesg/uuid"}
- {"dt": "2024-12-01T09:54:51.526421", "lvl": "target", "msg": "x lava-38026/1/"}
- {"dt": "2024-12-01T09:54:51.526749", "lvl": "target", "msg": "x lava-38026/1/lava-test-runner.conf"}
- {"dt": "2024-12-01T09:54:51.527072", "lvl": "target", "msg": "x lava-38026/1/tests/"}
- {"dt": "2024-12-01T09:54:51.527372", "lvl": "target", "msg": "x lava-38026/1/tests/1_bootrr/"}
- {"dt": "2024-12-01T09:54:51.527716", "lvl": "target", "msg": "x lava-38026/1/tests/1_bootrr/inline/"}
- {"dt": "2024-12-01T09:54:51.566256", "lvl": "target", "msg": "x lava-38026/1/tests/1_bootrr/inline/bootrr.yaml"}
- {"dt": "2024-12-01T09:54:51.566790", "lvl": "target", "msg": "x lava-38026/1/tests/1_bootrr/run.sh"}
- {"dt": "2024-12-01T09:54:51.567160", "lvl": "target", "msg": "x lava-38026/1/tests/1_bootrr/testdef.yaml"}
- {"dt": "2024-12-01T09:54:51.567467", "lvl": "target", "msg": "x lava-38026/1/tests/1_bootrr/testdef_metadata"}
- {"dt": "2024-12-01T09:54:51.567783", "lvl": "target", "msg": "x lava-38026/1/tests/1_bootrr/uuid"}
- {"dt": "2024-12-01T09:54:51.568059", "lvl": "target", "msg": "x lava-38026/bin/"}
- {"dt": "2024-12-01T09:54:51.568371", "lvl": "target", "msg": "x lava-38026/bin/lava-add-keys"}
- {"dt": "2024-12-01T09:54:51.568913", "lvl": "target", "msg": "x lava-38026/bin/lava-add-sources"}
- {"dt": "2024-12-01T09:54:51.569270", "lvl": "target", "msg": "x lava-38026/bin/lava-background-process-start"}
- {"dt": "2024-12-01T09:54:51.569553", "lvl": "target", "msg": "x lava-38026/bin/lava-background-process-stop"}
- {"dt": "2024-12-01T09:54:51.569870", "lvl": "target", "msg": "x lava-38026/bin/lava-common-functions"}
- {"dt": "2024-12-01T09:54:51.570214", "lvl": "target", "msg": "x lava-38026/bin/lava-echo-ipv4"}
- {"dt": "2024-12-01T09:54:51.570493", "lvl": "target", "msg": "x lava-38026/bin/lava-install-packages"}
- {"dt": "2024-12-01T09:54:51.609489", "lvl": "target", "msg": "x lava-38026/bin/lava-installed-packages"}
- {"dt": "2024-12-01T09:54:51.609960", "lvl": "target", "msg": "x lava-38026/bin/lava-os-build"}
- {"dt": "2024-12-01T09:54:51.610277", "lvl": "target", "msg": "x lava-38026/bin/lava-probe-channel"}
- {"dt": "2024-12-01T09:54:51.610554", "lvl": "target", "msg": "x lava-38026/bin/lava-probe-ip"}
- {"dt": "2024-12-01T09:54:51.610816", "lvl": "target", "msg": "x lava-38026/bin/lava-target-ip"}
- {"dt": "2024-12-01T09:54:51.611067", "lvl": "target", "msg": "x lava-38026/bin/lava-target-mac"}
- {"dt": "2024-12-01T09:54:51.611320", "lvl": "target", "msg": "x lava-38026/bin/lava-target-storage"}
- {"dt": "2024-12-01T09:54:51.611581", "lvl": "target", "msg": "x lava-38026/bin/lava-test-case"}
- {"dt": "2024-12-01T09:54:51.611852", "lvl": "target", "msg": "x lava-38026/bin/lava-test-event"}
- {"dt": "2024-12-01T09:54:51.612103", "lvl": "target", "msg": "x lava-38026/bin/lava-test-feedback"}
- {"dt": "2024-12-01T09:54:51.612347", "lvl": "target", "msg": "x lava-38026/bin/lava-test-raise"}
- {"dt": "2024-12-01T09:54:51.612588", "lvl": "target", "msg": "x lava-38026/bin/lava-test-reference"}
- {"dt": "2024-12-01T09:54:51.613155", "lvl": "target", "msg": "x lava-38026/bin/lava-test-runner"}
- {"dt": "2024-12-01T09:54:51.613436", "lvl": "target", "msg": "x lava-38026/bin/lava-test-set"}
- {"dt": "2024-12-01T09:54:51.635380", "lvl": "target", "msg": "x lava-38026/bin/lava-test-shell"}
- {"dt": "2024-12-01T09:54:51.635849", "lvl": "target", "msg": "x lava-38026/environment"}
- {"dt": "2024-12-01T09:54:51.636162", "lvl": "target", "msg": "x lava-38026/lava"}
- {"dt": "2024-12-01T09:54:51.636434", "lvl": "target", "msg": "x lava-38026/lava.pub"}
- {"dt": "2024-12-01T09:54:51.636687", "lvl": "target", "msg": "x lava-38026/results/"}
- {"dt": "2024-12-01T09:54:51.638457", "lvl": "target", "msg": "x lava-38026/tests/"}
- {"dt": "2024-12-01T09:54:51.639261", "lvl": "debug", "msg": "end: 2.5 overlay-unpack (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:54:51.639753", "lvl": "debug", "msg": "start: 2.6 export-device-env (timeout 00:07:15) [common]"}
- {"dt": "2024-12-01T09:54:51.640172", "lvl": "debug", "msg": "end: 2.6 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T09:54:51.640580", "lvl": "info", "msg": "end: 2 minimal-boot (duration 00:01:53) [common]"}
- {"dt": "2024-12-01T09:54:51.641026", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-12-01T09:54:51.641432", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-12-01T09:54:51.641766", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-12-01T09:54:51.642101", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-12-01T09:54:51.742624", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-12-01T09:54:51.743259", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-12-01T09:54:51.750266", "lvl": "target", "msg": "#"}
- {"dt": "2024-12-01T09:54:51.766474", "lvl": "debug", "msg": "Using /lava-38026"}
- {"dt": "2024-12-01T09:54:51.766931", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-12-01T09:54:51.867493", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh"}
- {"dt": "2024-12-01T09:54:51.877973", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-12-01T09:54:51.921297", "lvl": "input", "msg": ". /lava-38026/environment\n"}
- {"dt": "2024-12-01T09:54:52.022024", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-38026/environment"}
- {"dt": "2024-12-01T09:54:52.037654", "lvl": "target", "msg": ". /lava-38026/environment"}
- {"dt": "2024-12-01T09:54:52.081379", "lvl": "input", "msg": "/lava-38026/bin/lava-test-runner /lava-38026/0\n"}
- {"dt": "2024-12-01T09:54:52.182148", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-38026/bin/lava-test-runner /lava-38026/0"}
- {"dt": "2024-12-01T09:54:52.182774", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-12-01T09:54:52.197363", "lvl": "target", "msg": "/lava-38026/bin/lava-test-runner /lava-38026/0"}
- {"dt": "2024-12-01T09:54:52.262760", "lvl": "target", "msg": "+ export TESTRUN_ID=0_dmesg"}
- {"dt": "2024-12-01T09:54:52.263294", "lvl": "target", "msg": "+ TESTRUN_ID=0_dmesg"}
- {"dt": "2024-12-01T09:54:52.263606", "lvl": "target", "msg": "+ cd /lava-38026/0/tests/0_dmesg"}
- {"dt": "2024-12-01T09:54:52.263884", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-12-01T09:54:52.264146", "lvl": "target", "msg": "+ UUID=38026_1.5.4.1"}
- {"dt": "2024-12-01T09:54:52.264397", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-01T09:54:52.264645", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_dmesg 38026_1.5.4.1>"}
- {"dt": "2024-12-01T09:54:52.265027", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-12-01T09:54:52.265278", "lvl": "target", "msg": "+ dmesg --level=warn --notime -x -k"}
- {"dt": "2024-12-01T09:54:52.265520", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-12-01T09:54:52.265768", "lvl": "target", "msg": "+ dmesg --level=err --notime -x -k"}
- {"dt": "2024-12-01T09:54:52.266019", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-01T09:54:52.266263", "lvl": "target", "msg": "+ dmesg --level=crit --notime -x -k"}
- {"dt": "2024-12-01T09:54:52.266506", "lvl": "target", "msg": "+ test -s dmesg.crit"}
- {"dt": "2024-12-01T09:54:52.266747", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-01T09:54:52.266988", "lvl": "target", "msg": "++ cat dmesg.crit"}
- {"dt": "2024-12-01T09:54:52.267230", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-01T09:54:52.267474", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-01T09:54:52.268000", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_dmesg 38026_1.5.4.1"}
- {"dt": "2024-12-01T09:54:52.268287", "lvl": "info", "msg": "Starting test lava.0_dmesg (38026_1.5.4.1)"}
- {"dt": "2024-12-01T09:54:52.268646", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-12-01T09:54:52.306052", "lvl": "target", "msg": "+ lava-test-case crit --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-01T09:54:52.306605", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-01T09:54:52.306980", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-01T09:54:52.307299", "lvl": "target", "msg": "+ dmesg --level=alert --notime -x -k"}
- {"dt": "2024-12-01T09:54:52.307594", "lvl": "target", "msg": "+ test -s dmesg.alert"}
- {"dt": "2024-12-01T09:54:52.307863", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-01T09:54:52.308128", "lvl": "target", "msg": "++ cat dmesg.alert"}
- {"dt": "2024-12-01T09:54:52.308388", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-01T09:54:52.308633", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-01T09:54:52.308944", "lvl": "target", "msg": "+ lava-test-case alert --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-01T09:54:52.309206", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-01T09:54:52.309452", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-01T09:54:52.309967", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-01T09:54:52.310478", "lvl": "results", "msg": {"case": "crit", "definition": "0_dmesg", "endtc": ! "1337", "measurement": ! "0.0", "result": "pass", "starttc": ! "1337", "units": "lines"}}
- {"dt": "2024-12-01T09:54:52.311158", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-01T09:54:52.311524", "lvl": "results", "msg": {"case": "alert", "definition": "0_dmesg", "endtc": ! "1339", "measurement": ! "0.0", "result": "pass", "starttc": ! "1339", "units": "lines"}}
- {"dt": "2024-12-01T09:54:52.312135", "lvl": "target", "msg": "+ dmesg --level=emerg --notime -x -k"}
- {"dt": "2024-12-01T09:54:52.312406", "lvl": "target", "msg": "+ test -s dmesg.emerg"}
- {"dt": "2024-12-01T09:54:52.312656", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-01T09:54:52.354501", "lvl": "target", "msg": "++ cat dmesg.emerg"}
- {"dt": "2024-12-01T09:54:52.355063", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-01T09:54:52.355374", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-01T09:54:52.355649", "lvl": "target", "msg": "+ lava-test-case emerg --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-01T09:54:52.355917", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-01T09:54:52.356173", "lvl": "target", "msg": "+ cat dmesg.emerg dmesg.alert dmesg.crit dmesg.err dmesg.warn"}
- {"dt": "2024-12-01T09:54:52.356427", "lvl": "target", "msg": "kern  :warn  : F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-12-01T09:54:52.356672", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-01T09:54:52.356950", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_dmesg 38026_1.5.4.1>"}
- {"dt": "2024-12-01T09:54:52.357463", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-01T09:54:52.357842", "lvl": "results", "msg": {"case": "emerg", "definition": "0_dmesg", "endtc": ! "1353", "measurement": ! "0.0", "result": "pass", "starttc": ! "1353", "units": "lines"}}
- {"dt": "2024-12-01T09:54:52.358468", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_dmesg 38026_1.5.4.1"}
- {"dt": "2024-12-01T09:54:52.358804", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-12-01T09:54:52.359038", "lvl": "info", "msg": "Ending test lava.0_dmesg (38026_1.5.4.1), duration 0.09"}
- {"dt": "2024-12-01T09:54:52.359139", "lvl": "results", "msg": {"case": "0_dmesg", "definition": "lava", "duration": "0.09", "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": "38026_1.5.4.1"}}
- {"dt": "2024-12-01T09:54:52.359388", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2024-12-01T09:54:52.359627", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-12-01T09:54:52.359729", "lvl": "debug", "msg": "alert: pass\ncrit: pass\nemerg: pass\n"}
- {"dt": "2024-12-01T09:54:52.359824", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:54:52.359911", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:54:52.359995", "lvl": "info", "msg": "start: 4 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-12-01T09:54:52.360079", "lvl": "debug", "msg": "start: 4.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-12-01T09:54:52.360143", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-12-01T09:54:52.360208", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-12-01T09:54:52.460656", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-12-01T09:54:52.461343", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-12-01T09:54:52.461924", "lvl": "debug", "msg": "Using /lava-38026"}
- {"dt": "2024-12-01T09:54:52.462264", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-12-01T09:54:52.562840", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-12-01T09:54:52.563573", "lvl": "target", "msg": "#"}
- {"dt": "2024-12-01T09:54:52.564185", "lvl": "input", "msg": ". /lava-38026/environment\n"}
- {"dt": "2024-12-01T09:54:52.664817", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh. /lava-38026/environment"}
- {"dt": "2024-12-01T09:54:52.665648", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:52.666349", "lvl": "input", "msg": "/lava-38026/bin/lava-test-runner /lava-38026/1\n"}
- {"dt": "2024-12-01T09:54:52.766952", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-38026/environment/lava-38026/bin/lava-test-runner /lava-38026/1"}
- {"dt": "2024-12-01T09:54:52.767548", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-12-01T09:54:52.768126", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T09:54:52.772305", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-38026/bin/lava-test-runner /lava-38026/1"}
- {"dt": "2024-12-01T09:54:52.833237", "lvl": "target", "msg": "+ export TESTRUN_ID=1_bootrr"}
- {"dt": "2024-12-01T09:54:52.833741", "lvl": "target", "msg": "+ TESTRUN_ID=[   40.104495] <LAVA_SIGNAL_STARTRUN 1_bootrr 38026_1.5.4.5>"}
- {"dt": "2024-12-01T09:54:52.834080", "lvl": "target", "msg": "1_bootrr"}
- {"dt": "2024-12-01T09:54:52.834374", "lvl": "target", "msg": "+ cd /lava-38026/1/tests[   40.112782] <LAVA_SIGNAL_ENDRUN 1_bootrr 38026_1.5.4.5>"}
- {"dt": "2024-12-01T09:54:52.834696", "lvl": "target", "msg": "/1_bootrr"}
- {"dt": "2024-12-01T09:54:52.834954", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-12-01T09:54:52.835265", "lvl": "target", "msg": "+ UUID=38026_1.5.4.5"}
- {"dt": "2024-12-01T09:54:52.835555", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-01T09:54:52.835828", "lvl": "target", "msg": "+ export PATH=/opt/bootrr/helpers:/lava-38026/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-12-01T09:54:52.836386", "lvl": "debug", "msg": "Received signal: <STARTRUN> 1_bootrr 38026_1.5.4.5"}
- {"dt": "2024-12-01T09:54:52.836779", "lvl": "info", "msg": "Starting test lava.1_bootrr (38026_1.5.4.5)"}
- {"dt": "2024-12-01T09:54:52.837173", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-12-01T09:54:52.837596", "lvl": "debug", "msg": "Received signal: <ENDRUN> 1_bootrr 38026_1.5.4.5"}
- {"dt": "2024-12-01T09:54:52.837971", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-12-01T09:54:52.838278", "lvl": "info", "msg": "Ending test lava.1_bootrr (38026_1.5.4.5), duration 0.00"}
- {"dt": "2024-12-01T09:54:52.838710", "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": "38026_1.5.4.5"}}
- {"dt": "2024-12-01T09:54:52.839825", "lvl": "target", "msg": "+ PATH=/opt/bootrr/helpers:/lava-38026/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-12-01T09:54:52.859194", "lvl": "target", "msg": "+ cd /opt/bootrr"}
- {"dt": "2024-12-01T09:54:52.859719", "lvl": "target", "msg": "/lava-38026/1/tests/1_bootrr/run.sh: line 15: cd: /opt/bootrr: No such file or directory"}
- {"dt": "2024-12-01T09:54:52.860106", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-01T09:54:52.860685", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-12-01T09:54:52.861236", "lvl": "debug", "msg": "end: 4.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:54:52.861703", "lvl": "info", "msg": "end: 4 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:54:52.862197", "lvl": "info", "msg": "start: 5 finalize (timeout 00:07:14) [common]"}
- {"dt": "2024-12-01T09:54:52.862684", "lvl": "debug", "msg": "start: 5.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-12-01T09:54:52.863296", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'off' '2'"}
- {"dt": "2024-12-01T09:54:53.916257", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-01T09:54:53.917804", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-12-01T09:54:53.918595", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-12-01T09:54:54.019343", "lvl": "debug", "msg": "end: 5.1 power-off (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:54:54.019997", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "1.16", "extra": {"status": "success"}, "level": "5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T09:54:54.020899", "lvl": "debug", "msg": "start: 5.2 read-feedback (timeout 00:07:13) [common]"}
- {"dt": "2024-12-01T09:54:54.022171", "lvl": "feedback", "msg": "<LAVA_TEST_RUNNER EXIT>", "ns": "common"}
- {"dt": "2024-12-01T09:54:54.022893", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-12-01T09:54:55.022724", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-12-01T09:54:55.023355", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-12-01T09:54:55.023757", "lvl": "target", "msg": "root@raspberrypi5:~# \0"}
- {"dt": "2024-12-01T09:54:55.124647", "lvl": "debug", "msg": "end: 5.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T09:54:55.125241", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2024-12-01T09:54:55.125592", "lvl": "debug", "msg": "clean NBD port 61999"}
- {"dt": "2024-12-01T09:54:55.238391", "lvl": "info", "msg": "end: 5 finalize (duration 00:00:02) [common]"}
- {"dt": "2024-12-01T09:54:55.239743", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-12-01T09:54:55.240375", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/initrd"}
- {"dt": "2024-12-01T09:54:55.245932", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/kernel"}
- {"dt": "2024-12-01T09:54:55.255574", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/dtb"}
- {"dt": "2024-12-01T09:54:55.256103", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38026/nbd-deploy-qkca18sy/nbdroot"}
- {"dt": "2024-12-01T09:54:55.486039", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38026"}
- {"dt": "2024-12-01T09:54:55.487651", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38026"}
- {"dt": "2024-12-01T09:54:55.487851", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-12-01T09:54:55.487953", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
