- {"dt": "2024-12-02T10:45:52.015567", "lvl": "warning", "msg": "Action timeout for connect-device exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.015948", "lvl": "warning", "msg": "Action timeout for pdu-reboot exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.016081", "lvl": "warning", "msg": "Action timeout for reset-device exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.016215", "lvl": "warning", "msg": "Action timeout for login-action exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.016323", "lvl": "warning", "msg": "Action timeout for auto-login-action exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.016462", "lvl": "warning", "msg": "Action timeout for expect-shell-connection exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.016569", "lvl": "warning", "msg": "Action timeout for overlay-unpack exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.016682", "lvl": "warning", "msg": "Action timeout for export-device-env exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.016938", "lvl": "warning", "msg": "Action timeout for minimal-boot exceeds Job timeout"}
- {"dt": "2024-12-02T10:45:52.030858", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-12-02T10:45:52.030995", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-12-02T10:45:52.031144", "lvl": "info", "msg": "Start time: 2024-12-02 10:45:52.031134+00:00 (UTC)"}
- {"dt": "2024-12-02T10:45:52.031344", "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-02T10:45:52.399861", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image exists"}
- {"dt": "2024-12-02T10:45:52.601961", "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-02T10:45:52.804396", "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-02T10:45:53.011398", "lvl": "info", "msg": "validate duration: 0.98"}
- {"dt": "2024-12-02T10:45:53.011543", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-12-02T10:45:53.011722", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 00:10:00) [common]"}
- {"dt": "2024-12-02T10:45:53.011814", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2024-12-02T10:45:53.011891", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2024-12-02T10:45:53.011969", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2024-12-02T10:45:53.012135", "lvl": "debug", "msg": "Set_port 61986"}
- {"dt": "2024-12-02T10:45:53.012243", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:10:00) [common]"}
- {"dt": "2024-12-02T10:45:53.012345", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 00:10:00) [common]"}
- {"dt": "2024-12-02T10:45:53.012576", "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-02T10:45:53.012674", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-02T10:45:53.012769", "lvl": "debug", "msg": "total size: 2153804 (2\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.012853", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-12-02T10:45:53.309786", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.506617", "lvl": "debug", "msg": "progress   6\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.606217", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.623985", "lvl": "debug", "msg": "progress  18\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.705274", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.727170", "lvl": "debug", "msg": "progress  30\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.728472", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.729556", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.804494", "lvl": "debug", "msg": "progress  47\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.807596", "lvl": "debug", "msg": "progress  53\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.816164", "lvl": "debug", "msg": "progress  59\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.818085", "lvl": "debug", "msg": "progress  65\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.827390", "lvl": "debug", "msg": "progress  71\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.828053", "lvl": "debug", "msg": "progress  76\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.832784", "lvl": "debug", "msg": "progress  82\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.902093", "lvl": "debug", "msg": "progress  88\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.923471", "lvl": "debug", "msg": "progress  94\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.924538", "lvl": "debug", "msg": "progress 100\u202F% (2\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.957335", "lvl": "info", "msg": "2\u202FMB downloaded in 0.94\u202Fs (2.17\u202FMB/s)"}
- {"dt": "2024-12-02T10:45:53.957667", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:45:53.957760", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.95", "extra": {"label": "initrd", "md5sum": "69fcb22a915d8fa6208b51a2d38a1bd0", "sha256sum": "59fb98aa51ec6733051adf76648cfc363ccc8367467a4fb8794cfaf7c3b33988", "sha512sum": "52fefc4f3925b72ed297cf2bb536aefa2358e2d66fefd61cede40b155efd2e9b101119081caf7f09fd61d798027cdc78cb63718fd91f573a718dfcb5894851c7", "size": ! "2153804"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:45:53.957971", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:45:53.958066", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:09:59) [common]"}
- {"dt": "2024-12-02T10:45:53.958157", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 00:09:59) [common]"}
- {"dt": "2024-12-02T10:45:53.958355", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/release/ricefish/18.0.2/raspberrypi5/deploy/images/raspberrypi5/Image"}
- {"dt": "2024-12-02T10:45:53.958430", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/kernel/Image"}
- {"dt": "2024-12-02T10:45:53.958493", "lvl": "debug", "msg": "total size: 28887552 (27\u202FMB)"}
- {"dt": "2024-12-02T10:45:53.958556", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-02T10:45:54.254660", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:54.844290", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-12-02T10:45:54.968079", "lvl": "debug", "msg": "progress  10\u202F% (2\u202FMB)"}
- {"dt": "2024-12-02T10:45:55.084585", "lvl": "debug", "msg": "progress  15\u202F% (4\u202FMB)"}
- {"dt": "2024-12-02T10:45:55.199538", "lvl": "debug", "msg": "progress  20\u202F% (5\u202FMB)"}
- {"dt": "2024-12-02T10:45:55.369544", "lvl": "debug", "msg": "progress  25\u202F% (6\u202FMB)"}
- {"dt": "2024-12-02T10:45:55.517420", "lvl": "debug", "msg": "progress  30\u202F% (8\u202FMB)"}
- {"dt": "2024-12-02T10:45:55.628059", "lvl": "debug", "msg": "progress  35\u202F% (9\u202FMB)"}
- {"dt": "2024-12-02T10:45:55.745243", "lvl": "debug", "msg": "progress  40\u202F% (11\u202FMB)"}
- {"dt": "2024-12-02T10:45:55.861610", "lvl": "debug", "msg": "progress  45\u202F% (12\u202FMB)"}
- {"dt": "2024-12-02T10:45:55.978920", "lvl": "debug", "msg": "progress  50\u202F% (13\u202FMB)"}
- {"dt": "2024-12-02T10:45:56.099172", "lvl": "debug", "msg": "progress  55\u202F% (15\u202FMB)"}
- {"dt": "2024-12-02T10:45:56.213616", "lvl": "debug", "msg": "progress  60\u202F% (16\u202FMB)"}
- {"dt": "2024-12-02T10:45:56.330579", "lvl": "debug", "msg": "progress  65\u202F% (17\u202FMB)"}
- {"dt": "2024-12-02T10:45:56.447605", "lvl": "debug", "msg": "progress  70\u202F% (19\u202FMB)"}
- {"dt": "2024-12-02T10:45:56.565592", "lvl": "debug", "msg": "progress  75\u202F% (20\u202FMB)"}
- {"dt": "2024-12-02T10:45:56.683842", "lvl": "debug", "msg": "progress  80\u202F% (22\u202FMB)"}
- {"dt": "2024-12-02T10:45:56.802304", "lvl": "debug", "msg": "progress  85\u202F% (23\u202FMB)"}
- {"dt": "2024-12-02T10:45:56.919174", "lvl": "debug", "msg": "progress  90\u202F% (24\u202FMB)"}
- {"dt": "2024-12-02T10:45:57.035542", "lvl": "debug", "msg": "progress  95\u202F% (26\u202FMB)"}
- {"dt": "2024-12-02T10:45:57.154445", "lvl": "debug", "msg": "progress 100\u202F% (27\u202FMB)"}
- {"dt": "2024-12-02T10:45:57.155434", "lvl": "info", "msg": "27\u202FMB downloaded in 3.20\u202Fs (8.62\u202FMB/s)"}
- {"dt": "2024-12-02T10:45:57.156119", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2024-12-02T10:45:57.156454", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "3.20", "extra": {"label": "kernel", "md5sum": "601b383c3c881d04e3368bd84040fdbd", "sha256sum": "99041f31b2ec83f4057caf62ca4bf1534d860cf2d8612fde079894451d55e486", "sha512sum": "e956efcf89178c3aa3fe2491edd04292d25136cf49bc45f7fdfcaa45b40768824dafc595be0d4dbccf066dc2d8dcfce3c6b446189612fa22ab6f3c465cab66c3", "size": ! "28887552"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:45:57.156756", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-12-02T10:45:57.156866", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:09:56) [common]"}
- {"dt": "2024-12-02T10:45:57.156953", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:09:56) [common]"}
- {"dt": "2024-12-02T10:45:57.157143", "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-02T10:45:57.157215", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/dtb/bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-02T10:45:57.157277", "lvl": "debug", "msg": "total size: 78059 (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:57.157340", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-02T10:45:57.455545", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:57.554054", "lvl": "debug", "msg": "progress  83\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:57.555562", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:45:57.556287", "lvl": "info", "msg": "0\u202FMB downloaded in 0.40\u202Fs (0.19\u202FMB/s)"}
- {"dt": "2024-12-02T10:45:57.556971", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:45:57.557300", "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-02T10:45:57.558005", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:45:57.558360", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:09:55) [common]"}
- {"dt": "2024-12-02T10:45:57.558716", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:09:55) [common]"}
- {"dt": "2024-12-02T10:45:57.558956", "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-02T10:45:57.559037", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-02T10:45:57.559099", "lvl": "debug", "msg": "total size: 493866112 (470\u202FMB)"}
- {"dt": "2024-12-02T10:45:57.559161", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-12-02T10:45:57.857166", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-02T10:46:00.357211", "lvl": "debug", "msg": "progress   5\u202F% (23\u202FMB)"}
- {"dt": "2024-12-02T10:46:01.936126", "lvl": "debug", "msg": "progress  10\u202F% (47\u202FMB)"}
- {"dt": "2024-12-02T10:46:03.694162", "lvl": "debug", "msg": "progress  15\u202F% (70\u202FMB)"}
- {"dt": "2024-12-02T10:46:05.503838", "lvl": "debug", "msg": "progress  20\u202F% (94\u202FMB)"}
- {"dt": "2024-12-02T10:46:07.246786", "lvl": "debug", "msg": "progress  25\u202F% (117\u202FMB)"}
- {"dt": "2024-12-02T10:46:09.111878", "lvl": "debug", "msg": "progress  30\u202F% (141\u202FMB)"}
- {"dt": "2024-12-02T10:46:10.995334", "lvl": "debug", "msg": "progress  35\u202F% (164\u202FMB)"}
- {"dt": "2024-12-02T10:46:12.953462", "lvl": "debug", "msg": "progress  40\u202F% (188\u202FMB)"}
- {"dt": "2024-12-02T10:46:14.535811", "lvl": "debug", "msg": "progress  45\u202F% (211\u202FMB)"}
- {"dt": "2024-12-02T10:46:16.253751", "lvl": "debug", "msg": "progress  50\u202F% (235\u202FMB)"}
- {"dt": "2024-12-02T10:46:17.926392", "lvl": "debug", "msg": "progress  55\u202F% (259\u202FMB)"}
- {"dt": "2024-12-02T10:46:19.628562", "lvl": "debug", "msg": "progress  60\u202F% (282\u202FMB)"}
- {"dt": "2024-12-02T10:46:22.793634", "lvl": "debug", "msg": "progress  65\u202F% (306\u202FMB)"}
- {"dt": "2024-12-02T10:46:24.523060", "lvl": "debug", "msg": "progress  70\u202F% (329\u202FMB)"}
- {"dt": "2024-12-02T10:46:26.631459", "lvl": "debug", "msg": "progress  75\u202F% (353\u202FMB)"}
- {"dt": "2024-12-02T10:46:28.451803", "lvl": "debug", "msg": "progress  80\u202F% (376\u202FMB)"}
- {"dt": "2024-12-02T10:46:30.116389", "lvl": "debug", "msg": "progress  85\u202F% (400\u202FMB)"}
- {"dt": "2024-12-02T10:46:32.039418", "lvl": "debug", "msg": "progress  90\u202F% (423\u202FMB)"}
- {"dt": "2024-12-02T10:46:33.884117", "lvl": "debug", "msg": "progress  95\u202F% (447\u202FMB)"}
- {"dt": "2024-12-02T10:46:42.570805", "lvl": "debug", "msg": "progress 100\u202F% (470\u202FMB)"}
- {"dt": "2024-12-02T10:46:43.442528", "lvl": "info", "msg": "470\u202FMB downloaded in 45.88\u202Fs (10.26\u202FMB/s)"}
- {"dt": "2024-12-02T10:46:43.442855", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:46) [common]"}
- {"dt": "2024-12-02T10:46:43.442946", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "45.88", "extra": {"label": "nbdroot", "md5sum": "4efb22dd71d11d782c04913029ebe146", "sha256sum": "b475a2953ee1a6be85506449e60e93d14579f8af2dc5577723d004a952eda229", "sha512sum": "888ffdcec6b22a5b3cd3e391eaa7a1bf5c6493efe72df9f895ac361c0117ba23335502223b5b769ad9e63ef740001581525ce5eaeaf2ac29e1b76eb53d8395b8", "size": ! "493866112"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:46:43.443126", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:46) [common]"}
- {"dt": "2024-12-02T10:46:43.443220", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.443494", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7"}
- {"dt": "2024-12-02T10:46:43.443668", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin"}
- {"dt": "2024-12-02T10:46:43.443783", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/tests"}
- {"dt": "2024-12-02T10:46:43.443889", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/results"}
- {"dt": "2024-12-02T10:46:43.444001", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-add-keys"}
- {"dt": "2024-12-02T10:46:43.444174", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-add-sources"}
- {"dt": "2024-12-02T10:46:43.444334", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-background-process-start"}
- {"dt": "2024-12-02T10:46:43.444496", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-background-process-stop"}
- {"dt": "2024-12-02T10:46:43.444667", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-common-functions"}
- {"dt": "2024-12-02T10:46:43.444865", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-echo-ipv4"}
- {"dt": "2024-12-02T10:46:43.445019", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-install-packages"}
- {"dt": "2024-12-02T10:46:43.445169", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-installed-packages"}
- {"dt": "2024-12-02T10:46:43.445317", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-os-build"}
- {"dt": "2024-12-02T10:46:43.445463", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-probe-channel"}
- {"dt": "2024-12-02T10:46:43.445609", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-probe-ip"}
- {"dt": "2024-12-02T10:46:43.445753", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-target-ip"}
- {"dt": "2024-12-02T10:46:43.445897", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-target-mac"}
- {"dt": "2024-12-02T10:46:43.446041", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-target-storage"}
- {"dt": "2024-12-02T10:46:43.446188", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-test-case"}
- {"dt": "2024-12-02T10:46:43.446336", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-test-event"}
- {"dt": "2024-12-02T10:46:43.446479", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-test-feedback"}
- {"dt": "2024-12-02T10:46:43.446625", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-test-raise"}
- {"dt": "2024-12-02T10:46:43.446772", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-test-reference"}
- {"dt": "2024-12-02T10:46:43.446915", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-test-runner"}
- {"dt": "2024-12-02T10:46:43.447058", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-test-set"}
- {"dt": "2024-12-02T10:46:43.447207", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-test-shell"}
- {"dt": "2024-12-02T10:46:43.447354", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-install-packages (oe)"}
- {"dt": "2024-12-02T10:46:43.447545", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/bin/lava-installed-packages (oe)"}
- {"dt": "2024-12-02T10:46:43.447718", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/environment"}
- {"dt": "2024-12-02T10:46:43.447861", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-12-02T10:46:43.447938", "lvl": "debug", "msg": "- LAVA_JOB_ID=38058"}
- {"dt": "2024-12-02T10:46:43.448005", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2024-12-02T10:46:43.448125", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.448445", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.448545", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.448613", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-12-02T10:46:43.448690", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.448786", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.448854", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-12-02T10:46:43.448928", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.449006", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.449082", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-12-02T10:46:43.449170", "lvl": "debug", "msg": "start: 1.5.4.1 inline-repo-action (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.449244", "lvl": "debug", "msg": "Using /lava-38058 at stage 0"}
- {"dt": "2024-12-02T10:46:43.449669", "lvl": "debug", "msg": "uuid=38058_1.5.4.1 testdef=None"}
- {"dt": "2024-12-02T10:46:43.449766", "lvl": "debug", "msg": "end: 1.5.4.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.449849", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.450475", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.450555", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38058_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:46:43.450716", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.451598", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.451708", "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": "38058_1.5.4.1", "uuid": "38058_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:46:43.451918", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.452870", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/0/tests/0_dmesg test_uuid 38058_1.5.4.1"}
- {"dt": "2024-12-02T10:46:43.453071", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.453151", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/0/tests/0_dmesg/run.sh", "from": "inline", "name": "dmesg", "path": "inline/dmesg.yaml", "uuid": "38058_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:46:43.453315", "lvl": "debug", "msg": "start: 1.5.4.5 inline-repo-action (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.453385", "lvl": "debug", "msg": "Using /lava-38058 at stage 1"}
- {"dt": "2024-12-02T10:46:43.453758", "lvl": "debug", "msg": "uuid=38058_1.5.4.5 testdef=None"}
- {"dt": "2024-12-02T10:46:43.453863", "lvl": "debug", "msg": "end: 1.5.4.5 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.453946", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.454495", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.454568", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38058_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:46:43.454720", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.455526", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.455627", "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": "38058_1.5.4.5", "uuid": "38058_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:46:43.455829", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.456718", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/1/tests/1_bootrr test_uuid 38058_1.5.4.5"}
- {"dt": "2024-12-02T10:46:43.456941", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.457020", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/1/tests/1_bootrr/run.sh", "from": "inline", "name": "bootrr", "path": "inline/bootrr.yaml", "uuid": "38058_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:46:43.457160", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-12-02T10:46:43.457221", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/0 for stage 0"}
- {"dt": "2024-12-02T10:46:43.457337", "lvl": "debug", "msg": "- 0_dmesg"}
- {"dt": "2024-12-02T10:46:43.457444", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38058/lava-overlay-6ad7y5g7/lava-38058/1 for stage 1"}
- {"dt": "2024-12-02T10:46:43.457546", "lvl": "debug", "msg": "- 1_bootrr"}
- {"dt": "2024-12-02T10:46:43.457652", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.457744", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.467601", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.467749", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.467850", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.467948", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.468041", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.468134", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.468234", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.468320", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.468405", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.468476", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2024-12-02T10:46:43.468546", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61986, NBD-ROOT: 38058/nbd-deploy-ztgfba97/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-02T10:46:43.469074", "lvl": "debug", "msg": "nbd-server 61986 /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/nbdroot/agl-ivi-demo-qt-raspberrypi5-20241028040434.ext4"}
- {"dt": "2024-12-02T10:46:43.471802", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-02T10:46:43.471968", "lvl": "debug", "msg": "output: ** (process:4526): WARNING **: 10:46:43.471: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2024-12-02T10:46:43.472069", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-02T10:46:43.472153", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2024-12-02T10:46:43.472276", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.472385", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:00:50) [common]"}
- {"dt": "2024-12-02T10:46:43.472492", "lvl": "info", "msg": "start: 2 minimal-boot (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.472596", "lvl": "debug", "msg": "start: 2.1 connect-device (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.472683", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63007'"}
- {"dt": "2024-12-02T10:46:43.476287", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-12-02T10:46:43.476965", "lvl": "debug", "msg": "end: 2.1 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:46:43.477201", "lvl": "debug", "msg": "start: 2.2 reset-device (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.477389", "lvl": "debug", "msg": "start: 2.2.1 pdu-reboot (timeout 00:09:10) [common]"}
- {"dt": "2024-12-02T10:46:43.477752", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'reset' '2'"}
- {"dt": "2024-12-02T10:46:44.486991", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-02T10:46:44.487502", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-12-02T10:46:48.511190", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-02T10:46:48.511807", "lvl": "debug", "msg": ">> Switched outlet 2 on\r"}
- {"dt": "2024-12-02T10:46:50.514993", "lvl": "debug", "msg": ">> export LANG='C'\r"}
- {"dt": "2024-12-02T10:46:50.515578", "lvl": "debug", "msg": ">> export LC_ALL='C.UTF-8'\r"}
- {"dt": "2024-12-02T10:46:50.515956", "lvl": "debug", "msg": ">> export PATH='/usr/local/bin:/usr/local/sbin:/bin:/usr/bin:/usr/sbin:/sbin'\r"}
- {"dt": "2024-12-02T10:46:50.516279", "lvl": "debug", "msg": ">> export PWD='/'\r"}
- {"dt": "2024-12-02T10:46:50.520423", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-12-02T10:46:50.524570", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp\r"}
- {"dt": "2024-12-02T10:46:50.531904", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T10:46:50.532419", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T10:46:50.532841", "lvl": "debug", "msg": ">> /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-12-02T10:46:50.537169", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4 /var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\r"}
- {"dt": "2024-12-02T10:46:50.544175", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/kernel/Image\r"}
- {"dt": "2024-12-02T10:46:50.549379", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=38058/nbd-deploy-ztgfba97/kernel/Image\r"}
- {"dt": "2024-12-02T10:46:50.552440", "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-02T10:46:50.593122", "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-02T10:46:50.614527", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61986      0.0.0.0:*    users:((\"nbd-server\",pid=4527,fd=3))\r"}
- {"dt": "2024-12-02T10:46:50.616304", "lvl": "debug", "msg": ">> DEBUG: PID=4527\r"}
- {"dt": "2024-12-02T10:46:50.622961", "lvl": "debug", "msg": ">> NBDPORT=61986\r"}
- {"dt": "2024-12-02T10:46:50.624457", "lvl": "debug", "msg": ">> basename: extra operand \u2018/var/lib/lava/dispatcher/tmp/initramfs-netboot-image-raspberrypi5.ext4.gz\u2019\r"}
- {"dt": "2024-12-02T10:46:50.624602", "lvl": "debug", "msg": ">> Try 'basename --help' for more information.\r"}
- {"dt": "2024-12-02T10:46:50.625306", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-12-02T10:46:50.625431", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-12-02T10:46:50.625527", "lvl": "debug", "msg": ">> \t\tkernel 38058/nbd-deploy-ztgfba97/kernel/Image\r"}
- {"dt": "2024-12-02T10:46:50.625612", "lvl": "debug", "msg": ">> \t\tinitramfs \r"}
- {"dt": "2024-12-02T10:46:50.625688", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-12-02T10:46:50.626323", "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-02T10:46:50.626647", "lvl": "debug", "msg": ">> DEBUG: found /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/dtb/bcm2712-rpi-5-b.dtb\r"}
- {"dt": "2024-12-02T10:46:50.628085", "lvl": "debug", "msg": ">> DEBUG: BASETFTP=/var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97\r"}
- {"dt": "2024-12-02T10:46:50.629457", "lvl": "debug", "msg": ">> DEBUG: INITRD=/var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T10:46:50.630925", "lvl": "debug", "msg": ">> DEBUG: REL_INITRD=38058/nbd-deploy-ztgfba97/initrd/initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T10:46:50.632208", "lvl": "debug", "msg": ">> DEBUG: IMAGE=/var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/kernel/Image\r"}
- {"dt": "2024-12-02T10:46:50.633836", "lvl": "debug", "msg": ">> DEBUG: REL_IMAGE=38058/nbd-deploy-ztgfba97/kernel/Image\r"}
- {"dt": "2024-12-02T10:46:50.688974", "lvl": "debug", "msg": ">> DEBUG: check LISTEN 0      10           0.0.0.0:61986      0.0.0.0:*    users:((\"nbd-server\",pid=4527,fd=3))\r"}
- {"dt": "2024-12-02T10:46:50.690828", "lvl": "debug", "msg": ">> DEBUG: PID=4527\r"}
- {"dt": "2024-12-02T10:46:50.697286", "lvl": "debug", "msg": ">> NBDPORT=61986\r"}
- {"dt": "2024-12-02T10:46:50.699609", "lvl": "debug", "msg": ">> \r"}
- {"dt": "2024-12-02T10:46:50.699767", "lvl": "debug", "msg": ">> \t\tenable_uart=1\r"}
- {"dt": "2024-12-02T10:46:50.699868", "lvl": "debug", "msg": ">> \t\tkernel 38058/nbd-deploy-ztgfba97/kernel/Image\r"}
- {"dt": "2024-12-02T10:46:50.699950", "lvl": "debug", "msg": ">> \t\tinitramfs initramfs-netboot-image-raspberrypi5-20241028040434.ext4\r"}
- {"dt": "2024-12-02T10:46:50.700028", "lvl": "debug", "msg": ">> \t\t\r"}
- {"dt": "2024-12-02T10:46:50.701413", "lvl": "debug", "msg": "Returned 0 in 7 seconds"}
- {"dt": "2024-12-02T10:46:50.802180", "lvl": "debug", "msg": "end: 2.2.1 pdu-reboot (duration 00:00:07) [common]"}
- {"dt": "2024-12-02T10:46:50.802767", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "7.32", "extra": {"status": "success"}, "level": "2.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:46:50.803461", "lvl": "debug", "msg": "end: 2.2 reset-device (duration 00:00:07) [common]"}
- {"dt": "2024-12-02T10:46:50.803872", "lvl": "debug", "msg": "start: 2.3 auto-login-action (timeout 00:09:02) [common]"}
- {"dt": "2024-12-02T10:46:50.804236", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-12-02T10:46:50.804556", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2024-12-02T10:46:50.805579", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-12-02T10:46:50.805891", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-12-02T10:46:50.806155", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-12-02T10:46:50.806412", "lvl": "target", "msg": "\0"}
- {"dt": "2024-12-02T10:46:50.806660", "lvl": "target", "msg": "  1.27 RPi: BOOTSYS release VERSION:0b72471b DATE: 2024/11/27 TIME: 14:28:19"}
- {"dt": "2024-12-02T10:46:50.806908", "lvl": "target", "msg": "  1.31 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1732717699 serial a18d1bdb boardrev d04170 stc 1631478"}
- {"dt": "2024-12-02T10:46:50.807122", "lvl": "target", "msg": "  1.41 AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-12-02T10:46:50.807180", "lvl": "target", "msg": "  1.49 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-02T10:46:50.807236", "lvl": "target", "msg": "  1.51 PM_RSTS: 0x00001000"}
- {"dt": "2024-12-02T10:46:50.807292", "lvl": "target", "msg": "  1.51 part 00000000 reset_info 00000000"}
- {"dt": "2024-12-02T10:46:50.807348", "lvl": "target", "msg": "  1.55 PMIC reset-event 00000000 rtc 00000001 alarm 00000000 enabled 0"}
- {"dt": "2024-12-02T10:46:50.807404", "lvl": "target", "msg": "  1.61 uSD voltage 3.3V"}
- {"dt": "2024-12-02T10:46:50.807459", "lvl": "target", "msg": "  1.81 Initialising SDRAM rank 2 total-size: 64 Gbit 4267 (0x14 0x00)"}
- {"dt": "2024-12-02T10:46:50.807516", "lvl": "target", "msg": "  1.84 DDR 4267 1 0 64 152 BL:-1"}
- {"dt": "2024-12-02T10:46:52.542101", "lvl": "target", "msg": "  3.65 OTP boardrev d04170 bootrom a a"}
- {"dt": "2024-12-02T10:46:52.563109", "lvl": "target", "msg": "  3.66 Customer key hash 0000000000000000000000000000000000000000000000000000000000000000"}
- {"dt": "2024-12-02T10:46:52.563685", "lvl": "target", "msg": "  3.74 VC-JTAG unlocked"}
- {"dt": "2024-12-02T10:46:52.578716", "lvl": "target", "msg": "  3.96 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-02T10:46:53.121756", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:46:53.201605", "lvl": "target", "msg": "  4.24 RP1_BOOT chip ID: 0x20001927"}
- {"dt": "2024-12-02T10:46:53.217543", "lvl": "target", "msg": "  4.25 RP1_BOOT: fw size 46888"}
- {"dt": "2024-12-02T10:46:53.840302", "lvl": "target", "msg": "  4.62 PCI2 init"}
- {"dt": "2024-12-02T10:46:53.840896", "lvl": "target", "msg": "  4.62 PCI2 reset"}
- {"dt": "2024-12-02T10:46:53.918978", "lvl": "target", "msg": "  5.07 PCIe scan 00001de4:00000001"}
- {"dt": "2024-12-02T10:46:53.919551", "lvl": "target", "msg": "  5.08 RP1_CHIP_INFO 20001927"}
- {"dt": "2024-12-02T10:46:53.919934", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:46:53.920515", "lvl": "target", "msg": "  5.10 RPi: BOOTLOADER release VERSION:0b72471b DATE: 2024/11/27 TIME: 14:28:19"}
- {"dt": "2024-12-02T10:46:53.922452", "lvl": "target", "msg": "  5.17 BOOTMODE: 0x06 partition 0 build-ts BUILD_TIMESTAMP=1732717699 serial a18d1bdb boardrev d04170 stc 5017946"}
- {"dt": "2024-12-02T10:46:53.922939", "lvl": "target", "msg": "  5.27 AON_RESET: 00000003 PM_RSTS 00001000"}
- {"dt": "2024-12-02T10:46:53.984211", "lvl": "target", "msg": "  5.55 usb_pd_init status 3"}
- {"dt": "2024-12-02T10:46:53.984830", "lvl": "target", "msg": "  5.55 USB_PD CONFIG 0 41"}
- {"dt": "2024-12-02T10:46:53.985177", "lvl": "target", "msg": "  5.62 XHCI-STOP"}
- {"dt": "2024-12-02T10:46:53.985449", "lvl": "target", "msg": "  5.62 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:53.985711", "lvl": "target", "msg": "  5.66 USBSTS 1"}
- {"dt": "2024-12-02T10:46:53.986260", "lvl": "target", "msg": "  5.69 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:53.986541", "lvl": "target", "msg": "  5.74 xHC0 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-02T10:46:53.986795", "lvl": "target", "msg": "  5.86 XHCI-STOP"}
- {"dt": "2024-12-02T10:46:53.987039", "lvl": "target", "msg": "  5.86 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:53.987301", "lvl": "target", "msg": "  5.91 USBSTS 1"}
- {"dt": "2024-12-02T10:46:53.987573", "lvl": "target", "msg": "  5.93 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:53.987930", "lvl": "target", "msg": "  5.98 xHC1 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-02T10:46:54.031767", "lvl": "target", "msg": "  5.13 USB-PD: src-cap PDO object1 0x0a11912c"}
- {"dt": "2024-12-02T10:46:54.032369", "lvl": "target", "msg": "  5.14 Current 3000 mA"}
- {"dt": "2024-12-02T10:46:54.032785", "lvl": "target", "msg": "  5.16 Voltage 5000 mV"}
- {"dt": "2024-12-02T10:46:54.033081", "lvl": "target", "msg": "  5.18 USB-PD: src-cap PDO object2 0x0012d12c"}
- {"dt": "2024-12-02T10:46:54.033657", "lvl": "target", "msg": "  5.23 Current 3000 mA"}
- {"dt": "2024-12-02T10:46:54.033936", "lvl": "target", "msg": "  5.25 Voltage 9000 mV"}
- {"dt": "2024-12-02T10:46:54.034186", "lvl": "target", "msg": "  5.27 USB-PD: src-cap PDO object3 0x0014b12c"}
- {"dt": "2024-12-02T10:46:54.034432", "lvl": "target", "msg": "  5.31 Current 3000 mA"}
- {"dt": "2024-12-02T10:46:54.034676", "lvl": "target", "msg": "  5.33 Voltage 15000 mV"}
- {"dt": "2024-12-02T10:46:54.034923", "lvl": "target", "msg": "  5.35 USB-PD: src-cap PDO object4 0x00164145"}
- {"dt": "2024-12-02T10:46:54.035189", "lvl": "target", "msg": "  5.39 Current 3250 mA"}
- {"dt": "2024-12-02T10:46:54.035465", "lvl": "target", "msg": "  5.41 Voltage 20000 mV"}
- {"dt": "2024-12-02T10:46:54.035800", "lvl": "target", "msg": "  5.43 Boot mode: SD (01) order 24"}
- {"dt": "2024-12-02T10:46:54.561683", "lvl": "target", "msg": "  5.61 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T10:46:54.565358", "lvl": "target", "msg": "  5.72 SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T10:46:54.660717", "lvl": "target", "msg": "  5.81 EMMC"}
- {"dt": "2024-12-02T10:46:54.756619", "lvl": "target", "msg": "  5.81 SD retry 1 oc 0"}
- {"dt": "2024-12-02T10:46:54.798447", "lvl": "target", "msg": "  5.05 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T10:46:54.894248", "lvl": "target", "msg": "  6.14 SD retry 2 oc 0"}
- {"dt": "2024-12-02T10:46:54.930969", "lvl": "target", "msg": "  6.38 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T10:46:55.026913", "lvl": "target", "msg": "  6.47 SDV1"}
- {"dt": "2024-12-02T10:46:55.140492", "lvl": "target", "msg": "  6.47 SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-12-02T10:46:55.143974", "lvl": "target", "msg": "  6.49 Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-12-02T10:46:55.144523", "lvl": "target", "msg": "  6.55 Retry SD 1"}
- {"dt": "2024-12-02T10:46:55.188430", "lvl": "target", "msg": "  6.85 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T10:46:55.191557", "lvl": "target", "msg": "  6.96 SD HOST: 200000000 CTL0: 0x00800f00 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T10:46:55.287355", "lvl": "target", "msg": "  6.05 EMMC"}
- {"dt": "2024-12-02T10:46:55.383307", "lvl": "target", "msg": "  6.05 SD retry 1 oc 0"}
- {"dt": "2024-12-02T10:46:55.422197", "lvl": "target", "msg": "  6.29 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T10:46:55.517993", "lvl": "target", "msg": "  6.38 SD retry 2 oc 0"}
- {"dt": "2024-12-02T10:46:55.556003", "lvl": "target", "msg": "  6.63 SD HOST: 200000000 CTL0: 0x00800000 BUS: 400000 Hz actual: 390625 HZ div: 512 (256) status: 0x1fff0000 delay: 276"}
- {"dt": "2024-12-02T10:46:55.651816", "lvl": "target", "msg": "  6.72 SDV1"}
- {"dt": "2024-12-02T10:46:55.765091", "lvl": "target", "msg": "  6.72 SD CMD: 0x371a0010 (55) 0x0 0x1fff0001"}
- {"dt": "2024-12-02T10:46:55.768455", "lvl": "target", "msg": "  6.73 Failed to open device: 'sdcard' (cmd 371a0010 status 1fff0001)"}
- {"dt": "2024-12-02T10:46:55.768996", "lvl": "target", "msg": "  6.79 Boot mode: USB-MSD (04) order 2"}
- {"dt": "2024-12-02T10:46:55.818287", "lvl": "target", "msg": "  6.97 XHCI-STOP"}
- {"dt": "2024-12-02T10:46:55.818851", "lvl": "target", "msg": "  6.97 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:55.819174", "lvl": "target", "msg": "  6.01 USBSTS 0"}
- {"dt": "2024-12-02T10:46:55.819440", "lvl": "target", "msg": "  6.05 XHCI-STOP"}
- {"dt": "2024-12-02T10:46:55.819689", "lvl": "target", "msg": "  6.05 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:55.819934", "lvl": "target", "msg": "  6.10 USBSTS 0"}
- {"dt": "2024-12-02T10:46:55.820171", "lvl": "target", "msg": "  6.18 XHCI-STOP"}
- {"dt": "2024-12-02T10:46:55.820407", "lvl": "target", "msg": "  6.18 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:55.820644", "lvl": "target", "msg": "  6.23 USBSTS 1"}
- {"dt": "2024-12-02T10:46:55.820926", "lvl": "target", "msg": "  6.25 xHC0 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:55.821171", "lvl": "target", "msg": "  6.30 xHC0 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-02T10:46:55.821419", "lvl": "target", "msg": "  6.42 XHCI-STOP"}
- {"dt": "2024-12-02T10:46:55.844198", "lvl": "target", "msg": "  6.42 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:55.844797", "lvl": "target", "msg": "  6.47 USBSTS 1"}
- {"dt": "2024-12-02T10:46:55.845160", "lvl": "target", "msg": "  6.49 xHC1 ver: 272 HCS: 03000440 140000f1 07ff000a HCC: 0240fe6d"}
- {"dt": "2024-12-02T10:46:55.845432", "lvl": "target", "msg": "  6.54 xHC1 ports 3 slots 64 intrs 4"}
- {"dt": "2024-12-02T10:47:20.853713", "lvl": "target", "msg": " 31.62 USB MSD stopped. Timeout: 25 seconds"}
- {"dt": "2024-12-02T10:47:20.856948", "lvl": "target", "msg": " 31.63 Boot mode: NETWORK (02) order 0"}
- {"dt": "2024-12-02T10:47:20.942648", "lvl": "target", "msg": " 32.50 NETWORK: d8:3a:dd:e5:72:37 wait for link TFTP: 192.168.66.1"}
- {"dt": "2024-12-02T10:47:23.864443", "lvl": "target", "msg": " 34.75 LINK STATUS: speed: 1000 full duplex"}
- {"dt": "2024-12-02T10:47:23.865105", "lvl": "target", "msg": " 34.76 Link ready"}
- {"dt": "2024-12-02T10:47:23.911548", "lvl": "target", "msg": " 35.20 RX: 0 IP: 0 IPV4: 0 MAC: 0 UDP: 0 UDP RECV: 0 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:47:31.936113", "lvl": "target", "msg": " 43.21 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-02T10:47:31.936816", "lvl": "target", "msg": " 43.22 YI_ADDR 192.168.68.231"}
- {"dt": "2024-12-02T10:47:31.937380", "lvl": "target", "msg": " 43.25 SI_ADDR 192.168.68.1"}
- {"dt": "2024-12-02T10:47:31.937717", "lvl": "target", "msg": " 43.27 OPTIONS:-"}
- {"dt": "2024-12-02T10:47:31.938305", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv OFFER (2) expect OFFER 43.33 "}
- {"dt": "2024-12-02T10:47:31.938593", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1 43.36 "}
- {"dt": "2024-12-02T10:47:31.938849", "lvl": "target", "msg": "\top: 51 len:   4  43.39 "}
- {"dt": "2024-12-02T10:47:31.939096", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0 43.42 "}
- {"dt": "2024-12-02T10:47:31.939338", "lvl": "target", "msg": "\top:  3 len:   4 192.168.68.1 43.45 "}
- {"dt": "2024-12-02T10:47:31.939577", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-02T10:47:31.939953", "lvl": "target", "msg": " 43.48 "}
- {"dt": "2024-12-02T10:47:31.940276", "lvl": "target", "msg": " 43.49 NET 192.168.68.231 255.255.255.0 gw 192.168.68.1 tftp 192.168.66.1"}
- {"dt": "2024-12-02T10:47:31.940546", "lvl": "target", "msg": " 43.55 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-02T10:47:31.940892", "lvl": "target", "msg": " 43.59 YI_ADDR 192.168.68.231"}
- {"dt": "2024-12-02T10:47:31.979288", "lvl": "target", "msg": " 43.62 SI_ADDR 192.168.68.1"}
- {"dt": "2024-12-02T10:47:31.979882", "lvl": "target", "msg": " 43.65 OPTIONS:-"}
- {"dt": "2024-12-02T10:47:31.980291", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv ACK (5) expect ACK 43.70 "}
- {"dt": "2024-12-02T10:47:31.980621", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1 43.73 "}
- {"dt": "2024-12-02T10:47:31.981243", "lvl": "target", "msg": "\top: 51 len:   4  43.76 "}
- {"dt": "2024-12-02T10:47:31.981528", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0 43.79 "}
- {"dt": "2024-12-02T10:47:31.981782", "lvl": "target", "msg": "\top:  3 len:   4 192.168.68.1 43.82 "}
- {"dt": "2024-12-02T10:47:31.982032", "lvl": "target", "msg": "\top:  6 len:   4 10.1.1.1 43.85 "}
- {"dt": "2024-12-02T10:47:31.982275", "lvl": "target", "msg": "\top: 150 len:   4  43.88 "}
- {"dt": "2024-12-02T10:47:31.982516", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-02T10:47:31.982754", "lvl": "target", "msg": " 43.90 "}
- {"dt": "2024-12-02T10:47:31.982990", "lvl": "target", "msg": "\top: 15 len:  11  43.92 "}
- {"dt": "2024-12-02T10:47:31.983245", "lvl": "target", "msg": " 43.94 ARP 192.168.68.1 70:70:fc:05:68:c7"}
- {"dt": "2024-12-02T10:47:31.983575", "lvl": "target", "msg": " 43.97 NET 192.168.68.231 255.255.255.0 gw 192.168.68.1 tftp 192.168.66.1"}
- {"dt": "2024-12-02T10:47:32.011384", "lvl": "target", "msg": " 43.04 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:47:32.011931", "lvl": "target", "msg": " 43.11 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 a18d1bdb/config.txt"}
- {"dt": "2024-12-02T10:47:32.012262", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:47:32.014559", "lvl": "target", "msg": " 43.17 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:48:02.018730", "lvl": "target", "msg": " 73.11 TFTP: disconnect: timeouts 14"}
- {"dt": "2024-12-02T10:48:02.019444", "lvl": "target", "msg": " 73.12 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:48:02.019808", "lvl": "target", "msg": " 73.19 BMD \\\"a18d1bdb/config.txt\\\" not found"}
- {"dt": "2024-12-02T10:48:02.020098", "lvl": "target", "msg": " 73.24 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 config.txt"}
- {"dt": "2024-12-02T10:48:02.020362", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:48:02.022093", "lvl": "target", "msg": " 73.28 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:48:32.019859", "lvl": "target", "msg": "103.24 TFTP: disconnect: timeouts 28"}
- {"dt": "2024-12-02T10:48:32.020471", "lvl": "target", "msg": "103.24 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:48:32.023105", "lvl": "target", "msg": "103.32 Read config.txt failed"}
- {"dt": "2024-12-02T10:48:32.023628", "lvl": "target", "msg": "103.34 Retry NETWORK 1"}
- {"dt": "2024-12-02T10:48:32.095068", "lvl": "target", "msg": "103.81 Stopping network"}
- {"dt": "2024-12-02T10:48:32.095670", "lvl": "target", "msg": "103.81 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:48:32.096392", "lvl": "target", "msg": "103.88 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:48:32.096731", "lvl": "target", "msg": "103.95 RP1_NET NETWORK_CONTROL : 0x0000001c"}
- {"dt": "2024-12-02T10:48:32.097040", "lvl": "target", "msg": "103.99 RP1_NET NETWORK_CONFIG : 0x00d40502"}
- {"dt": "2024-12-02T10:48:32.097301", "lvl": "target", "msg": "103.03 RP1_NET NETWORK_STATUS : 0x00000006"}
- {"dt": "2024-12-02T10:48:32.097551", "lvl": "target", "msg": "103.07 RP1_NET DMA_CONFIG : 0x4020071f"}
- {"dt": "2024-12-02T10:48:32.097800", "lvl": "target", "msg": "103.10 RP1_NET TRANSMIT_STATUS : 0x00000021"}
- {"dt": "2024-12-02T10:48:32.098044", "lvl": "target", "msg": "103.14 RP1_NET RECEIVE_STATUS : 0x00000002"}
- {"dt": "2024-12-02T10:48:32.098760", "lvl": "target", "msg": "103.18 RP1_NET INT_STATUS : 0x0000008a"}
- {"dt": "2024-12-02T10:48:32.138311", "lvl": "target", "msg": "103.22 RP1_NET FRAMES_TXED_64 : 0x00000001"}
- {"dt": "2024-12-02T10:48:32.138885", "lvl": "target", "msg": "103.25 RP1_NET FRAMES_TXED_65 : 0x0000001d"}
- {"dt": "2024-12-02T10:48:32.139272", "lvl": "target", "msg": "103.29 RP1_NET FRAMES_TXED_128 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.139604", "lvl": "target", "msg": "103.33 RP1_NET FRAMES_TXED_256 : 0x00000006"}
- {"dt": "2024-12-02T10:48:32.140188", "lvl": "target", "msg": "103.37 RP1_NET FRAMES_TXED_512 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.140467", "lvl": "target", "msg": "103.41 RP1_NET FRAMES_TXED_1024 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.140724", "lvl": "target", "msg": "103.45 RP1_NET FRAMES_TXED_1519 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.141013", "lvl": "target", "msg": "103.49 RP1_NET TX_UNDERRUNS : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.141256", "lvl": "target", "msg": "103.53 RP1_NET FRAMES_RXED_OK : 0x00000003"}
- {"dt": "2024-12-02T10:48:32.141500", "lvl": "target", "msg": "103.57 RP1_NET BROADCAST_RXED : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.142538", "lvl": "target", "msg": "103.60 RP1_NET MULTICAST_RXED : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.181663", "lvl": "target", "msg": "103.64 RP1_NET FRAMES_RXED_64 : 0x00000001"}
- {"dt": "2024-12-02T10:48:32.182222", "lvl": "target", "msg": "103.68 RP1_NET FRAMES_RXED_65 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.182555", "lvl": "target", "msg": "103.72 RP1_NET FRAMES_RXED_128 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.182828", "lvl": "target", "msg": "103.76 RP1_NET FRAMES_RXED_256 : 0x00000002"}
- {"dt": "2024-12-02T10:48:32.183082", "lvl": "target", "msg": "103.80 RP1_NET FRAMES_RXED_512 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.183326", "lvl": "target", "msg": "103.84 RP1_NET FRAMES_RXED_1024 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.183571", "lvl": "target", "msg": "103.88 RP1_NET FRAMES_RXED_1519 : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.183812", "lvl": "target", "msg": "103.92 RP1_NET FCS_ERRORS : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.184052", "lvl": "target", "msg": "103.95 RP1_NET RX_RESOURCE_ERRORS : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.184293", "lvl": "target", "msg": "103.00 RP1_NET RX_OVERRUNS : 0x00000000"}
- {"dt": "2024-12-02T10:48:32.184906", "lvl": "target", "msg": "103.03 RP1_NET TRANSMIT_Q_PTR : 0x3c320900"}
- {"dt": "2024-12-02T10:48:32.218501", "lvl": "target", "msg": "103.07 RP1_NET RECEIVE_Q_PTR : 0x3c3203f0"}
- {"dt": "2024-12-02T10:48:32.219062", "lvl": "target", "msg": "103.11 RP1_NET tx_q_base : 0xfc3208c0"}
- {"dt": "2024-12-02T10:48:32.219402", "lvl": "target", "msg": "103.14 RP1_NET rx_q_base : 0xfc3203c0"}
- {"dt": "2024-12-02T10:48:32.219676", "lvl": "target", "msg": "103.17 RP1_NET rx dropped : 0"}
- {"dt": "2024-12-02T10:48:32.219934", "lvl": "target", "msg": "103.20 Stopping network"}
- {"dt": "2024-12-02T10:48:32.220186", "lvl": "target", "msg": "103.22 Stopping network"}
- {"dt": "2024-12-02T10:48:32.220433", "lvl": "target", "msg": "103.25 NETWORK: d8:3a:dd:e5:72:37 wait for link TFTP: 192.168.66.1"}
- {"dt": "2024-12-02T10:48:32.221766", "lvl": "target", "msg": "103.31 LINK STATUS: speed: 1000 full duplex"}
- {"dt": "2024-12-02T10:48:32.222287", "lvl": "target", "msg": "103.34 Link ready"}
- {"dt": "2024-12-02T10:48:32.301345", "lvl": "target", "msg": "103.88 RX: 3 IP: 0 IPV4: 2 MAC: 2 UDP: 2 UDP RECV: 2 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:48:32.301952", "lvl": "target", "msg": "103.94 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-02T10:48:32.302308", "lvl": "target", "msg": "103.97 YI_ADDR 192.168.68.231"}
- {"dt": "2024-12-02T10:48:32.302587", "lvl": "target", "msg": "103.00 SI_ADDR 192.168.68.1"}
- {"dt": "2024-12-02T10:48:32.302849", "lvl": "target", "msg": "103.03 OPTIONS:-"}
- {"dt": "2024-12-02T10:48:32.303100", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv OFFER (2) expect OFFER103.08 "}
- {"dt": "2024-12-02T10:48:32.303347", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1103.12 "}
- {"dt": "2024-12-02T10:48:32.303588", "lvl": "target", "msg": "\top: 51 len:   4 103.14 "}
- {"dt": "2024-12-02T10:48:32.303831", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0103.17 "}
- {"dt": "2024-12-02T10:48:32.304069", "lvl": "target", "msg": "\top:  3 len:   4 192.168.68.1103.21 "}
- {"dt": "2024-12-02T10:48:32.304310", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-02T10:48:32.304562", "lvl": "target", "msg": "103.23 "}
- {"dt": "2024-12-02T10:48:32.344622", "lvl": "target", "msg": "103.24 NET 192.168.68.231 255.255.255.0 gw 192.168.68.1 tftp 192.168.66.1"}
- {"dt": "2024-12-02T10:48:32.345570", "lvl": "target", "msg": "103.30 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-02T10:48:32.345933", "lvl": "target", "msg": "103.35 YI_ADDR 192.168.68.231"}
- {"dt": "2024-12-02T10:48:32.346218", "lvl": "target", "msg": "103.37 SI_ADDR 192.168.68.1"}
- {"dt": "2024-12-02T10:48:32.346485", "lvl": "target", "msg": "103.40 OPTIONS:-"}
- {"dt": "2024-12-02T10:48:32.346740", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv ACK (5) expect ACK103.45 "}
- {"dt": "2024-12-02T10:48:32.346986", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1103.49 "}
- {"dt": "2024-12-02T10:48:32.347228", "lvl": "target", "msg": "\top: 51 len:   4 103.51 "}
- {"dt": "2024-12-02T10:48:32.347466", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0103.54 "}
- {"dt": "2024-12-02T10:48:32.347715", "lvl": "target", "msg": "\top:  3 len:   4 192.168.68.1103.58 "}
- {"dt": "2024-12-02T10:48:32.347984", "lvl": "target", "msg": "\top:  6 len:   4 10.1.1.1103.61 "}
- {"dt": "2024-12-02T10:48:32.348258", "lvl": "target", "msg": "\top: 150 len:   4 103.63 "}
- {"dt": "2024-12-02T10:48:32.348519", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-02T10:48:32.348792", "lvl": "target", "msg": "103.66 "}
- {"dt": "2024-12-02T10:48:32.349112", "lvl": "target", "msg": "\top: 15 len:  11 103.68 "}
- {"dt": "2024-12-02T10:48:32.386975", "lvl": "target", "msg": "103.69 ARP 192.168.68.1 70:70:fc:05:68:c7"}
- {"dt": "2024-12-02T10:48:32.387543", "lvl": "target", "msg": "103.72 NET 192.168.68.231 255.255.255.0 gw 192.168.68.1 tftp 192.168.66.1"}
- {"dt": "2024-12-02T10:48:32.387898", "lvl": "target", "msg": "103.79 RX: 6 IP: 0 IPV4: 4 MAC: 4 UDP: 4 UDP RECV: 4 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:48:32.388179", "lvl": "target", "msg": "103.86 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 a18d1bdb/config.txt"}
- {"dt": "2024-12-02T10:48:32.388443", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:48:32.390290", "lvl": "target", "msg": "103.92 RX: 6 IP: 0 IPV4: 4 MAC: 4 UDP: 4 UDP RECV: 4 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:02.394906", "lvl": "target", "msg": "133.86 TFTP: disconnect: timeouts 42"}
- {"dt": "2024-12-02T10:49:02.395496", "lvl": "target", "msg": "133.87 RX: 6 IP: 0 IPV4: 4 MAC: 4 UDP: 4 UDP RECV: 4 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:02.395882", "lvl": "target", "msg": "133.94 BMD \\\"a18d1bdb/config.txt\\\" not found"}
- {"dt": "2024-12-02T10:49:02.396516", "lvl": "target", "msg": "133.99 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 config.txt"}
- {"dt": "2024-12-02T10:49:02.396865", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:49:02.398485", "lvl": "target", "msg": "133.03 RX: 6 IP: 0 IPV4: 4 MAC: 4 UDP: 4 UDP RECV: 4 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:32.395451", "lvl": "target", "msg": "163.99 TFTP: disconnect: timeouts 56"}
- {"dt": "2024-12-02T10:49:32.396050", "lvl": "target", "msg": "163.00 RX: 6 IP: 0 IPV4: 4 MAC: 4 UDP: 4 UDP RECV: 4 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:32.398672", "lvl": "target", "msg": "163.07 Read config.txt failed"}
- {"dt": "2024-12-02T10:49:37.396167", "lvl": "target", "msg": "168.10 Retry NETWORK 2"}
- {"dt": "2024-12-02T10:49:37.468101", "lvl": "target", "msg": "168.54 Stopping network"}
- {"dt": "2024-12-02T10:49:37.468658", "lvl": "target", "msg": "168.54 RX: 6 IP: 0 IPV4: 4 MAC: 4 UDP: 4 UDP RECV: 4 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.469425", "lvl": "target", "msg": "168.60 RX: 6 IP: 0 IPV4: 4 MAC: 4 UDP: 4 UDP RECV: 4 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.469753", "lvl": "target", "msg": "168.68 RP1_NET NETWORK_CONTROL : 0x0000001c"}
- {"dt": "2024-12-02T10:49:37.470026", "lvl": "target", "msg": "168.72 RP1_NET NETWORK_CONFIG : 0x00d40502"}
- {"dt": "2024-12-02T10:49:37.470285", "lvl": "target", "msg": "168.75 RP1_NET NETWORK_STATUS : 0x00000006"}
- {"dt": "2024-12-02T10:49:37.470551", "lvl": "target", "msg": "168.79 RP1_NET DMA_CONFIG : 0x4020071f"}
- {"dt": "2024-12-02T10:49:37.470804", "lvl": "target", "msg": "168.83 RP1_NET TRANSMIT_STATUS : 0x00000021"}
- {"dt": "2024-12-02T10:49:37.471048", "lvl": "target", "msg": "168.87 RP1_NET RECEIVE_STATUS : 0x00000002"}
- {"dt": "2024-12-02T10:49:37.471860", "lvl": "target", "msg": "168.91 RP1_NET INT_STATUS : 0x0000008a"}
- {"dt": "2024-12-02T10:49:37.511496", "lvl": "target", "msg": "168.94 RP1_NET FRAMES_TXED_64 : 0x00000001"}
- {"dt": "2024-12-02T10:49:37.512119", "lvl": "target", "msg": "168.98 RP1_NET FRAMES_TXED_65 : 0x0000001e"}
- {"dt": "2024-12-02T10:49:37.512452", "lvl": "target", "msg": "168.02 RP1_NET FRAMES_TXED_128 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.512729", "lvl": "target", "msg": "168.06 RP1_NET FRAMES_TXED_256 : 0x00000004"}
- {"dt": "2024-12-02T10:49:37.513405", "lvl": "target", "msg": "168.10 RP1_NET FRAMES_TXED_512 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.513688", "lvl": "target", "msg": "168.13 RP1_NET FRAMES_TXED_1024 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.513943", "lvl": "target", "msg": "168.17 RP1_NET FRAMES_TXED_1519 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.514192", "lvl": "target", "msg": "168.21 RP1_NET TX_UNDERRUNS : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.514437", "lvl": "target", "msg": "168.25 RP1_NET FRAMES_RXED_OK : 0x00000003"}
- {"dt": "2024-12-02T10:49:37.514684", "lvl": "target", "msg": "168.29 RP1_NET BROADCAST_RXED : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.515000", "lvl": "target", "msg": "168.33 RP1_NET MULTICAST_RXED : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.554719", "lvl": "target", "msg": "168.37 RP1_NET FRAMES_RXED_64 : 0x00000001"}
- {"dt": "2024-12-02T10:49:37.555276", "lvl": "target", "msg": "168.41 RP1_NET FRAMES_RXED_65 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.555613", "lvl": "target", "msg": "168.44 RP1_NET FRAMES_RXED_128 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.555884", "lvl": "target", "msg": "168.48 RP1_NET FRAMES_RXED_256 : 0x00000002"}
- {"dt": "2024-12-02T10:49:37.556140", "lvl": "target", "msg": "168.52 RP1_NET FRAMES_RXED_512 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.556390", "lvl": "target", "msg": "168.56 RP1_NET FRAMES_RXED_1024 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.556636", "lvl": "target", "msg": "168.60 RP1_NET FRAMES_RXED_1519 : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.556939", "lvl": "target", "msg": "168.64 RP1_NET FCS_ERRORS : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.557184", "lvl": "target", "msg": "168.68 RP1_NET RX_RESOURCE_ERRORS : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.557427", "lvl": "target", "msg": "168.72 RP1_NET RX_OVERRUNS : 0x00000000"}
- {"dt": "2024-12-02T10:49:37.558072", "lvl": "target", "msg": "168.75 RP1_NET TRANSMIT_Q_PTR : 0x3c3208f0"}
- {"dt": "2024-12-02T10:49:37.591537", "lvl": "target", "msg": "168.79 RP1_NET RECEIVE_Q_PTR : 0x3c3203f0"}
- {"dt": "2024-12-02T10:49:37.592103", "lvl": "target", "msg": "168.83 RP1_NET tx_q_base : 0xfc3208c0"}
- {"dt": "2024-12-02T10:49:37.592432", "lvl": "target", "msg": "168.86 RP1_NET rx_q_base : 0xfc3203c0"}
- {"dt": "2024-12-02T10:49:37.592707", "lvl": "target", "msg": "168.90 RP1_NET rx dropped : 0"}
- {"dt": "2024-12-02T10:49:37.593022", "lvl": "target", "msg": "168.93 Stopping network"}
- {"dt": "2024-12-02T10:49:37.593279", "lvl": "target", "msg": "168.95 Stopping network"}
- {"dt": "2024-12-02T10:49:37.593542", "lvl": "target", "msg": "168.97 NETWORK: d8:3a:dd:e5:72:37 wait for link TFTP: 192.168.66.1"}
- {"dt": "2024-12-02T10:49:37.594679", "lvl": "target", "msg": "168.03 LINK STATUS: speed: 1000 full duplex"}
- {"dt": "2024-12-02T10:49:37.595225", "lvl": "target", "msg": "168.07 Link ready"}
- {"dt": "2024-12-02T10:49:37.675379", "lvl": "target", "msg": "168.62 RX: 6 IP: 0 IPV4: 4 MAC: 4 UDP: 4 UDP RECV: 4 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.675967", "lvl": "target", "msg": "168.67 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-02T10:49:37.676304", "lvl": "target", "msg": "168.71 YI_ADDR 192.168.66.231"}
- {"dt": "2024-12-02T10:49:37.676587", "lvl": "target", "msg": "168.74 SI_ADDR 192.168.66.1"}
- {"dt": "2024-12-02T10:49:37.676901", "lvl": "target", "msg": "168.76 OPTIONS:-"}
- {"dt": "2024-12-02T10:49:37.677158", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv OFFER (2) expect OFFER168.82 "}
- {"dt": "2024-12-02T10:49:37.677404", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1168.85 "}
- {"dt": "2024-12-02T10:49:37.677649", "lvl": "target", "msg": "\top: 51 len:   4 168.88 "}
- {"dt": "2024-12-02T10:49:37.677893", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0168.91 "}
- {"dt": "2024-12-02T10:49:37.678471", "lvl": "target", "msg": "\top:  3 len:   4 192.168.66.1168.94 "}
- {"dt": "2024-12-02T10:49:37.678810", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-02T10:49:37.679103", "lvl": "target", "msg": "168.97 "}
- {"dt": "2024-12-02T10:49:37.718561", "lvl": "target", "msg": "168.98 NET 192.168.66.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.66.1"}
- {"dt": "2024-12-02T10:49:37.719152", "lvl": "target", "msg": "168.04 DHCP src: 70:70:fc:05:68:c7 192.168.66.1"}
- {"dt": "2024-12-02T10:49:37.719500", "lvl": "target", "msg": "168.08 YI_ADDR 192.168.66.231"}
- {"dt": "2024-12-02T10:49:37.719779", "lvl": "target", "msg": "168.11 SI_ADDR 192.168.66.1"}
- {"dt": "2024-12-02T10:49:37.720042", "lvl": "target", "msg": "168.13 OPTIONS:-"}
- {"dt": "2024-12-02T10:49:37.720297", "lvl": "target", "msg": "\top: 53 len:   1 DHCP recv ACK (5) expect ACK168.19 "}
- {"dt": "2024-12-02T10:49:37.720546", "lvl": "target", "msg": "\top: 54 len:   4 192.168.66.1168.22 "}
- {"dt": "2024-12-02T10:49:37.720838", "lvl": "target", "msg": "\top: 51 len:   4 168.24 "}
- {"dt": "2024-12-02T10:49:37.721083", "lvl": "target", "msg": "\top:  1 len:   4 255.255.255.0168.28 "}
- {"dt": "2024-12-02T10:49:37.721322", "lvl": "target", "msg": "\top:  3 len:   4 192.168.66.1168.31 "}
- {"dt": "2024-12-02T10:49:37.721587", "lvl": "target", "msg": "\top:  6 len:   4 10.1.1.1168.34 "}
- {"dt": "2024-12-02T10:49:37.721854", "lvl": "target", "msg": "\top: 150 len:   4 168.36 "}
- {"dt": "2024-12-02T10:49:37.722152", "lvl": "target", "msg": "\top: 43 len:  17 "}
- {"dt": "2024-12-02T10:49:37.722420", "lvl": "target", "msg": "168.39 "}
- {"dt": "2024-12-02T10:49:37.722881", "lvl": "target", "msg": "\top: 15 len:  11 168.41 "}
- {"dt": "2024-12-02T10:49:37.722961", "lvl": "target", "msg": "168.42 ARP 192.168.66.1 70:70:fc:05:68:c7"}
- {"dt": "2024-12-02T10:49:37.761743", "lvl": "target", "msg": "168.45 NET 192.168.66.231 255.255.255.0 gw 0.0.0.0 tftp 192.168.66.1"}
- {"dt": "2024-12-02T10:49:37.762356", "lvl": "target", "msg": "168.52 RX: 9 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.762726", "lvl": "target", "msg": "168.59 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 a18d1bdb/config.txt"}
- {"dt": "2024-12-02T10:49:37.763024", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:49:37.763288", "lvl": "target", "msg": "168.65 RX: 9 IP: 0 IPV4: 6 MAC: 6 UDP: 6 UDP RECV: 6 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.763550", "lvl": "target", "msg": "168.75 TFTP 1: File not found "}
- {"dt": "2024-12-02T10:49:37.763800", "lvl": "target", "msg": "168.75 TFTP: disconnect: timeouts 56"}
- {"dt": "2024-12-02T10:49:37.764923", "lvl": "target", "msg": "168.79 RX: 10 IP: 0 IPV4: 7 MAC: 7 UDP: 7 UDP RECV: 7 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.803981", "lvl": "target", "msg": "168.86 BMD \\\"a18d1bdb/config.txt\\\" not found"}
- {"dt": "2024-12-02T10:49:37.804518", "lvl": "target", "msg": "168.91 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 config.txt"}
- {"dt": "2024-12-02T10:49:37.804904", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:49:37.805182", "lvl": "target", "msg": "168.95 RX: 10 IP: 0 IPV4: 7 MAC: 7 UDP: 7 UDP RECV: 7 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.805452", "lvl": "target", "msg": "168.06 TFTP: complete 137"}
- {"dt": "2024-12-02T10:49:37.805706", "lvl": "target", "msg": "168.06 TFTP: disconnect: timeouts 56"}
- {"dt": "2024-12-02T10:49:37.805958", "lvl": "target", "msg": "168.08 RX: 12 IP: 0 IPV4: 9 MAC: 9 UDP: 9 UDP RECV: 9 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.807244", "lvl": "target", "msg": "168.16 Read config.txt bytes      137 hnd 0x0"}
- {"dt": "2024-12-02T10:49:37.879580", "lvl": "target", "msg": "168.63 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 pieeprom.sig"}
- {"dt": "2024-12-02T10:49:37.880155", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:49:37.880526", "lvl": "target", "msg": "168.66 RX: 12 IP: 0 IPV4: 9 MAC: 9 UDP: 9 UDP RECV: 9 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.880865", "lvl": "target", "msg": "168.75 TFTP 1: File not found "}
- {"dt": "2024-12-02T10:49:37.881149", "lvl": "target", "msg": "168.76 TFTP: disconnect: timeouts 56"}
- {"dt": "2024-12-02T10:49:37.881415", "lvl": "target", "msg": "168.79 RX: 13 IP: 0 IPV4: 10 MAC: 10 UDP: 10 UDP RECV: 10 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.881678", "lvl": "target", "msg": "168.87 Read pieeprom.upd failed"}
- {"dt": "2024-12-02T10:49:37.881936", "lvl": "target", "msg": "168.90 usb_max_current_enable default 0 max-current 3000"}
- {"dt": "2024-12-02T10:49:37.882195", "lvl": "target", "msg": "168.95 TFTP_GET: 70:70:fc:05:68:c7 192.168.66.1 bcm2712-rpi-5-b.dtb"}
- {"dt": "2024-12-02T10:49:37.882523", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:49:37.897788", "lvl": "target", "msg": "169.01 RX: 13 IP: 0 IPV4: 10 MAC: 10 UDP: 10 UDP RECV: 10 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.977769", "lvl": "target", "msg": "169.60 TFTP: complete 78059"}
- {"dt": "2024-12-02T10:49:37.978337", "lvl": "target", "msg": "169.60 TFTP: disconnect: timeouts 56"}
- {"dt": "2024-12-02T10:49:37.978729", "lvl": "target", "msg": "169.63 RX: 68 IP: 0 IPV4: 65 MAC: 65 UDP: 65 UDP RECV: 65 IP_CSUM_ERR: 0 UDP_CSUM_ERR: 0"}
- {"dt": "2024-12-02T10:49:37.979017", "lvl": "target", "msg": "169.71 Read bcm2712-rpi-5-b.dtb bytes    78059 hnd 0x0"}
- {"dt": "2024-12-02T10:49:37.979287", "lvl": "target", "msg": "169.76 dt-match: compatible: raspberrypi,5-model-b match: brcm,bcm2712"}
- {"dt": "2024-12-02T10:49:37.979550", "lvl": "target", "msg": "169.82 dt-match: compatible: brcm,bcm2712 match: brcm,bcm2712"}
- {"dt": "2024-12-02T10:49:37.981013", "lvl": "target", "msg": "169.89 Selecting USB low current limit"}
- {"dt": "2024-12-02T10:49:37.981518", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:05.624936", "lvl": "target", "msg": "NOTICE:  BL31: v2.6(release):v2.6-240-gfc45bc492-dirty"}
- {"dt": "2024-12-02T10:50:05.628090", "lvl": "target", "msg": "NOTICE:  BL31: Built : 13:41:55, Sep 23 2024"}
- {"dt": "2024-12-02T10:50:05.772479", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x414fd0b1]"}
- {"dt": "2024-12-02T10:50:05.774157", "lvl": "debug", "msg": "start: 2.3.1 login-action (timeout 00:05:47) [common]"}
- {"dt": "2024-12-02T10:50:05.774752", "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-02T10:50:05.775011", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-12-02T10:50:05.775213", "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-02T10:50:05.775402", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-12-02T10:50:05.775553", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-12-02T10:50:05.775709", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-12-02T10:50:05.775856", "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-02T10:50:05.776538", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:05:47)"}
- {"dt": "2024-12-02T10:50:05.778293", "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-02T10:50:05.778471", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-12-02T10:50:05.778591", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-12-02T10:50:05.778706", "lvl": "target", "msg": "[    0.000000] Machine model: Raspberry Pi 5 Model B Rev 1.0"}
- {"dt": "2024-12-02T10:50:05.778821", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-12-02T10:50:05.816112", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000002000000, size 64 MiB"}
- {"dt": "2024-12-02T10:50:05.816895", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool"}
- {"dt": "2024-12-02T10:50:05.817248", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000002000000..0x0000000005ffffff (65536 KiB) map reusable linux,cma"}
- {"dt": "2024-12-02T10:50:05.817339", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0"}
- {"dt": "2024-12-02T10:50:05.817428", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x000000003fd16180..0x000000003fd1627a (0 KiB) nomap non-reusable nvram@0"}
- {"dt": "2024-12-02T10:50:05.818851", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-12-02T10:50:05.859041", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000000000000-0x00000000ffffffff]"}
- {"dt": "2024-12-02T10:50:05.859686", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-12-02T10:50:05.860080", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]"}
- {"dt": "2024-12-02T10:50:05.860266", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-12-02T10:50:05.860502", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-12-02T10:50:05.860669", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000007ffff]"}
- {"dt": "2024-12-02T10:50:05.860887", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000000080000-0x000000003f7fffff]"}
- {"dt": "2024-12-02T10:50:05.861122", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x00000001ffffffff]"}
- {"dt": "2024-12-02T10:50:05.902318", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]"}
- {"dt": "2024-12-02T10:50:05.902590", "lvl": "target", "msg": "[    0.000000] On node 0, zone DMA: 512 pages in unavailable ranges"}
- {"dt": "2024-12-02T10:50:05.902756", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-12-02T10:50:05.902874", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-12-02T10:50:05.902975", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-12-02T10:50:05.903060", "lvl": "target", "msg": "[    0.000000] psci: MIGRATE_INFO_TYPE not supported."}
- {"dt": "2024-12-02T10:50:05.903133", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.2"}
- {"dt": "2024-12-02T10:50:05.905392", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 14 pages/cpu s184936 r8192 d36248 u229376"}
- {"dt": "2024-12-02T10:50:05.946021", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-12-02T10:50:05.946966", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-12-02T10:50:05.947493", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Hardware dirty bit management"}
- {"dt": "2024-12-02T10:50:05.947896", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-12-02T10:50:05.948174", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-BHB"}
- {"dt": "2024-12-02T10:50:05.948347", "lvl": "target", "msg": "[    0.000000] CPU features: kernel page table isolation forced ON by KASLR"}
- {"dt": "2024-12-02T10:50:05.948538", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)"}
- {"dt": "2024-12-02T10:50:05.949396", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-12-02T10:50:06.032299", "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=61986 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-02T10:50:06.033302", "lvl": "target", "msg": "[    0.000000] Unknown kernel command line parameters \\\"verbose earlyprintk\\\", will be passed to user space."}
- {"dt": "2024-12-02T10:50:06.034029", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 9, 8388608 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.034602", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 8, 4194304 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.034959", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 521728"}
- {"dt": "2024-12-02T10:50:06.036354", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-12-02T10:50:06.075655", "lvl": "target", "msg": "[    0.000000] software IO TLB: area num 4."}
- {"dt": "2024-12-02T10:50:06.076360", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000fbffc000-0x00000000ffffc000] (64MB)"}
- {"dt": "2024-12-02T10:50:06.076652", "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-02T10:50:06.077007", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1"}
- {"dt": "2024-12-02T10:50:06.077142", "lvl": "target", "msg": "[    0.000000] ftrace: allocating 49047 entries in 48 pages"}
- {"dt": "2024-12-02T10:50:06.078662", "lvl": "target", "msg": "[    0.000000] ftrace: allocated 48 pages with 2 groups"}
- {"dt": "2024-12-02T10:50:06.118748", "lvl": "target", "msg": "[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-12-02T10:50:06.119078", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-12-02T10:50:06.119280", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-12-02T10:50:06.119420", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4."}
- {"dt": "2024-12-02T10:50:06.119515", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2024-12-02T10:50:06.119607", "lvl": "target", "msg": "[    0.000000] \tRude variant of Tasks RCU enabled."}
- {"dt": "2024-12-02T10:50:06.119697", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-12-02T10:50:06.121821", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-12-02T10:50:06.162028", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4"}
- {"dt": "2024-12-02T10:50:06.162518", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-12-02T10:50:06.162625", "lvl": "target", "msg": "[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-12-02T10:50:06.162714", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-12-02T10:50:06.162794", "lvl": "target", "msg": "[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-12-02T10:50:06.162873", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys)."}
- {"dt": "2024-12-02T10:50:06.165116", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns"}
- {"dt": "2024-12-02T10:50:06.205421", "lvl": "target", "msg": "[    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns"}
- {"dt": "2024-12-02T10:50:06.206209", "lvl": "target", "msg": "[    0.000117] Console: colour dummy device 80x25"}
- {"dt": "2024-12-02T10:50:06.206395", "lvl": "target", "msg": "[    0.000130] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)"}
- {"dt": "2024-12-02T10:50:06.206664", "lvl": "target", "msg": "[    0.000132] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-12-02T10:50:06.206902", "lvl": "target", "msg": "[    0.000148] LSM: initializing lsm=capability,integrity"}
- {"dt": "2024-12-02T10:50:06.207012", "lvl": "target", "msg": "[    0.000193] Mount-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.248706", "lvl": "target", "msg": "[    0.000206] Mountpoint-cache hash table entries: 16384 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.249342", "lvl": "target", "msg": "[    0.000460] cgroup: Disabling memory control group subsystem"}
- {"dt": "2024-12-02T10:50:06.249741", "lvl": "target", "msg": "[    0.000740] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-02T10:50:06.250403", "lvl": "target", "msg": "[    0.000757] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-02T10:50:06.250530", "lvl": "target", "msg": "[    0.000769] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1."}
- {"dt": "2024-12-02T10:50:06.250633", "lvl": "target", "msg": "[    0.000805] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-12-02T10:50:06.251868", "lvl": "target", "msg": "[    0.000806] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-12-02T10:50:06.292152", "lvl": "target", "msg": "[    0.001189] EFI services will not be available."}
- {"dt": "2024-12-02T10:50:06.292921", "lvl": "target", "msg": "[    0.001228] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-12-02T10:50:06.293421", "lvl": "target", "msg": "[    0.001332] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-12-02T10:50:06.293845", "lvl": "target", "msg": "[    0.001361] CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1]"}
- {"dt": "2024-12-02T10:50:06.294169", "lvl": "target", "msg": "[    0.001486] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-12-02T10:50:06.294357", "lvl": "target", "msg": "[    0.001509] CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1]"}
- {"dt": "2024-12-02T10:50:06.294592", "lvl": "target", "msg": "[    0.001622] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-12-02T10:50:06.294833", "lvl": "target", "msg": "[    0.001642] CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1]"}
- {"dt": "2024-12-02T10:50:06.295801", "lvl": "target", "msg": "[    0.001662] smp: Brought up 1 node, 4 CPUs"}
- {"dt": "2024-12-02T10:50:06.335334", "lvl": "target", "msg": "[    0.001663] SMP: Total of 4 processors activated."}
- {"dt": "2024-12-02T10:50:06.335667", "lvl": "target", "msg": "[    0.001665] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-12-02T10:50:06.335850", "lvl": "target", "msg": "[    0.001666] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-12-02T10:50:06.335987", "lvl": "target", "msg": "[    0.001667] CPU features: detected: Common not Private translations"}
- {"dt": "2024-12-02T10:50:06.336103", "lvl": "target", "msg": "[    0.001668] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-12-02T10:50:06.336206", "lvl": "target", "msg": "[    0.001670] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-12-02T10:50:06.338379", "lvl": "target", "msg": "[    0.001671] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-12-02T10:50:06.378560", "lvl": "target", "msg": "[    0.001673] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-12-02T10:50:06.378796", "lvl": "target", "msg": "[    0.001673] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-12-02T10:50:06.379161", "lvl": "target", "msg": "[    0.001675] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-12-02T10:50:06.379442", "lvl": "target", "msg": "[    0.001698] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-12-02T10:50:06.379534", "lvl": "target", "msg": "[    0.001699] alternatives: applying system-wide alternatives"}
- {"dt": "2024-12-02T10:50:06.379607", "lvl": "target", "msg": "[    0.003160] devtmpfs: initialized"}
- {"dt": "2024-12-02T10:50:06.379679", "lvl": "target", "msg": "[    0.004982] Enabled cp15_barrier support"}
- {"dt": "2024-12-02T10:50:06.379751", "lvl": "target", "msg": "[    0.004986] Enabled setend support"}
- {"dt": "2024-12-02T10:50:06.422117", "lvl": "target", "msg": "[    0.005050] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-12-02T10:50:06.422754", "lvl": "target", "msg": "[    0.005054] futex hash table entries: 1024 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.423018", "lvl": "target", "msg": "[    0.005269] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-12-02T10:50:06.423252", "lvl": "target", "msg": "[    0.005356] DMI not present or invalid."}
- {"dt": "2024-12-02T10:50:06.423413", "lvl": "target", "msg": "[    0.005484] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-12-02T10:50:06.423636", "lvl": "target", "msg": "[    0.005787] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-12-02T10:50:06.425148", "lvl": "target", "msg": "[    0.005814] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-12-02T10:50:06.465179", "lvl": "target", "msg": "[    0.005843] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-12-02T10:50:06.465758", "lvl": "target", "msg": "[    0.005855] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-12-02T10:50:06.466106", "lvl": "target", "msg": "[    0.005891] audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-12-02T10:50:06.466263", "lvl": "target", "msg": "[    0.005971] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-12-02T10:50:06.466459", "lvl": "target", "msg": "[    0.005978] cpuidle: using governor menu"}
- {"dt": "2024-12-02T10:50:06.466536", "lvl": "target", "msg": "[    0.006028] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-12-02T10:50:06.468283", "lvl": "target", "msg": "[    0.006048] ASID allocator initialised with 32768 entries"}
- {"dt": "2024-12-02T10:50:06.526835", "lvl": "target", "msg": "[    0.006235] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-12-02T10:50:06.527265", "lvl": "target", "msg": "[    0.006725] bcm2835-mbox 107c013880.mailbox: mailbox enabled"}
- {"dt": "2024-12-02T10:50:06.527427", "lvl": "target", "msg": "[    0.006849] 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2"}
- {"dt": "2024-12-02T10:50:06.527713", "lvl": "target", "msg": "[    0.006856] printk: console [ttyAMA10] enabled"}
- {"dt": "2024-12-02T10:50:06.527979", "lvl": "target", "msg": "[    0.770915] raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-27T14:28:19, variant start_cd"}
- {"dt": "2024-12-02T10:50:06.529072", "lvl": "target", "msg": "[    0.784961] raspberrypi-firmware soc:firmware: Firmware hash is 0b72471b00000000000000000000000000000000"}
- {"dt": "2024-12-02T10:50:06.571342", "lvl": "target", "msg": "[    0.799811] Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-12-02T10:50:06.571960", "lvl": "target", "msg": "[    0.806888] Modules: 0 pages in range for non-PLT usage"}
- {"dt": "2024-12-02T10:50:06.572234", "lvl": "target", "msg": "[    0.806889] Modules: 129224 pages in range for PLT usage"}
- {"dt": "2024-12-02T10:50:06.572455", "lvl": "target", "msg": "[    0.813846] bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1"}
- {"dt": "2024-12-02T10:50:06.572618", "lvl": "target", "msg": "[    0.826472] iommu: Default domain type: Translated"}
- {"dt": "2024-12-02T10:50:06.572723", "lvl": "target", "msg": "[    0.831298] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-12-02T10:50:06.572940", "lvl": "target", "msg": "[    0.837744] SCSI subsystem initialized"}
- {"dt": "2024-12-02T10:50:06.574459", "lvl": "target", "msg": "[    0.841522] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-12-02T10:50:06.624814", "lvl": "target", "msg": "[    0.847028] usbcore: registered new interface driver hub"}
- {"dt": "2024-12-02T10:50:06.625059", "lvl": "target", "msg": "[    0.852360] usbcore: registered new device driver usb"}
- {"dt": "2024-12-02T10:50:06.625183", "lvl": "target", "msg": "[    0.857484] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-12-02T10:50:06.625282", "lvl": "target", "msg": "[    0.862463] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-12-02T10:50:06.625378", "lvl": "target", "msg": "[    0.871631] PTP clock support registered"}
- {"dt": "2024-12-02T10:50:06.625469", "lvl": "target", "msg": "[    0.875596] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-12-02T10:50:06.625555", "lvl": "target", "msg": "[    0.881866] vgaarb: loaded"}
- {"dt": "2024-12-02T10:50:06.627916", "lvl": "target", "msg": "[    0.884662] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-12-02T10:50:06.760442", "lvl": "target", "msg": "[    0.992206] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-12-02T10:50:06.761132", "lvl": "target", "msg": "[    0.996149] VFS: Dquot-cache hash table entries: 2048 (order 0, 16384 bytes)"}
- {"dt": "2024-12-02T10:50:06.761569", "lvl": "target", "msg": "[    1.003240] FS-Cache: Loaded"}
- {"dt": "2024-12-02T10:50:06.761900", "lvl": "target", "msg": "[    1.006149] CacheFiles: Loaded"}
- {"dt": "2024-12-02T10:50:06.762110", "lvl": "target", "msg": "[    1.010183] NET: Registered PF_INET protocol family"}
- {"dt": "2024-12-02T10:50:06.762898", "lvl": "target", "msg": "[    1.015178] IP idents hash table entries: 131072 (order: 6, 1048576 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.763003", "lvl": "target", "msg": "[    1.024269] tcp_listen_portaddr_hash hash table entries: 4096 (order: 2, 65536 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.805921", "lvl": "target", "msg": "[    1.032868] Table-perturb hash table entries: 65536 (order: 4, 262144 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.806591", "lvl": "target", "msg": "[    1.040641] TCP established hash table entries: 65536 (order: 5, 524288 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.807000", "lvl": "target", "msg": "[    1.048755] TCP bind hash table entries: 65536 (order: 7, 2097152 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.807184", "lvl": "target", "msg": "[    1.057721] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-12-02T10:50:06.807439", "lvl": "target", "msg": "[    1.064364] MPTCP token hash table entries: 8192 (order: 3, 196608 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.807565", "lvl": "target", "msg": "[    1.072027] UDP hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.849433", "lvl": "target", "msg": "[    1.078899] UDP-Lite hash table entries: 4096 (order: 3, 131072 bytes, linear)"}
- {"dt": "2024-12-02T10:50:06.849706", "lvl": "target", "msg": "[    1.086247] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-12-02T10:50:06.849929", "lvl": "target", "msg": "[    1.092045] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-12-02T10:50:06.850143", "lvl": "target", "msg": "[    1.097986] RPC: Registered udp transport module."}
- {"dt": "2024-12-02T10:50:06.850315", "lvl": "target", "msg": "[    1.102702] RPC: Registered tcp transport module."}
- {"dt": "2024-12-02T10:50:06.850436", "lvl": "target", "msg": "[    1.107418] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-12-02T10:50:06.850541", "lvl": "target", "msg": "[    1.112922] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-12-02T10:50:06.852450", "lvl": "target", "msg": "[    1.119386] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-12-02T10:50:06.893950", "lvl": "target", "msg": "[    1.123477] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2024-12-02T10:50:06.894540", "lvl": "target", "msg": "[    1.123483] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-12-02T10:50:06.894914", "lvl": "target", "msg": "[    1.133192] kvm [1]: GICV region size/alignment is unsafe, using trapping (reduced performance)"}
- {"dt": "2024-12-02T10:50:06.895200", "lvl": "target", "msg": "[    1.133209] rootfs image is not initramfs (invalid magic at start of compressed archive); looks like an initrd"}
- {"dt": "2024-12-02T10:50:06.895438", "lvl": "target", "msg": "[    1.141943] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-12-02T10:50:06.896129", "lvl": "target", "msg": "[    1.155961] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-12-02T10:50:06.896242", "lvl": "target", "msg": "[    1.162230] Initialise system trusted keyrings"}
- {"dt": "2024-12-02T10:50:06.897076", "lvl": "target", "msg": "[    1.165079] Freeing initrd memory: 12288K"}
- {"dt": "2024-12-02T10:50:06.937635", "lvl": "target", "msg": "[    1.166768] workingset: timestamp_bits=46 max_order=19 bucket_order=0"}
- {"dt": "2024-12-02T10:50:06.938266", "lvl": "target", "msg": "[    1.177193] zbud: loaded"}
- {"dt": "2024-12-02T10:50:06.938650", "lvl": "target", "msg": "[    1.179854] NFS: Registering the id_resolver key type"}
- {"dt": "2024-12-02T10:50:06.938935", "lvl": "target", "msg": "[    1.184925] Key type id_resolver registered"}
- {"dt": "2024-12-02T10:50:06.939180", "lvl": "target", "msg": "[    1.189118] Key type id_legacy registered"}
- {"dt": "2024-12-02T10:50:06.939402", "lvl": "target", "msg": "[    1.193140] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-12-02T10:50:06.939552", "lvl": "target", "msg": "[    1.199863] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-12-02T10:50:06.940587", "lvl": "target", "msg": "[    1.207319] F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-12-02T10:50:06.981156", "lvl": "target", "msg": "[    1.212868] NET: Registered PF_ALG protocol family"}
- {"dt": "2024-12-02T10:50:06.981502", "lvl": "target", "msg": "[    1.217673] Key type asymmetric registered"}
- {"dt": "2024-12-02T10:50:06.981695", "lvl": "target", "msg": "[    1.221778] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-12-02T10:50:06.981840", "lvl": "target", "msg": "[    1.226679] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2024-12-02T10:50:06.981972", "lvl": "target", "msg": "[    1.234131] io scheduler mq-deadline registered"}
- {"dt": "2024-12-02T10:50:06.982087", "lvl": "target", "msg": "[    1.238673] io scheduler kyber registered"}
- {"dt": "2024-12-02T10:50:06.982171", "lvl": "target", "msg": "[    1.242696] io scheduler bfq registered"}
- {"dt": "2024-12-02T10:50:06.982256", "lvl": "target", "msg": "[    1.246707] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 26)"}
- {"dt": "2024-12-02T10:50:07.020076", "lvl": "target", "msg": "[    1.254439] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 27)"}
- {"dt": "2024-12-02T10:50:07.020534", "lvl": "target", "msg": "[    1.262150] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 28)"}
- {"dt": "2024-12-02T10:50:07.020834", "lvl": "target", "msg": "[    1.269868] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 29)"}
- {"dt": "2024-12-02T10:50:07.023095", "lvl": "target", "msg": "[    1.279160] bcm2708_fb soc:fb: FB found 1 display(s)"}
- {"dt": "2024-12-02T10:50:07.046057", "lvl": "target", "msg": "[    1.292548] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-12-02T10:50:07.049035", "lvl": "target", "msg": "[    1.301508] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1920x1080"}
- {"dt": "2024-12-02T10:50:07.107552", "lvl": "target", "msg": "[    1.337566] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled"}
- {"dt": "2024-12-02T10:50:07.108316", "lvl": "target", "msg": "[    1.344113] 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 32, base_baud = 6000000) is a Broadcom BCM7271 UART"}
- {"dt": "2024-12-02T10:50:07.108867", "lvl": "target", "msg": "[    1.354708] serial serial0: tty port ttyS0 registered"}
- {"dt": "2024-12-02T10:50:07.109340", "lvl": "target", "msg": "[    1.359948] iproc-rng200 107d208000.rng: hwrng registered"}
- {"dt": "2024-12-02T10:50:07.109585", "lvl": "target", "msg": "[    1.365392] vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB)"}
- {"dt": "2024-12-02T10:50:07.110654", "lvl": "target", "msg": "[    1.373827] bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe"}
- {"dt": "2024-12-02T10:50:07.156201", "lvl": "target", "msg": "[    1.382440] brd: module loaded"}
- {"dt": "2024-12-02T10:50:07.156920", "lvl": "target", "msg": "[    1.386383] loop: module loaded"}
- {"dt": "2024-12-02T10:50:07.157444", "lvl": "target", "msg": "[    1.393370] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver"}
- {"dt": "2024-12-02T10:50:07.157847", "lvl": "target", "msg": "[    1.400762] Loading iSCSI transport class v2.0-870."}
- {"dt": "2024-12-02T10:50:07.158027", "lvl": "target", "msg": "[    1.406203] usbcore: registered new device driver r8152-cfgselector"}
- {"dt": "2024-12-02T10:50:07.158212", "lvl": "target", "msg": "[    1.412498] usbcore: registered new interface driver r8152"}
- {"dt": "2024-12-02T10:50:07.158677", "lvl": "target", "msg": "[    1.418003] usbcore: registered new interface driver lan78xx"}
- {"dt": "2024-12-02T10:50:07.158781", "lvl": "target", "msg": "[    1.423721] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)"}
- {"dt": "2024-12-02T10:50:07.208936", "lvl": "target", "msg": "[    1.429588] usbcore: registered new interface driver uas"}
- {"dt": "2024-12-02T10:50:07.209582", "lvl": "target", "msg": "[    1.434919] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-12-02T10:50:07.209936", "lvl": "target", "msg": "[    1.440962] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-12-02T10:50:07.210042", "lvl": "target", "msg": "[    1.447520] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-12-02T10:50:07.210142", "lvl": "target", "msg": "[    1.453549] usbcore: registered new interface driver cp210x"}
- {"dt": "2024-12-02T10:50:07.210239", "lvl": "target", "msg": "[    1.459140] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2024-12-02T10:50:07.210335", "lvl": "target", "msg": "[    1.465134] mousedev: PS/2 mouse device common for all mice"}
- {"dt": "2024-12-02T10:50:07.211780", "lvl": "target", "msg": "[    1.478257] rpi-rtc soc:rpi_rtc: registered as rtc0"}
- {"dt": "2024-12-02T10:50:07.254522", "lvl": "target", "msg": "[    1.484523] rpi-rtc soc:rpi_rtc: setting system clock to 1970-01-01T00:03:18 UTC (198)"}
- {"dt": "2024-12-02T10:50:07.255321", "lvl": "target", "msg": "[    1.492508] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-12-02T10:50:07.255714", "lvl": "target", "msg": "[    1.498835] bcm2835-wdt bcm2835-wdt: Poweroff handler already present!"}
- {"dt": "2024-12-02T10:50:07.256015", "lvl": "target", "msg": "[    1.505386] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer"}
- {"dt": "2024-12-02T10:50:07.256293", "lvl": "target", "msg": "[    1.513782] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-12-02T10:50:07.256563", "lvl": "target", "msg": "[    1.519988] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-12-02T10:50:07.257728", "lvl": "target", "msg": "[    1.524376] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-12-02T10:50:07.298254", "lvl": "target", "msg": "[    1.530189] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-12-02T10:50:07.298595", "lvl": "target", "msg": "[    1.536252] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ...."}
- {"dt": "2024-12-02T10:50:07.298751", "lvl": "target", "msg": "[    1.542725] hid: raw HID events driver (C) Jiri Kosina"}
- {"dt": "2024-12-02T10:50:07.298866", "lvl": "target", "msg": "[    1.547916] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-12-02T10:50:07.298963", "lvl": "target", "msg": "[    1.553506] usbhid: USB HID core driver"}
- {"dt": "2024-12-02T10:50:07.299041", "lvl": "target", "msg": "[    1.557579] hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available"}
- {"dt": "2024-12-02T10:50:07.301353", "lvl": "target", "msg": "[    1.565888] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-12-02T10:50:07.346841", "lvl": "target", "msg": "[    1.572197] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-12-02T10:50:07.347399", "lvl": "target", "msg": "[    1.577282] Key type dns_resolver registered"}
- {"dt": "2024-12-02T10:50:07.347789", "lvl": "target", "msg": "[    1.584887] registered taskstats version 1"}
- {"dt": "2024-12-02T10:50:07.348127", "lvl": "target", "msg": "[    1.589044] Loading compiled-in X.509 certificates"}
- {"dt": "2024-12-02T10:50:07.348427", "lvl": "target", "msg": "[    1.595140] Key type .fscrypt registered"}
- {"dt": "2024-12-02T10:50:07.348700", "lvl": "target", "msg": "[    1.599073] Key type fscrypt-provisioning registered"}
- {"dt": "2024-12-02T10:50:07.349133", "lvl": "target", "msg": "[    1.604646] brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges:"}
- {"dt": "2024-12-02T10:50:07.349907", "lvl": "target", "msg": "[    1.611642] brcm-pcie 1000120000.pcie:   No bus range found for /axi/pcie@120000, using [bus 00-ff]"}
- {"dt": "2024-12-02T10:50:07.391569", "lvl": "target", "msg": "[    1.620731] brcm-pcie 1000120000.pcie:      MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000"}
- {"dt": "2024-12-02T10:50:07.392188", "lvl": "target", "msg": "[    1.629028] brcm-pcie 1000120000.pcie:      MEM 0x1c00000000..0x1effffffff -> 0x0400000000"}
- {"dt": "2024-12-02T10:50:07.392330", "lvl": "target", "msg": "[    1.637327] brcm-pcie 1000120000.pcie:   IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000"}
- {"dt": "2024-12-02T10:50:07.392435", "lvl": "target", "msg": "[    1.645625] brcm-pcie 1000120000.pcie:   IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000"}
- {"dt": "2024-12-02T10:50:07.392525", "lvl": "target", "msg": "[    1.655080] brcm-pcie 1000120000.pcie: Forcing gen 2"}
- {"dt": "2024-12-02T10:50:07.394671", "lvl": "target", "msg": "[    1.660300] brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-12-02T10:50:07.435936", "lvl": "target", "msg": "[    1.666816] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-12-02T10:50:07.437417", "lvl": "target", "msg": "[    1.672320] pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb])"}
- {"dt": "2024-12-02T10:50:07.437901", "lvl": "target", "msg": "[    1.682885] pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff])"}
- {"dt": "2024-12-02T10:50:07.438267", "lvl": "target", "msg": "[    1.694068] pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400"}
- {"dt": "2024-12-02T10:50:07.438590", "lvl": "target", "msg": "[    1.700113] pci 0000:00:00.0: PME# supported from D0 D3hot"}
- {"dt": "2024-12-02T10:50:07.454567", "lvl": "target", "msg": "[    1.706191] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring"}
- {"dt": "2024-12-02T10:50:07.595507", "lvl": "target", "msg": "[    1.820667] brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC)"}
- {"dt": "2024-12-02T10:50:07.596089", "lvl": "target", "msg": "[    1.827319] pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000"}
- {"dt": "2024-12-02T10:50:07.596442", "lvl": "target", "msg": "[    1.833356] pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff]"}
- {"dt": "2024-12-02T10:50:07.596712", "lvl": "target", "msg": "[    1.839648] pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff]"}
- {"dt": "2024-12-02T10:50:07.597006", "lvl": "target", "msg": "[    1.845939] pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff]"}
- {"dt": "2024-12-02T10:50:07.597256", "lvl": "target", "msg": "[    1.852287] pci 0000:01:00.0: supports D1"}
- {"dt": "2024-12-02T10:50:07.598718", "lvl": "target", "msg": "[    1.856304] pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold"}
- {"dt": "2024-12-02T10:50:07.638793", "lvl": "target", "msg": "[    1.872670] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01"}
- {"dt": "2024-12-02T10:50:07.639342", "lvl": "target", "msg": "[    1.879312] pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-12-02T10:50:07.639681", "lvl": "target", "msg": "[    1.886474] pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff]"}
- {"dt": "2024-12-02T10:50:07.639955", "lvl": "target", "msg": "[    1.893636] pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff]"}
- {"dt": "2024-12-02T10:50:07.640213", "lvl": "target", "msg": "[    1.900799] pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff]"}
- {"dt": "2024-12-02T10:50:07.642072", "lvl": "target", "msg": "[    1.907961] pci 0000:00:00.0: PCI bridge to [bus 01]"}
- {"dt": "2024-12-02T10:50:07.682199", "lvl": "target", "msg": "[    1.912940] pci 0000:00:00.0:   bridge window [mem 0x1f00000000-0x1f005fffff]"}
- {"dt": "2024-12-02T10:50:07.682752", "lvl": "target", "msg": "[    1.920102] pci 0000:00:00.0: Max Payload Size set to  256/ 512 (was  128), Max Read Rq  512"}
- {"dt": "2024-12-02T10:50:07.683127", "lvl": "target", "msg": "[    1.928577] pci 0000:01:00.0: Max Payload Size set to  256/ 256 (was  128), Max Read Rq  512"}
- {"dt": "2024-12-02T10:50:07.683409", "lvl": "target", "msg": "[    1.937091] pcieport 0000:00:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-12-02T10:50:07.683672", "lvl": "target", "msg": "[    1.943229] pcieport 0000:00:00.0: PME: Signaling with IRQ 37"}
- {"dt": "2024-12-02T10:50:07.683923", "lvl": "target", "msg": "[    1.949034] pcieport 0000:00:00.0: AER: enabled with IRQ 37"}
- {"dt": "2024-12-02T10:50:07.719994", "lvl": "target", "msg": "[    1.954676] rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200"}
- {"dt": "2024-12-02T10:50:07.720540", "lvl": "target", "msg": "[    1.963759] rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200"}
- {"dt": "2024-12-02T10:50:07.720981", "lvl": "target", "msg": "[    1.973019] rp1 0000:01:00.0: enabling device (0000 -> 0002)"}
- {"dt": "2024-12-02T10:50:07.723289", "lvl": "target", "msg": "[    1.979303] rp1 0000:01:00.0: chip_id 0x20001927"}
- {"dt": "2024-12-02T10:50:07.772152", "lvl": "target", "msg": "[    2.002616] macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 105 (d8:3a:dd:e5:72:37)"}
- {"dt": "2024-12-02T10:50:07.772706", "lvl": "target", "msg": "[    2.015821] dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels"}
- {"dt": "2024-12-02T10:50:07.773818", "lvl": "target", "msg": "[    2.024316] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-12-02T10:50:07.774124", "lvl": "target", "msg": "[    2.029387] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-12-02T10:50:07.775447", "lvl": "target", "msg": "[    2.037063] xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-12-02T10:50:07.815607", "lvl": "target", "msg": "[    2.046069] xhci-hcd xhci-hcd.0: irq 130, io mem 0x1f00200000"}
- {"dt": "2024-12-02T10:50:07.816164", "lvl": "target", "msg": "[    2.051888] xhci-hcd xhci-hcd.0: xHCI Host Controller"}
- {"dt": "2024-12-02T10:50:07.816510", "lvl": "target", "msg": "[    2.056955] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-12-02T10:50:07.816827", "lvl": "target", "msg": "[    2.064204] xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-02T10:50:07.817097", "lvl": "target", "msg": "[    2.070349] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-12-02T10:50:07.817356", "lvl": "target", "msg": "[    2.078647] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-02T10:50:07.818796", "lvl": "target", "msg": "[    2.085896] usb usb1: Product: xHCI Host Controller"}
- {"dt": "2024-12-02T10:50:07.859160", "lvl": "target", "msg": "[    2.090787] usb usb1: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-02T10:50:07.859743", "lvl": "target", "msg": "[    2.096812] usb usb1: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-12-02T10:50:07.860092", "lvl": "target", "msg": "[    2.101354] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-12-02T10:50:07.860367", "lvl": "target", "msg": "[    2.105121] hub 1-0:1.0: 2 ports detected"}
- {"dt": "2024-12-02T10:50:07.860628", "lvl": "target", "msg": "[    2.109225] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-12-02T10:50:07.860927", "lvl": "target", "msg": "[    2.117521] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-02T10:50:07.861176", "lvl": "target", "msg": "[    2.124771] usb usb2: Product: xHCI Host Controller"}
- {"dt": "2024-12-02T10:50:07.862427", "lvl": "target", "msg": "[    2.129662] usb usb2: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-02T10:50:07.903482", "lvl": "target", "msg": "[    2.135703] usb usb2: SerialNumber: xhci-hcd.0"}
- {"dt": "2024-12-02T10:50:07.904051", "lvl": "target", "msg": "[    2.140286] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-12-02T10:50:07.904434", "lvl": "target", "msg": "[    2.144052] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-12-02T10:50:07.904724", "lvl": "target", "msg": "[    2.148188] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-12-02T10:50:07.905021", "lvl": "target", "msg": "[    2.153361] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-12-02T10:50:07.905271", "lvl": "target", "msg": "[    2.161035] xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810"}
- {"dt": "2024-12-02T10:50:07.905516", "lvl": "target", "msg": "[    2.170129] xhci-hcd xhci-hcd.1: irq 135, io mem 0x1f00300000"}
- {"dt": "2024-12-02T10:50:07.947113", "lvl": "target", "msg": "[    2.175950] xhci-hcd xhci-hcd.1: xHCI Host Controller"}
- {"dt": "2024-12-02T10:50:07.947641", "lvl": "target", "msg": "[    2.181017] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-12-02T10:50:07.948021", "lvl": "target", "msg": "[    2.188361] xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-02T10:50:07.948303", "lvl": "target", "msg": "[    2.194497] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06"}
- {"dt": "2024-12-02T10:50:07.948563", "lvl": "target", "msg": "[    2.202887] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-02T10:50:07.948859", "lvl": "target", "msg": "[    2.210135] usb usb3: Product: xHCI Host Controller"}
- {"dt": "2024-12-02T10:50:07.950412", "lvl": "target", "msg": "[    2.215026] usb usb3: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-02T10:50:07.990973", "lvl": "target", "msg": "[    2.221146] usb usb3: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-12-02T10:50:07.991550", "lvl": "target", "msg": "[    2.225662] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-12-02T10:50:07.991894", "lvl": "target", "msg": "[    2.229428] hub 3-0:1.0: 2 ports detected"}
- {"dt": "2024-12-02T10:50:07.992164", "lvl": "target", "msg": "[    2.233525] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06"}
- {"dt": "2024-12-02T10:50:07.992422", "lvl": "target", "msg": "[    2.241917] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1"}
- {"dt": "2024-12-02T10:50:07.992671", "lvl": "target", "msg": "[    2.249186] usb usb4: Product: xHCI Host Controller"}
- {"dt": "2024-12-02T10:50:07.992958", "lvl": "target", "msg": "[    2.254078] usb usb4: Manufacturer: Linux 6.6.22-v8-16k xhci-hcd"}
- {"dt": "2024-12-02T10:50:07.994289", "lvl": "target", "msg": "[    2.260255] usb usb4: SerialNumber: xhci-hcd.1"}
- {"dt": "2024-12-02T10:50:08.035477", "lvl": "target", "msg": "[    2.264781] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-12-02T10:50:08.036030", "lvl": "target", "msg": "[    2.268544] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-12-02T10:50:08.036370", "lvl": "target", "msg": "[    2.273103] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-02T10:50:08.036645", "lvl": "target", "msg": "[    2.281352] platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T10:50:08.036933", "lvl": "target", "msg": "[    2.289576] platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T10:50:08.037180", "lvl": "target", "msg": "[    2.297630] platform 1000800000.codec: Adding to iommu group 0"}
- {"dt": "2024-12-02T10:50:08.038922", "lvl": "target", "msg": "[    2.303486] platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T10:50:08.079491", "lvl": "target", "msg": "[    2.311881] platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T10:50:08.080073", "lvl": "target", "msg": "[    2.320101] platform 1000880000.pisp_be: Adding to iommu group 0"}
- {"dt": "2024-12-02T10:50:08.080413", "lvl": "target", "msg": "[    2.326327] platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T10:50:08.080688", "lvl": "target", "msg": "[    2.334186] platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T10:50:08.081000", "lvl": "target", "msg": "[    2.342406] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-02T10:50:08.134241", "lvl": "target", "msg": "[    2.349367] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-02T10:50:08.134773", "lvl": "target", "msg": "[    2.357796] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-02T10:50:08.135147", "lvl": "target", "msg": "[    2.364812] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774"}
- {"dt": "2024-12-02T10:50:08.135422", "lvl": "target", "msg": "[    2.373257] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success"}
- {"dt": "2024-12-02T10:50:08.135681", "lvl": "target", "msg": "[    2.380236] sdhci-brcmstb 1000fff000.mmc: Got CD GPIO"}
- {"dt": "2024-12-02T10:50:08.135930", "lvl": "target", "msg": "[    2.385520] mmc1: CQHCI version 5.10"}
- {"dt": "2024-12-02T10:50:08.136172", "lvl": "target", "msg": "[    2.388754] mmc0: CQHCI version 5.10"}
- {"dt": "2024-12-02T10:50:08.136410", "lvl": "target", "msg": "[    2.389590] of_cfs_init"}
- {"dt": "2024-12-02T10:50:08.137521", "lvl": "target", "msg": "[    2.395162] of_cfs_init: OK"}
- {"dt": "2024-12-02T10:50:08.175278", "lvl": "target", "msg": "[    2.428454] mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit"}
- {"dt": "2024-12-02T10:50:08.356468", "lvl": "target", "msg": "[    2.579195] mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit"}
- {"dt": "2024-12-02T10:50:08.357061", "lvl": "target", "msg": "[    2.595681] macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL)"}
- {"dt": "2024-12-02T10:50:08.357403", "lvl": "target", "msg": "[    2.606649] macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode"}
- {"dt": "2024-12-02T10:50:08.357677", "lvl": "target", "msg": "[    2.616862] pps pps0: new PPS source ptp0"}
- {"dt": "2024-12-02T10:50:08.357931", "lvl": "target", "msg": "[    2.619327] mmc1: new ultra high speed DDR50 SDIO card at address 0001"}
- {"dt": "2024-12-02T10:50:08.374334", "lvl": "target", "msg": "[    2.620914] macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered."}
- {"dt": "2024-12-02T10:50:11.432637", "lvl": "target", "msg": "[    5.685684] macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-12-02T10:50:11.495366", "lvl": "target", "msg": "[    5.712662] Sending DHCP requests ., OK"}
- {"dt": "2024-12-02T10:50:11.495901", "lvl": "target", "msg": "[    5.732511] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.231"}
- {"dt": "2024-12-02T10:50:11.496216", "lvl": "target", "msg": "[    5.740460] IP-Config: Complete:"}
- {"dt": "2024-12-02T10:50:11.496485", "lvl": "target", "msg": "[    5.743692]      device=eth0, hwaddr=d8:3a:dd:e5:72:37, ipaddr=192.168.66.231, mask=255.255.255.0, gw=192.168.66.1"}
- {"dt": "2024-12-02T10:50:11.496774", "lvl": "target", "msg": "[    5.754083]      host=192.168.66.231, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-12-02T10:50:11.498579", "lvl": "target", "msg": "[    5.761068]      bootserver=192.168.66.1, rootserver=192.168.66.1, rootpath="}
- {"dt": "2024-12-02T10:50:11.499041", "lvl": "target", "msg": "[    5.761069]      nameserver0=10.1.1.1"}
- {"dt": "2024-12-02T10:50:11.535112", "lvl": "target", "msg": "[    5.771883] clk: Disabling unused clocks"}
- {"dt": "2024-12-02T10:50:11.535680", "lvl": "target", "msg": "[    5.784366] ALSA device list:"}
- {"dt": "2024-12-02T10:50:11.536014", "lvl": "target", "msg": "[    5.787347]   No soundcards found."}
- {"dt": "2024-12-02T10:50:11.537031", "lvl": "target", "msg": "[    5.790793] uart-pl011 107d001000.serial: no DMA platform data"}
- {"dt": "2024-12-02T10:50:11.538702", "lvl": "target", "msg": "[    5.796654] Waiting 7 sec before mounting root device..."}
- {"dt": "2024-12-02T10:50:18.626857", "lvl": "target", "msg": "[   12.852688] RAMDISK: ext2 filesystem found at block 0"}
- {"dt": "2024-12-02T10:50:18.717845", "lvl": "target", "msg": "[   12.857756] 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-02T10:50:18.770869", "lvl": "target", "msg": "[   12.864502] |\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\b|\b/\b-\b\\\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-02T10:50:18.771477", "lvl": "target", "msg": "[   13.017960] EXT4-fs (ram0): mounted filesystem d291e3c8-a47c-4bba-b248-a917c9104949 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-12-02T10:50:18.771826", "lvl": "target", "msg": "[   13.029684] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2024-12-02T10:50:18.772100", "lvl": "target", "msg": "[   13.035560] devtmpfs: mounted"}
- {"dt": "2024-12-02T10:50:18.774065", "lvl": "target", "msg": "[   13.040809] Freeing unused kernel memory: 5568K"}
- {"dt": "2024-12-02T10:50:18.817716", "lvl": "target", "msg": "[   13.045403] Run /sbin/init as init process"}
- {"dt": "2024-12-02T10:50:18.818268", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-02T10:50:18.818617", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2024-12-02T10:50:18.818896", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2024-12-02T10:50:18.819155", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2024-12-02T10:50:18.819409", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2024-12-02T10:50:18.820947", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2024-12-02T10:50:18.860693", "lvl": "target", "msg": "_________________________________________________________________________[   13.096940] nbd0: detected capacity change from 0 to 8962048"}
- {"dt": "2024-12-02T10:50:18.862132", "lvl": "target", "msg": "_______"}
- {"dt": "2024-12-02T10:50:18.862563", "lvl": "target", "msg": "/sbin/init[1]: starting initrd script"}
- {"dt": "2024-12-02T10:50:18.862873", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2024-12-02T10:50:18.863149", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2024-12-02T10:50:18.863400", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2024-12-02T10:50:18.863651", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2024-12-02T10:50:18.864018", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2024-12-02T10:50:18.864542", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2024-12-02T10:50:18.865006", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2024-12-02T10:50:18.887397", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2024-12-02T10:50:18.887935", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.68.1:61986"}
- {"dt": "2024-12-02T10:50:18.888315", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2024-12-02T10:50:18.890547", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2024-12-02T10:50:20.814265", "lvl": "target", "msg": "[   15.046560] EXT4-fs (nbd0): mounted filesystem 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-12-02T10:50:20.815701", "lvl": "target", "msg": "/sbin/init[1]: Moving /dev to new rootfs"}
- {"dt": "2024-12-02T10:50:20.816064", "lvl": "target", "msg": "/sbin/init[1]: Moving /proc to new rootfs"}
- {"dt": "2024-12-02T10:50:20.816357", "lvl": "target", "msg": "/sbin/init[1]: Moving /sys to new rootfs"}
- {"dt": "2024-12-02T10:50:20.816621", "lvl": "target", "msg": "/sbin/init[1]: Moving /tmp to new rootfs"}
- {"dt": "2024-12-02T10:50:20.817677", "lvl": "target", "msg": "/sbin/init[1]: Moving /run to new rootfs"}
- {"dt": "2024-12-02T10:50:20.818087", "lvl": "target", "msg": "/sbin/init[1]: Switching to new rootfs"}
- {"dt": "2024-12-02T10:50:21.375152", "lvl": "target", "msg": "/sbin/init[1]: find_active_interface: first active interface is eth0"}
- {"dt": "2024-12-02T10:50:21.378282", "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-02T10:50:21.457770", "lvl": "target", "msg": "/sbin/init[1]: Unmounting /tmp and /run"}
- {"dt": "2024-12-02T10:50:21.571224", "lvl": "target", "msg": "/sbin/init[1]: Exec'ing systemd"}
- {"dt": "2024-12-02T10:50:21.571901", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-02T10:50:21.572335", "lvl": "target", "msg": "      ___   ________       ____              __  _"}
- {"dt": "2024-12-02T10:50:21.573522", "lvl": "target", "msg": "     /   | / ____/ /      / __ )____  ____  / /_(_)___  ____ _"}
- {"dt": "2024-12-02T10:50:21.573892", "lvl": "target", "msg": "    / /| |/ / __/ /      / __  / __ \\/ __ \\/ __/ / __ \\/ __ `/"}
- {"dt": "2024-12-02T10:50:21.574161", "lvl": "target", "msg": "   / ___ / /_/ / /___   / /_/ / /_/ / /_/ / /_/ / / / / /_/ /   _     _     _"}
- {"dt": "2024-12-02T10:50:21.575811", "lvl": "target", "msg": "  /_/  |_\\____/_____/  /_____/\\____/\\____/\\__/_/_/ /_/\\__, /   (_)   (_)   (_)"}
- {"dt": "2024-12-02T10:50:21.589528", "lvl": "target", "msg": "_____________________________________________________/____/_____________________"}
- {"dt": "2024-12-02T10:50:22.217456", "lvl": "target", "msg": "[   16.470854] systemd[1]: System time before build time, advancing clock."}
- {"dt": "2024-12-02T10:50:22.374940", "lvl": "target", "msg": "[   16.625287] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-12-02T10:50:22.375684", "lvl": "target", "msg": "[   16.630605] Segment Routing with IPv6"}
- {"dt": "2024-12-02T10:50:22.378260", "lvl": "target", "msg": "[   16.634287] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-12-02T10:50:22.519726", "lvl": "target", "msg": "[   16.748197] 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-02T10:50:22.522340", "lvl": "target", "msg": "[   16.780373] systemd[1]: Detected architecture arm64."}
- {"dt": "2024-12-02T10:50:22.537609", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:22.538132", "lvl": "target", "msg": "Welcome to Automotive Grade Linux 18.0.2 (ricefish)!"}
- {"dt": "2024-12-02T10:50:22.538471", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:22.553624", "lvl": "target", "msg": "[   16.820390] systemd[1]: Hostname set to <raspberrypi5>."}
- {"dt": "2024-12-02T10:50:22.596509", "lvl": "target", "msg": "[   16.849718] systemd[1]: Initializing machine ID from random generator."}
- {"dt": "2024-12-02T10:50:23.102673", "lvl": "target", "msg": "[   17.355940] systemd[1]: Queued start job for default target Graphical Interface."}
- {"dt": "2024-12-02T10:50:23.140640", "lvl": "target", "msg": "[   17.397273] systemd[1]: Created slice Slice /system/getty."}
- {"dt": "2024-12-02T10:50:23.143886", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/getty."}
- {"dt": "2024-12-02T10:50:23.222360", "lvl": "target", "msg": "[   17.416935] systemd[1]: Created slice Slice /system/modprobe."}
- {"dt": "2024-12-02T10:50:23.222937", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/modprobe."}
- {"dt": "2024-12-02T10:50:23.224202", "lvl": "target", "msg": "[   17.436891] systemd[1]: Created slice Slice /system/serial-getty."}
- {"dt": "2024-12-02T10:50:23.224572", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/serial-getty."}
- {"dt": "2024-12-02T10:50:23.224883", "lvl": "target", "msg": "[   17.456878] systemd[1]: Created slice Slice /system/wireplumber."}
- {"dt": "2024-12-02T10:50:23.225145", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/wireplumber."}
- {"dt": "2024-12-02T10:50:23.225390", "lvl": "target", "msg": "[   17.476792] systemd[1]: Created slice User and Session Slice."}
- {"dt": "2024-12-02T10:50:23.225867", "lvl": "target", "msg": "[  OK  ] Created slice User and Session Slice."}
- {"dt": "2024-12-02T10:50:23.287765", "lvl": "target", "msg": "[   17.496728] systemd[1]: Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-12-02T10:50:23.288539", "lvl": "target", "msg": "[  OK  ] Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2024-12-02T10:50:23.289760", "lvl": "target", "msg": "[   17.520709] systemd[1]: Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-12-02T10:50:23.290113", "lvl": "target", "msg": "[  OK  ] Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2024-12-02T10:50:23.290387", "lvl": "target", "msg": "[   17.540687] systemd[1]: Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-12-02T10:50:23.290648", "lvl": "target", "msg": "         Expecting device /sys/devices/platform/gpu/graphics/fb0..."}
- {"dt": "2024-12-02T10:50:23.371177", "lvl": "target", "msg": "[   17.560701] systemd[1]: Reached target Host and Network Name Lookups."}
- {"dt": "2024-12-02T10:50:23.371710", "lvl": "target", "msg": "[  OK  ] Reached target Host and Network Name Lookups."}
- {"dt": "2024-12-02T10:50:23.372057", "lvl": "target", "msg": "[   17.580677] systemd[1]: Reached target Path Units."}
- {"dt": "2024-12-02T10:50:23.372331", "lvl": "target", "msg": "[  OK  ] Reached target Path Units."}
- {"dt": "2024-12-02T10:50:23.372584", "lvl": "target", "msg": "[   17.596685] systemd[1]: Reached target Remote File Systems."}
- {"dt": "2024-12-02T10:50:23.372875", "lvl": "target", "msg": "[  OK  ] Reached target Remote File Systems."}
- {"dt": "2024-12-02T10:50:23.373121", "lvl": "target", "msg": "[   17.612679] systemd[1]: Reached target Slice Units."}
- {"dt": "2024-12-02T10:50:23.373366", "lvl": "target", "msg": "[  OK  ] Reached target Slice Units."}
- {"dt": "2024-12-02T10:50:23.373607", "lvl": "target", "msg": "[   17.628682] systemd[1]: Reached target Swaps."}
- {"dt": "2024-12-02T10:50:23.374623", "lvl": "target", "msg": "[  OK  ] Reached target Swaps."}
- {"dt": "2024-12-02T10:50:23.446155", "lvl": "target", "msg": "[   17.647314] systemd[1]: Listening on Process Core Dump Socket."}
- {"dt": "2024-12-02T10:50:23.446696", "lvl": "target", "msg": "[  OK  ] Listening on Process Core Dump Socket."}
- {"dt": "2024-12-02T10:50:23.447049", "lvl": "target", "msg": "[   17.664737] systemd[1]: Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-12-02T10:50:23.447337", "lvl": "target", "msg": "[  OK  ] Listening on initctl Compatibility Named Pipe."}
- {"dt": "2024-12-02T10:50:23.447592", "lvl": "target", "msg": "[   17.684861] systemd[1]: Listening on Journal Audit Socket."}
- {"dt": "2024-12-02T10:50:23.448559", "lvl": "target", "msg": "[  OK  ] Listening on Journal Audit Socket."}
- {"dt": "2024-12-02T10:50:23.448864", "lvl": "target", "msg": "[   17.700762] systemd[1]: Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-12-02T10:50:23.449855", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket (/dev/log)."}
- {"dt": "2024-12-02T10:50:23.519274", "lvl": "target", "msg": "[   17.720772] systemd[1]: Listening on Journal Socket."}
- {"dt": "2024-12-02T10:50:23.519817", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket."}
- {"dt": "2024-12-02T10:50:23.520160", "lvl": "target", "msg": "[   17.736801] systemd[1]: Listening on Network Service Netlink Socket."}
- {"dt": "2024-12-02T10:50:23.520436", "lvl": "target", "msg": "[  OK  ] Listening on Network Service Netlink Socket."}
- {"dt": "2024-12-02T10:50:23.521424", "lvl": "target", "msg": "[   17.756784] systemd[1]: Listening on udev Control Socket."}
- {"dt": "2024-12-02T10:50:23.521705", "lvl": "target", "msg": "[  OK  ] Listening on udev Control Socket."}
- {"dt": "2024-12-02T10:50:23.521957", "lvl": "target", "msg": "[   17.772743] systemd[1]: Listening on udev Kernel Socket."}
- {"dt": "2024-12-02T10:50:23.522203", "lvl": "target", "msg": "[  OK  ] Listening on udev Kernel Socket."}
- {"dt": "2024-12-02T10:50:23.522523", "lvl": "target", "msg": "[   17.788753] systemd[1]: Listening on User Database Manager Socket."}
- {"dt": "2024-12-02T10:50:23.554565", "lvl": "target", "msg": "[  OK  ] Listening on User Database Manager Socket."}
- {"dt": "2024-12-02T10:50:23.557785", "lvl": "target", "msg": "[   17.808771] systemd[1]: Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages)."}
- {"dt": "2024-12-02T10:50:23.633419", "lvl": "target", "msg": "[   17.844753] systemd[1]: Mounting POSIX Message Queue File System..."}
- {"dt": "2024-12-02T10:50:23.633948", "lvl": "target", "msg": "         Mounting POSIX Message Queue File System..."}
- {"dt": "2024-12-02T10:50:23.634293", "lvl": "target", "msg": "[   17.865272] systemd[1]: Mounting Kernel Debug File System..."}
- {"dt": "2024-12-02T10:50:23.634561", "lvl": "target", "msg": "         Mounting Kernel Debug Fi[   17.871676] systemd[1]: Mounting Kernel Trace File System..."}
- {"dt": "2024-12-02T10:50:23.634815", "lvl": "target", "msg": "le System..."}
- {"dt": "2024-12-02T10:50:23.635060", "lvl": "target", "msg": "         Mounting Kernel Trace File System..."}
- {"dt": "2024-12-02T10:50:23.635301", "lvl": "target", "msg": "[   17.890834] systemd[1]: Mounting Temporary Directory /tmp..."}
- {"dt": "2024-12-02T10:50:23.636799", "lvl": "target", "msg": "         Mounting Temporary Direc[   17.901765] systemd[1]: Starting Create List of Static Device Nodes..."}
- {"dt": "2024-12-02T10:50:23.688585", "lvl": "target", "msg": "tory /tmp..."}
- {"dt": "2024-12-02T10:50:23.689159", "lvl": "target", "msg": "         Starting Create List of [   17.921669] systemd[1]: Starting Load Kernel Module configfs..."}
- {"dt": "2024-12-02T10:50:23.689497", "lvl": "target", "msg": "Static Device Nodes..."}
- {"dt": "2024-12-02T10:50:23.689773", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.941603] systemd[1]: Starting Load Kernel Module drm..."}
- {"dt": "2024-12-02T10:50:23.690036", "lvl": "target", "msg": "ule configfs..."}
- {"dt": "2024-12-02T10:50:23.750205", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.961652] systemd[1]: Starting Load Kernel Module fuse..."}
- {"dt": "2024-12-02T10:50:23.750856", "lvl": "target", "msg": "ule drm..."}
- {"dt": "2024-12-02T10:50:23.751190", "lvl": "target", "msg": "         Starting Load Kernel Mod[   17.981589] systemd[1]: Starting SELinux autorelabel service loading..."}
- {"dt": "2024-12-02T10:50:23.751476", "lvl": "target", "msg": "ule fuse..."}
- {"dt": "2024-12-02T10:50:23.751734", "lvl": "target", "msg": "         Starting SELinux autorel[   18.001508] systemd[1]: Starting SELinux init for /dev service loading..."}
- {"dt": "2024-12-02T10:50:23.751986", "lvl": "target", "msg": "abel service loading..."}
- {"dt": "2024-12-02T10:50:23.753473", "lvl": "target", "msg": "[   18.018313] fuse: init (API version 7.39)"}
- {"dt": "2024-12-02T10:50:23.791507", "lvl": "target", "msg": "         Starting SELinux init fo[   18.036782] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/)."}
- {"dt": "2024-12-02T10:50:23.794388", "lvl": "target", "msg": "r /dev service loading..."}
- {"dt": "2024-12-02T10:50:23.812955", "lvl": "target", "msg": "[   18.069849] systemd[1]: Starting Journal Service..."}
- {"dt": "2024-12-02T10:50:23.816147", "lvl": "target", "msg": "         Starting Journal Service..."}
- {"dt": "2024-12-02T10:50:23.894175", "lvl": "target", "msg": "[   18.090544] systemd[1]: Starting Load Kernel Modules..."}
- {"dt": "2024-12-02T10:50:23.894788", "lvl": "target", "msg": "         Starting Load Kernel Mod[   18.096691] systemd[1]: Starting Remount Root and Kernel File Systems..."}
- {"dt": "2024-12-02T10:50:23.895194", "lvl": "target", "msg": "ules..."}
- {"dt": "2024-12-02T10:50:23.895524", "lvl": "target", "msg": "         Starting Remount Root an[   18.117948] systemd[1]: Starting Coldplug All udev Devices..."}
- {"dt": "2024-12-02T10:50:23.895835", "lvl": "target", "msg": "d Kernel File Systems..."}
- {"dt": "2024-12-02T10:50:23.896140", "lvl": "target", "msg": "         Starting Coldplug All ud[   18.138177] systemd[1]: Mounted POSIX Message Queue File System."}
- {"dt": "2024-12-02T10:50:23.896438", "lvl": "target", "msg": "ev Devices..."}
- {"dt": "2024-12-02T10:50:23.897872", "lvl": "target", "msg": "[  OK  ] Mounted POSIX Message Queue File System."}
- {"dt": "2024-12-02T10:50:23.964962", "lvl": "target", "msg": "[   18.169688] systemd[1]: Mounted Kernel Debug File System."}
- {"dt": "2024-12-02T10:50:23.965507", "lvl": "target", "msg": "[   18.175455] systemd-journald[196]: Collecting audit messages is enabled."}
- {"dt": "2024-12-02T10:50:23.965873", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Debug File System."}
- {"dt": "2024-12-02T10:50:23.966160", "lvl": "target", "msg": "[   18.184798] EXT4-fs (nbd0): re-mounted 47966b53-c54b-46c5-a736-dd34e4b9c216 r/w. Quota mode: none."}
- {"dt": "2024-12-02T10:50:23.966422", "lvl": "target", "msg": "[   18.205439] systemd[1]: Mounted Kernel Trace File System."}
- {"dt": "2024-12-02T10:50:23.966676", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Trace File System."}
- {"dt": "2024-12-02T10:50:23.966922", "lvl": "target", "msg": "[   18.224897] systemd[1]: Mounted Temporary Directory /tmp."}
- {"dt": "2024-12-02T10:50:23.968295", "lvl": "target", "msg": "[  OK  ] Mounted Temporary Directory /tmp."}
- {"dt": "2024-12-02T10:50:23.985768", "lvl": "target", "msg": "[   18.240969] systemd[1]: Finished Create List of Static Device Nodes."}
- {"dt": "2024-12-02T10:50:23.989059", "lvl": "target", "msg": "[  OK  ] Finished Create List of Static Device Nodes."}
- {"dt": "2024-12-02T10:50:24.066424", "lvl": "target", "msg": "[   18.299901] systemd[1]: Started Journal Service."}
- {"dt": "2024-12-02T10:50:24.066982", "lvl": "target", "msg": "[  OK  ] Started Journal Service.[   18.304621] audit: type=1130 audit(1725908514.823: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-02T10:50:24.067366", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:24.067649", "lvl": "target", "msg": "[   18.319026] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-12-02T10:50:24.121265", "lvl": "target", "msg": "[   18.336815] audit: type=1130 audit(1725908514.863:3): 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-02T10:50:24.121783", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Mod[   18.356088] snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored"}
- {"dt": "2024-12-02T10:50:24.122110", "lvl": "target", "msg": "ule configfs."}
- {"dt": "2024-12-02T10:50:24.124181", "lvl": "target", "msg": "[   18.376748] audit: type=1130 audit(1725908514.903: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-02T10:50:24.154930", "lvl": "target", "msg": "[   18.395716] audit: type=1131 audit(1725908514.903:5): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-02T10:50:24.155465", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module drm."}
- {"dt": "2024-12-02T10:50:24.202044", "lvl": "target", "msg": "[   18.428735] audit: type=1130 audit(1725908514.955: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-02T10:50:24.202618", "lvl": "target", "msg": "[   18.447247] audit: type=1131 audit(1725908514.955:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=modprobe@drm comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2024-12-02T10:50:24.205349", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module fuse."}
- {"dt": "2024-12-02T10:50:24.255146", "lvl": "target", "msg": "[   18.476710] audit: type=1130 audit(1725908515.003: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-02T10:50:24.255712", "lvl": "target", "msg": "[   18.495308] audit: type=1131 audit(1725908515.003: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-02T10:50:24.258398", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-12-02T10:50:24.308312", "lvl": "target", "msg": "[   18.528698] audit: type=1130 audit(1725908515.055: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-02T10:50:24.308932", "lvl": "target", "msg": "[   18.547906] audit: type=1131 audit(1725908515.055: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-02T10:50:24.311592", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-12-02T10:50:24.329288", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Modules."}
- {"dt": "2024-12-02T10:50:24.332508", "lvl": "target", "msg": "[  OK  ] Finished Remount Root and Kernel File Systems."}
- {"dt": "2024-12-02T10:50:24.348295", "lvl": "target", "msg": "[  OK  ] Finished Coldplug All udev Devices."}
- {"dt": "2024-12-02T10:50:24.380405", "lvl": "target", "msg": "         Mounting FUSE Control File System..."}
- {"dt": "2024-12-02T10:50:24.396230", "lvl": "target", "msg": "         Mounting Kernel Configuration File System..."}
- {"dt": "2024-12-02T10:50:24.412275", "lvl": "target", "msg": "         Starting Flush Journal to Persistent Storage..."}
- {"dt": "2024-12-02T10:50:24.427982", "lvl": "target", "msg": "         Starting Apply Kernel Variables..."}
- {"dt": "2024-12-02T10:50:24.462728", "lvl": "target", "msg": "[   18.704091] systemd-journald[196]: Received client request to flush runtime journal."}
- {"dt": "2024-12-02T10:50:24.465849", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev gracefully..."}
- {"dt": "2024-12-02T10:50:24.493511", "lvl": "target", "msg": "[  OK  ] Mounted FUSE Control File System."}
- {"dt": "2024-12-02T10:50:24.496675", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Configuration File System."}
- {"dt": "2024-12-02T10:50:24.512614", "lvl": "target", "msg": "[  OK  ] Finished Flush Journal to Persistent Storage."}
- {"dt": "2024-12-02T10:50:24.538795", "lvl": "target", "msg": "[  OK  ] Finished Apply Kernel Variables."}
- {"dt": "2024-12-02T10:50:24.541929", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev gracefully."}
- {"dt": "2024-12-02T10:50:24.589662", "lvl": "target", "msg": "         Starting Create System Users..."}
- {"dt": "2024-12-02T10:50:24.685650", "lvl": "target", "msg": "         Starting User Database Manager..."}
- {"dt": "2024-12-02T10:50:24.701599", "lvl": "target", "msg": "[  OK  ] Started User Database Manager."}
- {"dt": "2024-12-02T10:50:24.717672", "lvl": "target", "msg": "[  OK  ] Finished Create System Users."}
- {"dt": "2024-12-02T10:50:24.774391", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev..."}
- {"dt": "2024-12-02T10:50:24.777528", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev."}
- {"dt": "2024-12-02T10:50:24.793061", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Local File Systems."}
- {"dt": "2024-12-02T10:50:24.853278", "lvl": "target", "msg": "         Mounting /var/volatile..."}
- {"dt": "2024-12-02T10:50:24.856364", "lvl": "target", "msg": "         Starting Rule-based Manager for Device Events and Files..."}
- {"dt": "2024-12-02T10:50:24.856915", "lvl": "target", "msg": "[  OK  ] Mounted /var/volatile."}
- {"dt": "2024-12-02T10:50:24.872007", "lvl": "target", "msg": "         Starting Load/Save OS Random Seed..."}
- {"dt": "2024-12-02T10:50:24.887795", "lvl": "target", "msg": "[  OK  ] Reached target Local File Systems."}
- {"dt": "2024-12-02T10:50:24.970929", "lvl": "target", "msg": "         Starting Rebuild Dynamic Linker Cache..."}
- {"dt": "2024-12-02T10:50:24.971727", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-12-02T10:50:24.972323", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-12-02T10:50:24.972704", "lvl": "target", "msg": "         Starting Create Volatile Files and Directories..."}
- {"dt": "2024-12-02T10:50:24.974431", "lvl": "target", "msg": "[  OK  ] Finished Load/Save OS Random Seed."}
- {"dt": "2024-12-02T10:50:24.974933", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-12-02T10:50:25.021725", "lvl": "target", "msg": "         Starting Generated file SELinux relabeling..."}
- {"dt": "2024-12-02T10:50:25.037617", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-12-02T10:50:25.085501", "lvl": "target", "msg": "[  OK  ] Finished Create Volatile Files and Directories."}
- {"dt": "2024-12-02T10:50:25.129330", "lvl": "target", "msg": "         Starting Load Audit Rules..."}
- {"dt": "2024-12-02T10:50:25.132516", "lvl": "target", "msg": "         Starting Rebuild Journal Catalog..."}
- {"dt": "2024-12-02T10:50:25.340132", "lvl": "target", "msg": "[  OK  ] Finished Generated file SELinux relabeling."}
- {"dt": "2024-12-02T10:50:25.403922", "lvl": "target", "msg": "[  OK  ] Finished Rebuild Journal Catalog."}
- {"dt": "2024-12-02T10:50:25.461876", "lvl": "target", "msg": "[  OK  ] Started Rule-based Manager for Device Events and Files."}
- {"dt": "2024-12-02T10:50:25.557471", "lvl": "target", "msg": "[  OK  ] Finished Load Audit Rules."}
- {"dt": "2024-12-02T10:50:25.573504", "lvl": "target", "msg": "         Starting Security Audit Logging Service..."}
- {"dt": "2024-12-02T10:50:25.605323", "lvl": "target", "msg": "         Starting Virtual Console Setup..."}
- {"dt": "2024-12-02T10:50:25.697274", "lvl": "target", "msg": "[   19.950898] input: pwr_button as /devices/platform/pwr_button/input/input0"}
- {"dt": "2024-12-02T10:50:25.738299", "lvl": "target", "msg": "[   19.970488] rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040"}
- {"dt": "2024-12-02T10:50:25.739052", "lvl": "target", "msg": "[   19.978356] rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1"}
- {"dt": "2024-12-02T10:50:25.739672", "lvl": "target", "msg": "[   19.986172] rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040"}
- {"dt": "2024-12-02T10:50:25.740208", "lvl": "target", "msg": "[   19.994043] rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2"}
- {"dt": "2024-12-02T10:50:25.740573", "lvl": "target", "msg": "[   20.001719] rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020"}
- {"dt": "2024-12-02T10:50:25.792045", "lvl": "target", "msg": "[   20.009614] rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3"}
- {"dt": "2024-12-02T10:50:25.792597", "lvl": "target", "msg": "[   20.017340] rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020"}
- {"dt": "2024-12-02T10:50:25.793019", "lvl": "target", "msg": "[   20.017377] rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4"}
- {"dt": "2024-12-02T10:50:25.793305", "lvl": "target", "msg": "[   20.017451] rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000"}
- {"dt": "2024-12-02T10:50:25.793565", "lvl": "target", "msg": "[   20.017474] rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0"}
- {"dt": "2024-12-02T10:50:25.793816", "lvl": "target", "msg": "[   20.027434] macb 1f00100000.ethernet end0: renamed from eth0 (while UP)"}
- {"dt": "2024-12-02T10:50:25.858990", "lvl": "target", "msg": "[   20.124062] mc: Linux media interface: v0.10"}
- {"dt": "2024-12-02T10:50:26.002638", "lvl": "target", "msg": "[  OK  ] Started Security Audit Logging Service."}
- {"dt": "2024-12-02T10:50:26.003222", "lvl": "target", "msg": "[   20.246161] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-12-02T10:50:26.005751", "lvl": "target", "msg": "         Starting Record System Boot/Shutdown in UTMP..."}
- {"dt": "2024-12-02T10:50:26.071466", "lvl": "target", "msg": "[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch."}
- {"dt": "2024-12-02T10:50:26.074618", "lvl": "target", "msg": "[  OK  ] Finished Record System Boot/Shutdown in UTMP."}
- {"dt": "2024-12-02T10:50:26.149456", "lvl": "target", "msg": "[   20.373919] Bluetooth: Core ver 2.22"}
- {"dt": "2024-12-02T10:50:26.149985", "lvl": "target", "msg": "[   20.377662] NET: Registered PF_BLUETOOTH protocol family"}
- {"dt": "2024-12-02T10:50:26.150367", "lvl": "target", "msg": "[   20.383278] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2024-12-02T10:50:26.151359", "lvl": "target", "msg": "[   20.389806] Bluetooth: HCI socket layer initialized"}
- {"dt": "2024-12-02T10:50:26.151644", "lvl": "target", "msg": "[   20.394863] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2024-12-02T10:50:26.151902", "lvl": "target", "msg": "[   20.400216] Bluetooth: SCO socket layer initialized"}
- {"dt": "2024-12-02T10:50:26.152150", "lvl": "target", "msg": "[   20.412893] pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T10:50:26.192835", "lvl": "target", "msg": "[   20.421308] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2024-12-02T10:50:26.193408", "lvl": "target", "msg": "[   20.421331] pispbe 1000880000.pisp_be: pispbe_probe: HW version:  0x02252700"}
- {"dt": "2024-12-02T10:50:26.193801", "lvl": "target", "msg": "[   20.422338] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned."}
- {"dt": "2024-12-02T10:50:26.194820", "lvl": "target", "msg": "[   20.431743] Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'"}
- {"dt": "2024-12-02T10:50:26.195120", "lvl": "target", "msg": "[   20.436472] rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu"}
- {"dt": "2024-12-02T10:50:26.196397", "lvl": "target", "msg": "[   20.436966] pispbe 1000880000.pisp_be: pispbe_probe: BatchStatus: 0x00000000"}
- {"dt": "2024-12-02T10:50:26.236171", "lvl": "target", "msg": "[   20.436968] pispbe 1000880000.pisp_be: pispbe_probe: Status:      0x00000000"}
- {"dt": "2024-12-02T10:50:26.236708", "lvl": "target", "msg": "[   20.436969] pispbe 1000880000.pisp_be: Register nodes for group 0"}
- {"dt": "2024-12-02T10:50:26.237134", "lvl": "target", "msg": "[   20.446112] pispbe 1000880000.pisp_be: input device node registered as /dev/video20"}
- {"dt": "2024-12-02T10:50:26.238162", "lvl": "target", "msg": "[   20.446487] Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'"}
- {"dt": "2024-12-02T10:50:26.238498", "lvl": "target", "msg": "[   20.446680] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2024-12-02T10:50:26.239506", "lvl": "target", "msg": "[   20.446870] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'"}
- {"dt": "2024-12-02T10:50:26.279441", "lvl": "target", "msg": "[   20.464505] rpivid 1000800000.codec: Device registered as /dev/video19"}
- {"dt": "2024-12-02T10:50:26.279973", "lvl": "target", "msg": "[   20.468979] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video21"}
- {"dt": "2024-12-02T10:50:26.281208", "lvl": "target", "msg": "[   20.489836] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2"}
- {"dt": "2024-12-02T10:50:26.281606", "lvl": "target", "msg": "[   20.498798] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video22"}
- {"dt": "2024-12-02T10:50:26.281886", "lvl": "target", "msg": "[   20.504368] cfg80211: failed to load regulatory.db"}
- {"dt": "2024-12-02T10:50:26.283289", "lvl": "target", "msg": "[   20.511659] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video23"}
- {"dt": "2024-12-02T10:50:26.322833", "lvl": "target", "msg": "[   20.511895] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2024-12-02T10:50:26.323348", "lvl": "target", "msg": "[   20.511898] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2024-12-02T10:50:26.323750", "lvl": "target", "msg": "[   20.511899] Bluetooth: HCI UART protocol BCSP registered"}
- {"dt": "2024-12-02T10:50:26.324097", "lvl": "target", "msg": "[   20.511917] Bluetooth: HCI UART protocol Three-wire (H5) registered"}
- {"dt": "2024-12-02T10:50:26.325141", "lvl": "target", "msg": "[   20.512015] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator"}
- {"dt": "2024-12-02T10:50:26.325441", "lvl": "target", "msg": "[   20.512048] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator"}
- {"dt": "2024-12-02T10:50:26.326662", "lvl": "target", "msg": "[   20.512066] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2024-12-02T10:50:26.366338", "lvl": "target", "msg": "[   20.571152] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video24"}
- {"dt": "2024-12-02T10:50:26.367047", "lvl": "target", "msg": "[   20.578540] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6"}
- {"dt": "2024-12-02T10:50:26.367616", "lvl": "target", "msg": "[   20.584883] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video25"}
- {"dt": "2024-12-02T10:50:26.368057", "lvl": "target", "msg": "[   20.592456] usbcore: registered new interface driver brcmfmac"}
- {"dt": "2024-12-02T10:50:26.368627", "lvl": "target", "msg": "[   20.598275] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video26"}
- {"dt": "2024-12-02T10:50:26.410118", "lvl": "target", "msg": "[   20.608598] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2"}
- {"dt": "2024-12-02T10:50:26.410832", "lvl": "target", "msg": "[   20.614517] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video27"}
- {"dt": "2024-12-02T10:50:26.412263", "lvl": "target", "msg": "[   20.656343] pispbe 1000880000.pisp_be: config device node registered as /dev/video28"}
- {"dt": "2024-12-02T10:50:26.412675", "lvl": "target", "msg": "[   20.664352] pispbe 1000880000.pisp_be: Register nodes for group 1"}
- {"dt": "2024-12-02T10:50:26.413158", "lvl": "target", "msg": "[   20.670517] pispbe 1000880000.pisp_be: input device node registered as /dev/video29"}
- {"dt": "2024-12-02T10:50:26.413743", "lvl": "target", "msg": "[   20.678310] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video30"}
- {"dt": "2024-12-02T10:50:26.453977", "lvl": "target", "msg": "[   20.686375] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video31"}
- {"dt": "2024-12-02T10:50:26.454525", "lvl": "target", "msg": "[   20.694708] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video32"}
- {"dt": "2024-12-02T10:50:26.454882", "lvl": "target", "msg": "[   20.702863] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video33"}
- {"dt": "2024-12-02T10:50:26.455178", "lvl": "target", "msg": "[   20.710788] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video34"}
- {"dt": "2024-12-02T10:50:26.457303", "lvl": "target", "msg": "[   20.718777] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video35"}
- {"dt": "2024-12-02T10:50:26.480793", "lvl": "target", "msg": "[   20.727213] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video36"}
- {"dt": "2024-12-02T10:50:26.483899", "lvl": "target", "msg": "[  OK  ] Finished Virtual Console[   20.735749] pispbe 1000880000.pisp_be: config device node registered as /dev/video37"}
- {"dt": "2024-12-02T10:50:26.484439", "lvl": "target", "msg": " Setup."}
- {"dt": "2024-12-02T10:50:26.531591", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/bthelper."}
- {"dt": "2024-12-02T10:50:26.573447", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2024-12-02T10:50:26.576568", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2024-12-02T10:50:26.620396", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2024-12-02T10:50:26.620940", "lvl": "target", "msg": "[   20.855436] brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2)"}
- {"dt": "2024-12-02T10:50:26.621284", "lvl": "target", "msg": "[   20.863267] 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-02T10:50:26.621562", "lvl": "target", "msg": "[   20.877543] Bluetooth: hci0: BCM: chip id 107"}
- {"dt": "2024-12-02T10:50:26.621820", "lvl": "target", "msg": "         Starting SELinux init fo[   20.882754] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-12-02T10:50:26.622069", "lvl": "target", "msg": "r /dev service loading..."}
- {"dt": "2024-12-02T10:50:26.623593", "lvl": "target", "msg": "[   20.890684] Bluetooth: hci0: BCM4345C0"}
- {"dt": "2024-12-02T10:50:26.695166", "lvl": "target", "msg": "[   20.895727] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000"}
- {"dt": "2024-12-02T10:50:26.695722", "lvl": "target", "msg": "         Starting Network Name Resolution..."}
- {"dt": "2024-12-02T10:50:26.696099", "lvl": "target", "msg": "[   20.908630] Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch"}
- {"dt": "2024-12-02T10:50:26.696377", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-12-02T10:50:26.696633", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2024-12-02T10:50:26.696923", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2024-12-02T10:50:26.697173", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-12-02T10:50:26.698514", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2024-12-02T10:50:26.714219", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2024-12-02T10:50:26.873853", "lvl": "target", "msg": "[  OK  ] Started Network Name Resolution."}
- {"dt": "2024-12-02T10:50:27.337156", "lvl": "target", "msg": "[   21.605296] Bluetooth: hci0: BCM: features 0x2f"}
- {"dt": "2024-12-02T10:50:27.366031", "lvl": "target", "msg": "[   21.611164] Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190"}
- {"dt": "2024-12-02T10:50:27.366609", "lvl": "target", "msg": "[   21.617376] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382"}
- {"dt": "2024-12-02T10:50:27.369170", "lvl": "target", "msg": "[   21.623605] Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac)"}
- {"dt": "2024-12-02T10:50:29.149533", "lvl": "target", "msg": "[*     ] (1 of 2) A start job is running for\u2026namic Linker Cache (2s / no limit)"}
- {"dt": "2024-12-02T10:50:29.733154", "lvl": "target", "msg": "M[K[**    ] (1 of 2) A start job is running for\u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2024-12-02T10:50:30.315870", "lvl": "target", "msg": "M[K[***   ] (1 of 2) A start job is running for\u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2024-12-02T10:50:30.899658", "lvl": "target", "msg": "M[K[ ***  ] (2 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (7s / 1min 30s)"}
- {"dt": "2024-12-02T10:50:31.482670", "lvl": "target", "msg": "M[K[  *** ] (2 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (8s / 1min 30s)"}
- {"dt": "2024-12-02T10:50:32.066226", "lvl": "target", "msg": "M[K[   ***] (2 of 2) A start job is running for\u2026m/gpu/graphics/fb0 (8s / 1min 30s)"}
- {"dt": "2024-12-02T10:50:32.481451", "lvl": "target", "msg": "M[K[  OK  ] Finished Rebuild Dynamic Linker Cache."}
- {"dt": "2024-12-02T10:50:32.513540", "lvl": "target", "msg": "[K         Starting Update is Completed..."}
- {"dt": "2024-12-02T10:50:32.586193", "lvl": "target", "msg": "[  OK  ] Finished Update is Completed."}
- {"dt": "2024-12-02T10:50:32.586777", "lvl": "target", "msg": "[  OK  ] Reached target System Initialization."}
- {"dt": "2024-12-02T10:50:32.587157", "lvl": "target", "msg": "[  OK  ] Started Daily Cleanup of Temporary Directories."}
- {"dt": "2024-12-02T10:50:32.587458", "lvl": "target", "msg": "[  OK  ] Reached target Timer Units."}
- {"dt": "2024-12-02T10:50:32.588493", "lvl": "target", "msg": "[  OK  ] Listening on agl-compositor, a Wayl\u2026ositor, as a systemd user service."}
- {"dt": "2024-12-02T10:50:32.589735", "lvl": "target", "msg": "[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket."}
- {"dt": "2024-12-02T10:50:32.605178", "lvl": "target", "msg": "[  OK  ] Listening on D-Bus System Message Bus Socket."}
- {"dt": "2024-12-02T10:50:32.644113", "lvl": "target", "msg": "[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets."}
- {"dt": "2024-12-02T10:50:32.644641", "lvl": "target", "msg": "[  OK  ] Listening on mpd.socket."}
- {"dt": "2024-12-02T10:50:32.672164", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Manager Socket..."}
- {"dt": "2024-12-02T10:50:32.687830", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Socket..."}
- {"dt": "2024-12-02T10:50:32.688339", "lvl": "target", "msg": "         Starting sshd.socket..."}
- {"dt": "2024-12-02T10:50:32.723814", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Manager Socket."}
- {"dt": "2024-12-02T10:50:32.724399", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Socket."}
- {"dt": "2024-12-02T10:50:32.727214", "lvl": "target", "msg": "[  OK  ] Listening on sshd.socket."}
- {"dt": "2024-12-02T10:50:32.742624", "lvl": "target", "msg": "[  OK  ] Reached target Socket Units."}
- {"dt": "2024-12-02T10:50:32.758890", "lvl": "target", "msg": "[  OK  ] Reached target Basic System."}
- {"dt": "2024-12-02T10:50:32.790620", "lvl": "target", "msg": "         Starting CAN interface helper..."}
- {"dt": "2024-12-02T10:50:32.817468", "lvl": "target", "msg": "         Starting D-Bus System Message Bus..."}
- {"dt": "2024-12-02T10:50:32.820546", "lvl": "target", "msg": "         Starting Raspberry Pi bluetooth helper..."}
- {"dt": "2024-12-02T10:50:32.836472", "lvl": "target", "msg": "         Starting IPv6 Packet Filtering Framework..."}
- {"dt": "2024-12-02T10:50:32.875308", "lvl": "target", "msg": "         Starting IPv4 Packet Filtering Framework..."}
- {"dt": "2024-12-02T10:50:32.876036", "lvl": "target", "msg": "         Starting Telephony service..."}
- {"dt": "2024-12-02T10:50:32.878455", "lvl": "target", "msg": "[  OK  ] Started PipeWire Multimedia Service."}
- {"dt": "2024-12-02T10:50:32.879159", "lvl": "target", "msg": "         Starting Authorization Manager..."}
- {"dt": "2024-12-02T10:50:32.894302", "lvl": "target", "msg": "         Starting User Login Management..."}
- {"dt": "2024-12-02T10:50:32.910258", "lvl": "target", "msg": "         Starting Disk Manager..."}
- {"dt": "2024-12-02T10:50:32.929294", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (audio)."}
- {"dt": "2024-12-02T10:50:32.960170", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (bluetooth)."}
- {"dt": "2024-12-02T10:50:32.963294", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (policy)."}
- {"dt": "2024-12-02T10:50:33.004311", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (video-capture)."}
- {"dt": "2024-12-02T10:50:33.005193", "lvl": "target", "msg": "         Starting OpenSSH Key Generation..."}
- {"dt": "2024-12-02T10:50:33.007302", "lvl": "target", "msg": "[  OK  ] Finished IPv6 Packet Filtering Framework."}
- {"dt": "2024-12-02T10:50:33.023165", "lvl": "target", "msg": "[  OK  ] Finished IPv4 Packet Filtering Framework."}
- {"dt": "2024-12-02T10:50:33.023905", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Network."}
- {"dt": "2024-12-02T10:50:33.050975", "lvl": "target", "msg": "         Starting Network Configuration..."}
- {"dt": "2024-12-02T10:50:33.054116", "lvl": "target", "msg": "[   27.311833] CAN device driver interface"}
- {"dt": "2024-12-02T10:50:33.070097", "lvl": "target", "msg": "[   27.332254] vcan: Virtual CAN interface driver"}
- {"dt": "2024-12-02T10:50:33.086011", "lvl": "target", "msg": "[  OK  ] Finished CAN interface helper."}
- {"dt": "2024-12-02T10:50:33.102108", "lvl": "target", "msg": "[  OK  ] Finished Raspberry Pi bluetooth helper."}
- {"dt": "2024-12-02T10:50:33.133858", "lvl": "target", "msg": "         Starting Bluetooth service..."}
- {"dt": "2024-12-02T10:50:33.165751", "lvl": "target", "msg": "[  OK  ] Started User Login Management."}
- {"dt": "2024-12-02T10:50:34.442996", "lvl": "target", "msg": "[  OK  ] Started Network Configuration."}
- {"dt": "2024-12-02T10:50:34.682620", "lvl": "target", "msg": "[  OK  ] Started D-Bus System Message Bus."}
- {"dt": "2024-12-02T10:50:34.698578", "lvl": "target", "msg": "         Starting Connection service..."}
- {"dt": "2024-12-02T10:50:34.714648", "lvl": "target", "msg": "[  OK  ] Started Bluetooth service."}
- {"dt": "2024-12-02T10:50:34.730471", "lvl": "target", "msg": "[  OK  ] Started Telephony service."}
- {"dt": "2024-12-02T10:50:34.746503", "lvl": "target", "msg": "[  OK  ] Reached target Bluetooth Support."}
- {"dt": "2024-12-02T10:50:34.938029", "lvl": "target", "msg": "[   29.182714] Bluetooth: BNEP (Ethernet Emulation) ver 1.3"}
- {"dt": "2024-12-02T10:50:34.938648", "lvl": "target", "msg": "[   29.188055] Bluetooth: BNEP filters: protocol multicast"}
- {"dt": "2024-12-02T10:50:34.939010", "lvl": "target", "msg": "[   29.193313] Bluetooth: BNEP socket layer initialized"}
- {"dt": "2024-12-02T10:50:34.941322", "lvl": "target", "msg": "[   29.199554] Bluetooth: MGMT ver 1.22"}
- {"dt": "2024-12-02T10:50:35.004828", "lvl": "target", "msg": "         Starting Hostname Service..."}
- {"dt": "2024-12-02T10:50:35.100685", "lvl": "target", "msg": "[  OK  ] Started Hostname Service."}
- {"dt": "2024-12-02T10:50:35.225373", "lvl": "target", "msg": "[   29.476515] Bluetooth: RFCOMM TTY layer initialized"}
- {"dt": "2024-12-02T10:50:35.225995", "lvl": "target", "msg": "[   29.481424] Bluetooth: RFCOMM socket layer initialized"}
- {"dt": "2024-12-02T10:50:35.228557", "lvl": "target", "msg": "[   29.486763] Bluetooth: RFCOMM ver 1.11"}
- {"dt": "2024-12-02T10:50:35.260272", "lvl": "target", "msg": "[  OK  ] Started Connection service."}
- {"dt": "2024-12-02T10:50:35.276283", "lvl": "target", "msg": "[  OK  ] Reached target Network."}
- {"dt": "2024-12-02T10:50:35.335112", "lvl": "target", "msg": "[  OK  ] Started agl-service-radio.service."}
- {"dt": "2024-12-02T10:50:35.338258", "lvl": "target", "msg": "         Starting Avahi mDNS/DNS-SD Stack..."}
- {"dt": "2024-12-02T10:50:35.354143", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val databroker."}
- {"dt": "2024-12-02T10:50:35.375156", "lvl": "target", "msg": "[   29.628290] pipewire[477]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set"}
- {"dt": "2024-12-02T10:50:35.391171", "lvl": "target", "msg": "[  OK  ] Started agl-service-audiomixer.service."}
- {"dt": "2024-12-02T10:50:35.417955", "lvl": "target", "msg": "[  OK  ] Started agl-service-hvac.service."}
- {"dt": "2024-12-02T10:50:35.421210", "lvl": "target", "msg": "         Starting agl-vss-helper.service..."}
- {"dt": "2024-12-02T10:50:35.437137", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val DBC feeder."}
- {"dt": "2024-12-02T10:50:35.453067", "lvl": "target", "msg": "         Starting Permit User Sessions..."}
- {"dt": "2024-12-02T10:50:35.532855", "lvl": "target", "msg": "[  OK  ] Finished Permit User Sessions."}
- {"dt": "2024-12-02T10:50:35.548802", "lvl": "target", "msg": "[  OK  ] Started Avahi mDNS/DNS-SD Stack."}
- {"dt": "2024-12-02T10:50:35.580712", "lvl": "target", "msg": "         Starting AGL compositor..."}
- {"dt": "2024-12-02T10:50:35.596606", "lvl": "target", "msg": "[  OK  ] Started Getty on tty1."}
- {"dt": "2024-12-02T10:50:35.612624", "lvl": "target", "msg": "[  OK  ] Started Serial Getty on ttyAMA10."}
- {"dt": "2024-12-02T10:50:35.628584", "lvl": "target", "msg": "[  OK  ] Reached target Login Prompts."}
- {"dt": "2024-12-02T10:50:36.139599", "lvl": "target", "msg": "         Starting WPA supplicant..."}
- {"dt": "2024-12-02T10:50:36.554711", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2024-12-02T10:50:36.570634", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2024-12-02T10:50:37.848076", "lvl": "target", "msg": "[  OK  ] Started WPA supplicant."}
- {"dt": "2024-12-02T10:50:37.905653", "lvl": "target", "msg": "[  OK  ] Created slice User Slice of UID 1001."}
- {"dt": "2024-12-02T10:50:37.909008", "lvl": "target", "msg": "         Starting User Runtime Directory /run/user/1001..."}
- {"dt": "2024-12-02T10:50:38.036610", "lvl": "target", "msg": "[  OK  ] Finished User Runtime Directory /run/user/1001."}
- {"dt": "2024-12-02T10:50:38.094275", "lvl": "target", "msg": "         Starting User Manager for UID 1001..."}
- {"dt": "2024-12-02T10:50:38.097613", "lvl": "target", "msg": "[  OK  ] Finished OpenSSH Key Generation."}
- {"dt": "2024-12-02T10:50:38.362720", "lvl": "target", "msg": "[   32.619026] can: controller area network core"}
- {"dt": "2024-12-02T10:50:38.365888", "lvl": "target", "msg": "[   32.623441] NET: Registered PF_CAN protocol family"}
- {"dt": "2024-12-02T10:50:38.389837", "lvl": "target", "msg": "[  OK  ] Started Authorization Ma[   32.643580] can: raw protocol"}
- {"dt": "2024-12-02T10:50:38.390356", "lvl": "target", "msg": "nager."}
- {"dt": "2024-12-02T10:50:38.421676", "lvl": "target", "msg": "         Starting applaunchd.service..."}
- {"dt": "2024-12-02T10:50:38.453588", "lvl": "target", "msg": "[  OK  ] Started Disk Manager."}
- {"dt": "2024-12-02T10:50:38.485604", "lvl": "target", "msg": "[  OK  ] Started Automount Disk Manager."}
- {"dt": "2024-12-02T10:50:39.044499", "lvl": "target", "msg": "[  OK  ] Started User Manager for UID 1001."}
- {"dt": "2024-12-02T10:50:39.220240", "lvl": "target", "msg": "[  OK  ] Started Session c1 of User agl-driver."}
- {"dt": "2024-12-02T10:50:39.443465", "lvl": "target", "msg": "[  OK  ] Started applaunchd.service."}
- {"dt": "2024-12-02T10:50:39.587238", "lvl": "target", "msg": "[FAILED] Failed to start AGL compositor."}
- {"dt": "2024-12-02T10:50:39.627971", "lvl": "target", "msg": "See 'systemctl status agl-compositor.service' for details."}
- {"dt": "2024-12-02T10:50:39.628509", "lvl": "target", "msg": "[DEPEND] Dependency failed for homescreen.service."}
- {"dt": "2024-12-02T10:50:39.631341", "lvl": "target", "msg": "[DEPEND] Dependency failed for launcher.service."}
- {"dt": "2024-12-02T10:50:41.709073", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:41.710056", "lvl": "target", "msg": "Automotive Grade Linux 18.0.2 raspberrypi5 ttyAMA10"}
- {"dt": "2024-12-02T10:50:41.710590", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:41.713429", "lvl": "debug", "msg": "Matched prompt #12: login:"}
- {"dt": "2024-12-02T10:50:41.713940", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "35.94", "extra": {"extra": []}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:50:41.714566", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2024-12-02T10:50:41.714942", "lvl": "input", "msg": "root\n"}
- {"dt": "2024-12-02T10:50:41.815736", "lvl": "target", "msg": "raspberrypi5 login: root"}
- {"dt": "2024-12-02T10:50:41.816406", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-02T10:50:41.816847", "lvl": "debug", "msg": "login-action: Wait for prompt ['raspberrypi5:~#', '/ #', 'Login incorrect', 'Login timed out'] (timeout 00:05:11)"}
- {"dt": "2024-12-02T10:50:41.823891", "lvl": "target", "msg": "root"}
- {"dt": "2024-12-02T10:50:42.398998", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-02T10:50:42.399833", "lvl": "debug", "msg": "end: 2.3.1 login-action (duration 00:00:37) [common]"}
- {"dt": "2024-12-02T10:50:42.400320", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "36.63", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:50:42.401229", "lvl": "debug", "msg": "end: 2.3 auto-login-action (duration 00:03:52) [common]"}
- {"dt": "2024-12-02T10:50:42.401745", "lvl": "debug", "msg": "start: 2.4 expect-shell-connection (timeout 00:05:11) [common]"}
- {"dt": "2024-12-02T10:50:42.402214", "lvl": "debug", "msg": "Setting prompt string to ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-02T10:50:42.402507", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['raspberrypi5:~#', '/ #']"}
- {"dt": "2024-12-02T10:50:42.402795", "lvl": "input", "msg": "\n"}
- {"dt": "2024-12-02T10:50:42.453187", "lvl": "target", "msg": "7[r[999;999H[6n8root@raspberrypi5:~# "}
- {"dt": "2024-12-02T10:50:42.453964", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:20:00)"}
- {"dt": "2024-12-02T10:50:42.454546", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2024-12-02T10:50:42.462477", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:42.478880", "lvl": "debug", "msg": "end: 2.4 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:50:42.479525", "lvl": "debug", "msg": "start: 2.5 overlay-unpack (timeout 00:05:11) [common]"}
- {"dt": "2024-12-02T10:50:42.479982", "lvl": "input", "msg": "rm overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-02T10:50:42.580588", "lvl": "target", "msg": "root@raspberrypi5:~# rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T10:50:42.590121", "lvl": "target", "msg": "rm overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T10:50:42.616232", "lvl": "target", "msg": "rm: cannot remove 'overlay-1.5.5.tar.gz': No such file or directory"}
- {"dt": "2024-12-02T10:50:42.617096", "lvl": "input", "msg": "wget http://192.168.66.1/tmp/38058/compress-overlay-imian9b7/overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-02T10:50:42.717828", "lvl": "target", "msg": "root@raspberrypi5:~#wget http://192.168.66.1/tmp/38058/compress-overlay-imian9b7/overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T10:50:42.761124", "lvl": "target", "msg": " wget http://192.168.66.1/tmp/38058/compress-overlay-imian9b7/overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T10:50:42.770843", "lvl": "target", "msg": "Connecting to 192.168.66.1 (192.168.66.1:80)"}
- {"dt": "2024-12-02T10:50:42.771345", "lvl": "target", "msg": "saving to 'overlay-1.5.5.tar.gz'"}
- {"dt": "2024-12-02T10:50:42.774307", "lvl": "target", "msg": "overlay-1.5.5.tar.gz 100% |**********************************************************************************************************************************************|  8044  0:00:00 ETA"}
- {"dt": "2024-12-02T10:50:42.774834", "lvl": "target", "msg": "'overlay-1.5.5.tar.gz' saved"}
- {"dt": "2024-12-02T10:50:42.790108", "lvl": "input", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz\n"}
- {"dt": "2024-12-02T10:50:42.891039", "lvl": "target", "msg": "root@raspberrypi5:~# tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T10:50:42.901588", "lvl": "target", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2024-12-02T10:50:42.965601", "lvl": "target", "msg": "x lava-38058/"}
- {"dt": "2024-12-02T10:50:43.005820", "lvl": "target", "msg": "x lava-38058/0/"}
- {"dt": "2024-12-02T10:50:43.006340", "lvl": "target", "msg": "x lava-38058/0/lava-test-runner.conf"}
- {"dt": "2024-12-02T10:50:43.006718", "lvl": "target", "msg": "x lava-38058/0/tests/"}
- {"dt": "2024-12-02T10:50:43.006994", "lvl": "target", "msg": "x lava-38058/0/tests/0_dmesg/"}
- {"dt": "2024-12-02T10:50:43.007251", "lvl": "target", "msg": "x lava-38058/0/tests/0_dmesg/inline/"}
- {"dt": "2024-12-02T10:50:43.007496", "lvl": "target", "msg": "x lava-38058/0/tests/0_dmesg/inline/dmesg.yaml"}
- {"dt": "2024-12-02T10:50:43.007736", "lvl": "target", "msg": "x lava-38058/0/tests/0_dmesg/run.sh"}
- {"dt": "2024-12-02T10:50:43.007973", "lvl": "target", "msg": "x lava-38058/0/tests/0_dmesg/testdef.yaml"}
- {"dt": "2024-12-02T10:50:43.008211", "lvl": "target", "msg": "x lava-38058/0/tests/0_dmesg/testdef_metadata"}
- {"dt": "2024-12-02T10:50:43.008443", "lvl": "target", "msg": "x lava-38058/0/tests/0_dmesg/uuid"}
- {"dt": "2024-12-02T10:50:43.008678", "lvl": "target", "msg": "x lava-38058/1/"}
- {"dt": "2024-12-02T10:50:43.009423", "lvl": "target", "msg": "x lava-38058/1/lava-test-runner.conf"}
- {"dt": "2024-12-02T10:50:43.009825", "lvl": "target", "msg": "x lava-38058/1/tests/"}
- {"dt": "2024-12-02T10:50:43.010078", "lvl": "target", "msg": "x lava-38058/1/tests/1_bootrr/"}
- {"dt": "2024-12-02T10:50:43.049350", "lvl": "target", "msg": "x lava-38058/1/tests/1_bootrr/inline/"}
- {"dt": "2024-12-02T10:50:43.049889", "lvl": "target", "msg": "x lava-38058/1/tests/1_bootrr/inline/bootrr.yaml"}
- {"dt": "2024-12-02T10:50:43.050216", "lvl": "target", "msg": "x lava-38058/1/tests/1_bootrr/run.sh"}
- {"dt": "2024-12-02T10:50:43.050485", "lvl": "target", "msg": "x lava-38058/1/tests/1_bootrr/testdef.yaml"}
- {"dt": "2024-12-02T10:50:43.050738", "lvl": "target", "msg": "x lava-38058/1/tests/1_bootrr/testdef_metadata"}
- {"dt": "2024-12-02T10:50:43.050983", "lvl": "target", "msg": "x lava-38058/1/tests/1_bootrr/uuid"}
- {"dt": "2024-12-02T10:50:43.051227", "lvl": "target", "msg": "x lava-38058/bin/"}
- {"dt": "2024-12-02T10:50:43.051466", "lvl": "target", "msg": "x lava-38058/bin/lava-add-keys"}
- {"dt": "2024-12-02T10:50:43.051703", "lvl": "target", "msg": "x lava-38058/bin/lava-add-sources"}
- {"dt": "2024-12-02T10:50:43.051940", "lvl": "target", "msg": "x lava-38058/bin/lava-background-process-start"}
- {"dt": "2024-12-02T10:50:43.052197", "lvl": "target", "msg": "x lava-38058/bin/lava-background-process-stop"}
- {"dt": "2024-12-02T10:50:43.052460", "lvl": "target", "msg": "x lava-38058/bin/lava-common-functions"}
- {"dt": "2024-12-02T10:50:43.053054", "lvl": "target", "msg": "x lava-38058/bin/lava-echo-ipv4"}
- {"dt": "2024-12-02T10:50:43.092597", "lvl": "target", "msg": "x lava-38058/bin/lava-install-packages"}
- {"dt": "2024-12-02T10:50:43.093169", "lvl": "target", "msg": "x lava-38058/bin/lava-installed-packages"}
- {"dt": "2024-12-02T10:50:43.093487", "lvl": "target", "msg": "x lava-38058/bin/lava-os-build"}
- {"dt": "2024-12-02T10:50:43.093756", "lvl": "target", "msg": "x lava-38058/bin/lava-probe-channel"}
- {"dt": "2024-12-02T10:50:43.094007", "lvl": "target", "msg": "x lava-38058/bin/lava-probe-ip"}
- {"dt": "2024-12-02T10:50:43.094246", "lvl": "target", "msg": "x lava-38058/bin/lava-target-ip"}
- {"dt": "2024-12-02T10:50:43.094485", "lvl": "target", "msg": "x lava-38058/bin/lava-target-mac"}
- {"dt": "2024-12-02T10:50:43.094722", "lvl": "target", "msg": "x lava-38058/bin/lava-target-storage"}
- {"dt": "2024-12-02T10:50:43.094959", "lvl": "target", "msg": "x lava-38058/bin/lava-test-case"}
- {"dt": "2024-12-02T10:50:43.095194", "lvl": "target", "msg": "x lava-38058/bin/lava-test-event"}
- {"dt": "2024-12-02T10:50:43.095440", "lvl": "target", "msg": "x lava-38058/bin/lava-test-feedback"}
- {"dt": "2024-12-02T10:50:43.095707", "lvl": "target", "msg": "x lava-38058/bin/lava-test-raise"}
- {"dt": "2024-12-02T10:50:43.095983", "lvl": "target", "msg": "x lava-38058/bin/lava-test-reference"}
- {"dt": "2024-12-02T10:50:43.096538", "lvl": "target", "msg": "x lava-38058/bin/lava-test-runner"}
- {"dt": "2024-12-02T10:50:43.119220", "lvl": "target", "msg": "x lava-38058/bin/lava-test-set"}
- {"dt": "2024-12-02T10:50:43.119741", "lvl": "target", "msg": "x lava-38058/bin/lava-test-shell"}
- {"dt": "2024-12-02T10:50:43.120081", "lvl": "target", "msg": "x lava-38058/environment"}
- {"dt": "2024-12-02T10:50:43.120358", "lvl": "target", "msg": "x lava-38058/lava"}
- {"dt": "2024-12-02T10:50:43.120617", "lvl": "target", "msg": "x lava-38058/lava.pub"}
- {"dt": "2024-12-02T10:50:43.122356", "lvl": "target", "msg": "x lava-38058/results/"}
- {"dt": "2024-12-02T10:50:43.123070", "lvl": "target", "msg": "x lava-38058/tests/"}
- {"dt": "2024-12-02T10:50:43.123898", "lvl": "debug", "msg": "end: 2.5 overlay-unpack (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:50:43.124379", "lvl": "debug", "msg": "start: 2.6 export-device-env (timeout 00:05:10) [common]"}
- {"dt": "2024-12-02T10:50:43.124827", "lvl": "debug", "msg": "end: 2.6 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-12-02T10:50:43.125232", "lvl": "info", "msg": "end: 2 minimal-boot (duration 00:04:00) [common]"}
- {"dt": "2024-12-02T10:50:43.125674", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-12-02T10:50:43.126062", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-12-02T10:50:43.126376", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-12-02T10:50:43.126685", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-12-02T10:50:43.227335", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-12-02T10:50:43.228031", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-12-02T10:50:43.233986", "lvl": "target", "msg": "#"}
- {"dt": "2024-12-02T10:50:43.250287", "lvl": "debug", "msg": "Using /lava-38058"}
- {"dt": "2024-12-02T10:50:43.250793", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-12-02T10:50:43.351507", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh"}
- {"dt": "2024-12-02T10:50:43.361573", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-12-02T10:50:43.405381", "lvl": "input", "msg": ". /lava-38058/environment\n"}
- {"dt": "2024-12-02T10:50:43.506232", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-38058/environment"}
- {"dt": "2024-12-02T10:50:43.521380", "lvl": "target", "msg": ". /lava-38058/environment"}
- {"dt": "2024-12-02T10:50:43.565425", "lvl": "input", "msg": "/lava-38058/bin/lava-test-runner /lava-38058/0\n"}
- {"dt": "2024-12-02T10:50:43.666278", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-38058/bin/lava-test-runner /lava-38058/0"}
- {"dt": "2024-12-02T10:50:43.666952", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-12-02T10:50:43.680998", "lvl": "target", "msg": "/lava-38058/bin/lava-test-runner /lava-38058/0"}
- {"dt": "2024-12-02T10:50:43.763677", "lvl": "target", "msg": "+ export TESTRUN_ID=0_dmesg"}
- {"dt": "2024-12-02T10:50:43.764204", "lvl": "target", "msg": "+ TESTRUN_ID=0_dmesg"}
- {"dt": "2024-12-02T10:50:43.764594", "lvl": "target", "msg": "+ cd /lava-38058/0/tests/0_dmesg"}
- {"dt": "2024-12-02T10:50:43.764923", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-12-02T10:50:43.765191", "lvl": "target", "msg": "+ UUID=38058_1.5.4.1"}
- {"dt": "2024-12-02T10:50:43.765445", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-02T10:50:43.765692", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_dmesg 38058_1.5.4.1>"}
- {"dt": "2024-12-02T10:50:43.765970", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-12-02T10:50:43.766213", "lvl": "target", "msg": "+ dmesg --level=warn --notime -x -k"}
- {"dt": "2024-12-02T10:50:43.766458", "lvl": "target", "msg": "+ for level in warn err"}
- {"dt": "2024-12-02T10:50:43.766720", "lvl": "target", "msg": "+ dmesg --level=err --notime -x -k"}
- {"dt": "2024-12-02T10:50:43.766998", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-02T10:50:43.767290", "lvl": "target", "msg": "+ dmesg --level=crit --notime -x -k"}
- {"dt": "2024-12-02T10:50:43.767589", "lvl": "target", "msg": "+ test -s dmesg.crit"}
- {"dt": "2024-12-02T10:50:43.767841", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-02T10:50:43.767939", "lvl": "target", "msg": "++ cat dmesg.crit"}
- {"dt": "2024-12-02T10:50:43.768010", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-02T10:50:43.768082", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-02T10:50:43.768346", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_dmesg 38058_1.5.4.1"}
- {"dt": "2024-12-02T10:50:43.768459", "lvl": "info", "msg": "Starting test lava.0_dmesg (38058_1.5.4.1)"}
- {"dt": "2024-12-02T10:50:43.768574", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-12-02T10:50:43.807060", "lvl": "target", "msg": "+ lava-test-case crit --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-02T10:50:43.807583", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-02T10:50:43.807936", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-02T10:50:43.808217", "lvl": "target", "msg": "+ dmesg --level=alert --notime -x -k"}
- {"dt": "2024-12-02T10:50:43.808476", "lvl": "target", "msg": "+ test -s dmesg.alert"}
- {"dt": "2024-12-02T10:50:43.808725", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-02T10:50:43.809020", "lvl": "target", "msg": "++ cat dmesg.alert"}
- {"dt": "2024-12-02T10:50:43.809268", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-02T10:50:43.809512", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-02T10:50:43.809754", "lvl": "target", "msg": "+ lava-test-case alert --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-02T10:50:43.810000", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-02T10:50:43.810269", "lvl": "target", "msg": "+ for level in crit alert emerg"}
- {"dt": "2024-12-02T10:50:43.810814", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=crit RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-02T10:50:43.811421", "lvl": "results", "msg": {"case": "crit", "definition": "0_dmesg", "endtc": ! "1516", "measurement": ! "0.0", "result": "pass", "starttc": ! "1516", "units": "lines"}}
- {"dt": "2024-12-02T10:50:43.811664", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=alert RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-02T10:50:43.811776", "lvl": "results", "msg": {"case": "alert", "definition": "0_dmesg", "endtc": ! "1518", "measurement": ! "0.0", "result": "pass", "starttc": ! "1518", "units": "lines"}}
- {"dt": "2024-12-02T10:50:43.811942", "lvl": "target", "msg": "+ dmesg --level=emerg --notime -x -k"}
- {"dt": "2024-12-02T10:50:43.812023", "lvl": "target", "msg": "+ test -s dmesg.emerg"}
- {"dt": "2024-12-02T10:50:43.812095", "lvl": "target", "msg": "+ res=pass"}
- {"dt": "2024-12-02T10:50:43.854677", "lvl": "target", "msg": "++ cat dmesg.emerg"}
- {"dt": "2024-12-02T10:50:43.855190", "lvl": "target", "msg": "++ wc -l"}
- {"dt": "2024-12-02T10:50:43.855560", "lvl": "target", "msg": "+ count=0"}
- {"dt": "2024-12-02T10:50:43.855837", "lvl": "target", "msg": "+ lava-test-case emerg --result pass --measurement 0 --units lines"}
- {"dt": "2024-12-02T10:50:43.856099", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0>"}
- {"dt": "2024-12-02T10:50:43.856351", "lvl": "target", "msg": "+ cat dmesg.emerg dmesg.alert dmesg.crit dmesg.err dmesg.warn"}
- {"dt": "2024-12-02T10:50:43.856599", "lvl": "target", "msg": "kern  :warn  : F2FS not supported on PAGE_SIZE(16384) != 4096"}
- {"dt": "2024-12-02T10:50:43.856885", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-02T10:50:43.857149", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_dmesg 38058_1.5.4.1>"}
- {"dt": "2024-12-02T10:50:43.857674", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=emerg RESULT=pass UNITS=lines MEASUREMENT=0"}
- {"dt": "2024-12-02T10:50:43.858094", "lvl": "results", "msg": {"case": "emerg", "definition": "0_dmesg", "endtc": ! "1532", "measurement": ! "0.0", "result": "pass", "starttc": ! "1532", "units": "lines"}}
- {"dt": "2024-12-02T10:50:43.858822", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_dmesg 38058_1.5.4.1"}
- {"dt": "2024-12-02T10:50:43.859176", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-12-02T10:50:43.859479", "lvl": "info", "msg": "Ending test lava.0_dmesg (38058_1.5.4.1), duration 0.09"}
- {"dt": "2024-12-02T10:50:43.859936", "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": "38058_1.5.4.1"}}
- {"dt": "2024-12-02T10:50:43.860985", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2024-12-02T10:50:43.861564", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-12-02T10:50:43.862017", "lvl": "debug", "msg": "alert: pass\ncrit: pass\nemerg: pass\n"}
- {"dt": "2024-12-02T10:50:43.862399", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:50:43.862759", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:50:43.863112", "lvl": "info", "msg": "start: 4 lava-test-retry (timeout 00:01:00) [common]"}
- {"dt": "2024-12-02T10:50:43.863472", "lvl": "debug", "msg": "start: 4.1 lava-test-shell (timeout 00:01:00) [common]"}
- {"dt": "2024-12-02T10:50:43.863742", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-12-02T10:50:43.864019", "lvl": "input", "msg": "#\n"}
- {"dt": "2024-12-02T10:50:43.964623", "lvl": "target", "msg": "root@raspberrypi5:~# #"}
- {"dt": "2024-12-02T10:50:43.965412", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['raspberrypi5:~#', '/ #'] (timeout 00:01:00)"}
- {"dt": "2024-12-02T10:50:43.965990", "lvl": "debug", "msg": "Using /lava-38058"}
- {"dt": "2024-12-02T10:50:43.966315", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2024-12-02T10:50:44.067007", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-12-02T10:50:44.067839", "lvl": "target", "msg": "#"}
- {"dt": "2024-12-02T10:50:44.068565", "lvl": "input", "msg": ". /lava-38058/environment\n"}
- {"dt": "2024-12-02T10:50:44.169276", "lvl": "target", "msg": "root@raspberrypi5:~# export SHELL=/bin/sh. /lava-38058/environment"}
- {"dt": "2024-12-02T10:50:44.170101", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:44.170821", "lvl": "input", "msg": "/lava-38058/bin/lava-test-runner /lava-38058/1\n"}
- {"dt": "2024-12-02T10:50:44.271516", "lvl": "target", "msg": "root@raspberrypi5:~# . /lava-38058/environment/lava-38058/bin/lava-test-runner /lava-38058/1"}
- {"dt": "2024-12-02T10:50:44.272302", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-12-02T10:50:44.272948", "lvl": "target", "msg": ""}
- {"dt": "2024-12-02T10:50:44.288820", "lvl": "target", "msg": "root@raspberrypi5:~# /lava-38058/bin/lava-test-runner /lava-38058/1"}
- {"dt": "2024-12-02T10:50:44.352824", "lvl": "target", "msg": "+ export TESTRUN_ID=1_bootrr"}
- {"dt": "2024-12-02T10:50:44.353380", "lvl": "target", "msg": "+ TESTRUN_I[   38.587510] <LAVA_SIGNAL_STARTRUN 1_bootrr 38058_1.5.4.5>"}
- {"dt": "2024-12-02T10:50:44.353721", "lvl": "target", "msg": "D=1_bootrr"}
- {"dt": "2024-12-02T10:50:44.353995", "lvl": "target", "msg": "+ cd /lava-38058/1/tes[   38.595804] <LAVA_SIGNAL_ENDRUN 1_bootrr 38058_1.5.4.5>"}
- {"dt": "2024-12-02T10:50:44.354261", "lvl": "target", "msg": "ts/1_bootrr"}
- {"dt": "2024-12-02T10:50:44.354537", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2024-12-02T10:50:44.354831", "lvl": "target", "msg": "+ UUID=38058_1.5.4.5"}
- {"dt": "2024-12-02T10:50:44.355095", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-02T10:50:44.355352", "lvl": "target", "msg": "+ export PATH=/opt/bootrr/helpers:/lava-38058/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-12-02T10:50:44.355888", "lvl": "debug", "msg": "Received signal: <STARTRUN> 1_bootrr 38058_1.5.4.5"}
- {"dt": "2024-12-02T10:50:44.356248", "lvl": "info", "msg": "Starting test lava.1_bootrr (38058_1.5.4.5)"}
- {"dt": "2024-12-02T10:50:44.356623", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-12-02T10:50:44.357088", "lvl": "debug", "msg": "Received signal: <ENDRUN> 1_bootrr 38058_1.5.4.5"}
- {"dt": "2024-12-02T10:50:44.357374", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-12-02T10:50:44.357450", "lvl": "info", "msg": "Ending test lava.1_bootrr (38058_1.5.4.5), duration 0.00"}
- {"dt": "2024-12-02T10:50:44.357559", "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": "38058_1.5.4.5"}}
- {"dt": "2024-12-02T10:50:44.357846", "lvl": "target", "msg": "+ PATH=/opt/bootrr/helpers:/lava-38058/1/../bin:/usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin"}
- {"dt": "2024-12-02T10:50:44.378634", "lvl": "target", "msg": "+ cd /opt/bootrr"}
- {"dt": "2024-12-02T10:50:44.379136", "lvl": "target", "msg": "/lava-38058/1/tests/1_bootrr/run.sh: line 15: cd: /opt/bootrr: No such file or directory"}
- {"dt": "2024-12-02T10:50:44.379461", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-12-02T10:50:44.379993", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-12-02T10:50:44.380469", "lvl": "debug", "msg": "end: 4.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:50:44.380896", "lvl": "info", "msg": "end: 4 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:50:44.381292", "lvl": "info", "msg": "start: 5 finalize (timeout 00:05:09) [common]"}
- {"dt": "2024-12-02T10:50:44.381738", "lvl": "debug", "msg": "start: 5.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-12-02T10:50:44.382361", "lvl": "debug", "msg": "Calling: 'upower.sh' '--atx' 'off' '2'"}
- {"dt": "2024-12-02T10:50:45.435187", "lvl": "debug", "msg": ">> Accessing Gembird #0 USB device 002\r"}
- {"dt": "2024-12-02T10:50:45.435812", "lvl": "debug", "msg": ">> Switched outlet 2 off\r"}
- {"dt": "2024-12-02T10:50:45.437105", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-12-02T10:50:45.537880", "lvl": "debug", "msg": "end: 5.1 power-off (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:50:45.538461", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "1.16", "extra": {"status": "success"}, "level": "5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-02T10:50:45.539193", "lvl": "debug", "msg": "start: 5.2 read-feedback (timeout 00:05:07) [common]"}
- {"dt": "2024-12-02T10:50:45.540245", "lvl": "feedback", "msg": "<LAVA_TEST_RUNNER EXIT>", "ns": "common"}
- {"dt": "2024-12-02T10:50:45.540819", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-12-02T10:50:46.541117", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-12-02T10:50:46.541788", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-12-02T10:50:46.542163", "lvl": "target", "msg": "root@raspberrypi5:~# \0"}
- {"dt": "2024-12-02T10:50:46.643217", "lvl": "debug", "msg": "end: 5.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-12-02T10:50:46.643782", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2024-12-02T10:50:46.644107", "lvl": "debug", "msg": "clean NBD port 61986"}
- {"dt": "2024-12-02T10:50:46.756676", "lvl": "info", "msg": "end: 5 finalize (duration 00:00:02) [common]"}
- {"dt": "2024-12-02T10:50:46.757909", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-12-02T10:50:46.758419", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/initrd"}
- {"dt": "2024-12-02T10:50:46.762228", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/kernel"}
- {"dt": "2024-12-02T10:50:46.765079", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/dtb"}
- {"dt": "2024-12-02T10:50:46.765304", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38058/nbd-deploy-ztgfba97/nbdroot"}
- {"dt": "2024-12-02T10:50:46.991042", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38058"}
- {"dt": "2024-12-02T10:50:46.992558", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38058"}
- {"dt": "2024-12-02T10:50:46.992742", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-12-02T10:50:46.992844", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
