- {"dt": "2025-07-09T13:17:08.537013", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.09.dev0173"}
- {"dt": "2025-07-09T13:17:08.537099", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2025-07-09T13:17:08.537170", "lvl": "info", "msg": "Start time: 2025-07-09 13:17:08.537163+00:00 (UTC)"}
- {"dt": "2025-07-09T13:17:08.538029", "lvl": "debug", "msg": "Validating that file:///tmp/tuxrun-5pnzlwij/startup.nsh exists"}
- {"dt": "2025-07-09T13:17:08.538122", "lvl": "debug", "msg": "Validating that https://storage.tuxboot.com/buildroot/fvp-aemva/FVP_AARCH64_EFI.fd exists"}
- {"dt": "2025-07-09T13:17:08.742942", "lvl": "debug", "msg": "Validating that https://storage.tuxboot.com/buildroot/fvp-aemva/bl1.bin exists"}
- {"dt": "2025-07-09T13:17:08.995720", "lvl": "debug", "msg": "Validating that https://storage.tuxboot.com/buildroot/fvp-aemva/fvp-base-revc.dtb exists"}
- {"dt": "2025-07-09T13:17:09.151261", "lvl": "debug", "msg": "Validating that https://storage.tuxboot.com/buildroot/fvp-aemva/Image exists"}
- {"dt": "2025-07-09T13:17:09.308590", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_20250617_trixie_arm64_rootfs.ext4.xz exists"}
- {"dt": "2025-07-09T13:17:09.308768", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/267/modules.tar.xz exists"}
- {"dt": "2025-07-09T13:17:09.308942", "lvl": "debug", "msg": "Validating that https://storage.tuxboot.com/buildroot/fvp-aemva/fip.bin exists"}
- {"dt": "2025-07-09T13:17:09.468598", "lvl": "info", "msg": "validate duration: 0.93"}
- {"dt": "2025-07-09T13:17:09.468739", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2025-07-09T13:17:09.468868", "lvl": "info", "msg": "start: 1 fvp-deploy (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.468950", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.469113", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7"}
- {"dt": "2025-07-09T13:17:09.469226", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin"}
- {"dt": "2025-07-09T13:17:09.469306", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/tests"}
- {"dt": "2025-07-09T13:17:09.469365", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/results"}
- {"dt": "2025-07-09T13:17:09.469448", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-add-keys"}
- {"dt": "2025-07-09T13:17:09.469555", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-add-sources"}
- {"dt": "2025-07-09T13:17:09.469651", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-background-process-start"}
- {"dt": "2025-07-09T13:17:09.469748", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-background-process-stop"}
- {"dt": "2025-07-09T13:17:09.469842", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-common-functions"}
- {"dt": "2025-07-09T13:17:09.469932", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2025-07-09T13:17:09.470022", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-install-packages"}
- {"dt": "2025-07-09T13:17:09.470111", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-installed-packages"}
- {"dt": "2025-07-09T13:17:09.470197", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-os-build"}
- {"dt": "2025-07-09T13:17:09.470283", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-probe-channel"}
- {"dt": "2025-07-09T13:17:09.470369", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-probe-ip"}
- {"dt": "2025-07-09T13:17:09.470487", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-target-ip"}
- {"dt": "2025-07-09T13:17:09.470609", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-target-mac"}
- {"dt": "2025-07-09T13:17:09.470724", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-target-storage"}
- {"dt": "2025-07-09T13:17:09.470831", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-test-case"}
- {"dt": "2025-07-09T13:17:09.470939", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-test-event"}
- {"dt": "2025-07-09T13:17:09.471042", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-test-feedback"}
- {"dt": "2025-07-09T13:17:09.471143", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-test-raise"}
- {"dt": "2025-07-09T13:17:09.471242", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-test-reference"}
- {"dt": "2025-07-09T13:17:09.471338", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-test-runner"}
- {"dt": "2025-07-09T13:17:09.471434", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-test-set"}
- {"dt": "2025-07-09T13:17:09.471529", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/bin/lava-test-shell"}
- {"dt": "2025-07-09T13:17:09.471609", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/environment"}
- {"dt": "2025-07-09T13:17:09.471677", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2025-07-09T13:17:09.471726", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2025-07-09T13:17:09.471775", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=10.89.16.2"}
- {"dt": "2025-07-09T13:17:09.471853", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.471908", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2025-07-09T13:17:09.471963", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.472034", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.472078", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2025-07-09T13:17:09.472137", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.472206", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.472261", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2025-07-09T13:17:09.472329", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.472385", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2025-07-09T13:17:09.472595", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2025-07-09T13:17:09.472660", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.472715", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.473001", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.473050", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-overlay", "level": "1.1.3.2", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "commands", "path": "command", "from": "inline"}}}
- {"dt": "2025-07-09T13:17:09.473149", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.473483", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.473532", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-install-overlay", "level": "1.1.3.3", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "commands", "path": "command", "from": "inline", "skipped test-install-overlay": "1_1.1.3.1"}}}
- {"dt": "2025-07-09T13:17:09.473632", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.473942", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/0/tests/0_commands test_uuid 1_1.1.3.1"}
- {"dt": "2025-07-09T13:17:09.474111", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.474162", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-runscript-overlay", "level": "1.1.3.4", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "commands", "path": "command", "from": "inline", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/0/tests/0_commands/run.sh"}}}
- {"dt": "2025-07-09T13:17:09.474247", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2025-07-09T13:17:09.474285", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/lava-overlay-ho8u4yy7/lava-1/0 for stage 0"}
- {"dt": "2025-07-09T13:17:09.474338", "lvl": "debug", "msg": "- 0_commands"}
- {"dt": "2025-07-09T13:17:09.474402", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.474457", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.479127", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.479199", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.479260", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.479318", "lvl": "debug", "msg": "start: 1.2.1 file-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:09.479396", "lvl": "info", "msg": "downloading file:///tmp/tuxrun-5pnzlwij/startup.nsh"}
- {"dt": "2025-07-09T13:17:09.479442", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/startup.nsh"}
- {"dt": "2025-07-09T13:17:09.479481", "lvl": "debug", "msg": "total size: 120 (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:09.479521", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-07-09T13:17:09.479606", "lvl": "info", "msg": "0\u202FMB downloaded in 0.00\u202Fs (0.93\u202FMB/s)"}
- {"dt": "2025-07-09T13:17:09.479751", "lvl": "debug", "msg": "end: 1.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.479846", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.2.1", "duration": "0.00", "result": "pass", "extra": {"label": "startup", "size": ! "120", "sha256sum": "a5ece2b65c7e7a975cc419e5a26d82ccf6d3359a19e6227ce24736ab3d5d0cf0"}}}
- {"dt": "2025-07-09T13:17:09.480001", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:09.480062", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-07-09T13:17:09.480115", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:09.480187", "lvl": "info", "msg": "downloading https://storage.tuxboot.com/buildroot/fvp-aemva/FVP_AARCH64_EFI.fd"}
- {"dt": "2025-07-09T13:17:09.480232", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/FVP_AARCH64_EFI.fd"}
- {"dt": "2025-07-09T13:17:09.480271", "lvl": "debug", "msg": "total size: 2621440 (2\u202FMB)"}
- {"dt": "2025-07-09T13:17:09.480310", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-07-09T13:17:10.128815", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:10.312712", "lvl": "debug", "msg": "progress   6\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:10.413963", "lvl": "debug", "msg": "progress  16\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:10.515482", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:10.619462", "lvl": "debug", "msg": "progress  70\u202F% (1\u202FMB)"}
- {"dt": "2025-07-09T13:17:10.695319", "lvl": "info", "msg": "2\u202FMB downloaded in 1.22\u202Fs (2.06\u202FMB/s)"}
- {"dt": "2025-07-09T13:17:10.695475", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2025-07-09T13:17:10.695533", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.3.1", "duration": "1.22", "result": "pass", "extra": {"label": "uefi", "size": ! "2621440", "sha256sum": "696d4cf998e91d68b8d45fe35f82a2f57cefea941f031f68a16cf99b314337f6"}}}
- {"dt": "2025-07-09T13:17:10.695633", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:01) [common]"}
- {"dt": "2025-07-09T13:17:10.695689", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:04:59) [common]"}
- {"dt": "2025-07-09T13:17:10.695743", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:10.695836", "lvl": "info", "msg": "downloading https://storage.tuxboot.com/buildroot/fvp-aemva/bl1.bin"}
- {"dt": "2025-07-09T13:17:10.695880", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/bl1.bin"}
- {"dt": "2025-07-09T13:17:10.695918", "lvl": "debug", "msg": "total size: 31181 (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:10.695955", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-07-09T13:17:10.974481", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:10.974701", "lvl": "info", "msg": "0\u202FMB downloaded in 0.28\u202Fs (0.11\u202FMB/s)"}
- {"dt": "2025-07-09T13:17:10.974851", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:10.974928", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.4.1", "duration": "0.28", "result": "pass", "extra": {"label": "bl1", "size": ! "31181", "sha256sum": "063732a1ba119b62d25a83afd5e0ba3ba2ef384749189bfec84bef5d87fe034c"}}}
- {"dt": "2025-07-09T13:17:10.975061", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:10.975203", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:04:58) [common]"}
- {"dt": "2025-07-09T13:17:10.975292", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:10.975427", "lvl": "info", "msg": "downloading https://storage.tuxboot.com/buildroot/fvp-aemva/fvp-base-revc.dtb"}
- {"dt": "2025-07-09T13:17:10.975509", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/fvp-base-revc.dtb"}
- {"dt": "2025-07-09T13:17:10.975581", "lvl": "debug", "msg": "total size: 10350 (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:10.975651", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-07-09T13:17:11.273570", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:11.273782", "lvl": "info", "msg": "0\u202FMB downloaded in 0.30\u202Fs (0.03\u202FMB/s)"}
- {"dt": "2025-07-09T13:17:11.273931", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:11.274028", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.5.1", "duration": "0.30", "result": "pass", "extra": {"label": "dtb", "size": ! "10350", "sha256sum": "e7b02cf2cae34c6f2fa8cf4efc7678067f8b5cb06bd5c26616cd4d7630464f7b"}}}
- {"dt": "2025-07-09T13:17:11.274242", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:11.274336", "lvl": "debug", "msg": "start: 1.6 download-retry (timeout 00:04:58) [common]"}
- {"dt": "2025-07-09T13:17:11.274439", "lvl": "debug", "msg": "start: 1.6.1 http-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:11.274612", "lvl": "info", "msg": "downloading https://storage.tuxboot.com/buildroot/fvp-aemva/Image"}
- {"dt": "2025-07-09T13:17:11.274687", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/Image"}
- {"dt": "2025-07-09T13:17:11.274730", "lvl": "debug", "msg": "total size: 12235264 (11\u202FMB)"}
- {"dt": "2025-07-09T13:17:11.274789", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-07-09T13:17:11.624159", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:11.790262", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:11.891361", "lvl": "debug", "msg": "progress  19\u202F% (2\u202FMB)"}
- {"dt": "2025-07-09T13:17:11.994564", "lvl": "debug", "msg": "progress  27\u202F% (3\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.095954", "lvl": "debug", "msg": "progress  34\u202F% (4\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.199786", "lvl": "debug", "msg": "progress  42\u202F% (4\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.300183", "lvl": "debug", "msg": "progress  50\u202F% (5\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.402155", "lvl": "debug", "msg": "progress  59\u202F% (6\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.502867", "lvl": "debug", "msg": "progress  67\u202F% (7\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.605354", "lvl": "debug", "msg": "progress  76\u202F% (8\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.705624", "lvl": "debug", "msg": "progress  83\u202F% (9\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.806855", "lvl": "debug", "msg": "progress  91\u202F% (10\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.908763", "lvl": "debug", "msg": "progress  98\u202F% (11\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.922657", "lvl": "info", "msg": "11\u202FMB downloaded in 1.65\u202Fs (7.08\u202FMB/s)"}
- {"dt": "2025-07-09T13:17:12.922800", "lvl": "debug", "msg": "end: 1.6.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2025-07-09T13:17:12.922874", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.6.1", "duration": "1.65", "result": "pass", "extra": {"label": "kernel", "size": ! "12235264", "sha256sum": "b74743c5e89e1cea0f73368d24ae0ae85c5204ff84be3b5e9610417417d2f235"}}}
- {"dt": "2025-07-09T13:17:12.923011", "lvl": "debug", "msg": "end: 1.6 download-retry (duration 00:00:02) [common]"}
- {"dt": "2025-07-09T13:17:12.923089", "lvl": "debug", "msg": "start: 1.7 download-retry (timeout 00:04:57) [common]"}
- {"dt": "2025-07-09T13:17:12.923167", "lvl": "debug", "msg": "start: 1.7.1 file-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:12.923281", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_20250617_trixie_arm64_rootfs.ext4.xz"}
- {"dt": "2025-07-09T13:17:12.923344", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/https___storage.tuxboot.com_debian_20250617_trixie_arm64_rootfs.ext4"}
- {"dt": "2025-07-09T13:17:12.923397", "lvl": "debug", "msg": "total size: 150840416 (143\u202FMB)"}
- {"dt": "2025-07-09T13:17:12.923451", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-07-09T13:17:13.024328", "lvl": "debug", "msg": "progress   1\u202F% (2\u202FMB)"}
- {"dt": "2025-07-09T13:17:13.175918", "lvl": "debug", "msg": "progress   6\u202F% (8\u202FMB)"}
- {"dt": "2025-07-09T13:17:13.375696", "lvl": "debug", "msg": "progress  11\u202F% (15\u202FMB)"}
- {"dt": "2025-07-09T13:17:13.567008", "lvl": "debug", "msg": "progress  16\u202F% (23\u202FMB)"}
- {"dt": "2025-07-09T13:17:13.760140", "lvl": "debug", "msg": "progress  21\u202F% (30\u202FMB)"}
- {"dt": "2025-07-09T13:17:13.966712", "lvl": "debug", "msg": "progress  26\u202F% (37\u202FMB)"}
- {"dt": "2025-07-09T13:17:14.139152", "lvl": "debug", "msg": "progress  31\u202F% (44\u202FMB)"}
- {"dt": "2025-07-09T13:17:14.328519", "lvl": "debug", "msg": "progress  36\u202F% (51\u202FMB)"}
- {"dt": "2025-07-09T13:17:14.538466", "lvl": "debug", "msg": "progress  41\u202F% (59\u202FMB)"}
- {"dt": "2025-07-09T13:17:14.740560", "lvl": "debug", "msg": "progress  46\u202F% (66\u202FMB)"}
- {"dt": "2025-07-09T13:17:14.944055", "lvl": "debug", "msg": "progress  51\u202F% (73\u202FMB)"}
- {"dt": "2025-07-09T13:17:15.159583", "lvl": "debug", "msg": "progress  56\u202F% (80\u202FMB)"}
- {"dt": "2025-07-09T13:17:15.359387", "lvl": "debug", "msg": "progress  61\u202F% (87\u202FMB)"}
- {"dt": "2025-07-09T13:17:15.536713", "lvl": "debug", "msg": "progress  66\u202F% (94\u202FMB)"}
- {"dt": "2025-07-09T13:17:15.730831", "lvl": "debug", "msg": "progress  71\u202F% (102\u202FMB)"}
- {"dt": "2025-07-09T13:17:15.927029", "lvl": "debug", "msg": "progress  76\u202F% (109\u202FMB)"}
- {"dt": "2025-07-09T13:17:16.116544", "lvl": "debug", "msg": "progress  81\u202F% (116\u202FMB)"}
- {"dt": "2025-07-09T13:17:16.313044", "lvl": "debug", "msg": "progress  86\u202F% (123\u202FMB)"}
- {"dt": "2025-07-09T13:17:16.488261", "lvl": "debug", "msg": "progress  91\u202F% (130\u202FMB)"}
- {"dt": "2025-07-09T13:17:16.618141", "lvl": "debug", "msg": "progress  96\u202F% (138\u202FMB)"}
- {"dt": "2025-07-09T13:17:19.590465", "lvl": "info", "msg": "143\u202FMB downloaded in 6.67\u202Fs (21.58\u202FMB/s)"}
- {"dt": "2025-07-09T13:17:19.590688", "lvl": "debug", "msg": "end: 1.7.1 file-download (duration 00:00:07) [common]"}
- {"dt": "2025-07-09T13:17:19.590769", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.7.1", "duration": "6.67", "result": "pass", "extra": {"label": "rootfs", "size": ! "150840416", "sha256sum": "1eaaae772692e22cefec5345d642e254407cec1431dd51a20007af2a1819b610"}}}
- {"dt": "2025-07-09T13:17:19.590947", "lvl": "debug", "msg": "start: 1.7.2 download-retry (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:19.591032", "lvl": "debug", "msg": "start: 1.7.2.1 file-download (timeout 00:00:33) [common]"}
- {"dt": "2025-07-09T13:17:19.591170", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/267/modules.tar.xz"}
- {"dt": "2025-07-09T13:17:19.591246", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/rootfs.modules/modules.tar"}
- {"dt": "2025-07-09T13:17:19.591305", "lvl": "debug", "msg": "total size: 12252624 (11\u202FMB)"}
- {"dt": "2025-07-09T13:17:19.591367", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-07-09T13:17:19.692196", "lvl": "debug", "msg": "progress  28\u202F% (3\u202FMB)"}
- {"dt": "2025-07-09T13:17:19.792794", "lvl": "debug", "msg": "progress  58\u202F% (6\u202FMB)"}
- {"dt": "2025-07-09T13:17:19.892859", "lvl": "debug", "msg": "progress  87\u202F% (10\u202FMB)"}
- {"dt": "2025-07-09T13:17:19.943238", "lvl": "info", "msg": "11\u202FMB downloaded in 0.35\u202Fs (33.20\u202FMB/s)"}
- {"dt": "2025-07-09T13:17:19.943405", "lvl": "debug", "msg": "end: 1.7.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:19.943487", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.7.2.1", "duration": "0.35", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "12252624", "sha256sum": "971046e9720ddefa88556b1fd783853be241e5ab064a392d2a84177f795d0381"}}}
- {"dt": "2025-07-09T13:17:19.943675", "lvl": "debug", "msg": "end: 1.7.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:19.943770", "lvl": "debug", "msg": "start: 1.7.3 append-overlays (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:19.943848", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/https___storage.tuxboot.com_debian_20250617_trixie_arm64_rootfs.ext4'"}
- {"dt": "2025-07-09T13:17:26.433401", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2025-07-09T13:17:26.434252", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/compress-overlay-21lhy18j/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2025-07-09T13:17:26.501353", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/rootfs.modules/modules.tar' to '/usr/'"}
- {"dt": "2025-07-09T13:17:29.862058", "lvl": "debug", "msg": "end: 1.7.3 append-overlays (duration 00:00:10) [common]"}
- {"dt": "2025-07-09T13:17:29.862467", "lvl": "debug", "msg": "end: 1.7 download-retry (duration 00:00:17) [common]"}
- {"dt": "2025-07-09T13:17:29.862573", "lvl": "debug", "msg": "start: 1.8 download-retry (timeout 00:04:40) [common]"}
- {"dt": "2025-07-09T13:17:29.862674", "lvl": "debug", "msg": "start: 1.8.1 http-download (timeout 00:01:40) [common]"}
- {"dt": "2025-07-09T13:17:29.862950", "lvl": "info", "msg": "downloading https://storage.tuxboot.com/buildroot/fvp-aemva/fip.bin"}
- {"dt": "2025-07-09T13:17:29.863018", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/fip.bin"}
- {"dt": "2025-07-09T13:17:29.863070", "lvl": "debug", "msg": "total size: 2738964 (2\u202FMB)"}
- {"dt": "2025-07-09T13:17:29.863118", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-07-09T13:17:30.583839", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:30.772202", "lvl": "debug", "msg": "progress   7\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:30.957369", "lvl": "debug", "msg": "progress  13\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:31.142334", "lvl": "debug", "msg": "progress  19\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:31.324003", "lvl": "debug", "msg": "progress  25\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:31.457523", "lvl": "debug", "msg": "progress  31\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:31.617217", "lvl": "debug", "msg": "progress  37\u202F% (0\u202FMB)"}
- {"dt": "2025-07-09T13:17:31.789310", "lvl": "debug", "msg": "progress  43\u202F% (1\u202FMB)"}
- {"dt": "2025-07-09T13:17:31.923212", "lvl": "debug", "msg": "progress  49\u202F% (1\u202FMB)"}
- {"dt": "2025-07-09T13:17:32.073322", "lvl": "debug", "msg": "progress  55\u202F% (1\u202FMB)"}
- {"dt": "2025-07-09T13:17:32.254480", "lvl": "debug", "msg": "progress  61\u202F% (1\u202FMB)"}
- {"dt": "2025-07-09T13:17:32.389485", "lvl": "debug", "msg": "progress  68\u202F% (1\u202FMB)"}
- {"dt": "2025-07-09T13:17:32.537164", "lvl": "debug", "msg": "progress  74\u202F% (1\u202FMB)"}
- {"dt": "2025-07-09T13:17:32.667275", "lvl": "debug", "msg": "progress  80\u202F% (2\u202FMB)"}
- {"dt": "2025-07-09T13:17:32.813865", "lvl": "debug", "msg": "progress  86\u202F% (2\u202FMB)"}
- {"dt": "2025-07-09T13:17:32.947070", "lvl": "debug", "msg": "progress  93\u202F% (2\u202FMB)"}
- {"dt": "2025-07-09T13:17:33.091086", "lvl": "debug", "msg": "progress  98\u202F% (2\u202FMB)"}
- {"dt": "2025-07-09T13:17:33.098160", "lvl": "info", "msg": "2\u202FMB downloaded in 3.24\u202Fs (0.81\u202FMB/s)"}
- {"dt": "2025-07-09T13:17:33.098332", "lvl": "debug", "msg": "end: 1.8.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2025-07-09T13:17:33.098417", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "http-download", "level": "1.8.1", "duration": "3.24", "result": "pass", "extra": {"label": "fip", "size": ! "2738964", "sha256sum": "520afbd0ffd585c23090d42fc24cc8c48c84ae568dc3852077b11d616509e2f5"}}}
- {"dt": "2025-07-09T13:17:33.098569", "lvl": "debug", "msg": "end: 1.8 download-retry (duration 00:00:03) [common]"}
- {"dt": "2025-07-09T13:17:33.098658", "lvl": "info", "msg": "end: 1 fvp-deploy (duration 00:00:24) [common]"}
- {"dt": "2025-07-09T13:17:33.098765", "lvl": "info", "msg": "start: 2 boot-fvp (timeout 00:30:00) [common]"}
- {"dt": "2025-07-09T13:17:33.098837", "lvl": "debug", "msg": "start: 2.1 boot-fvp-main (timeout 00:30:00) [common]"}
- {"dt": "2025-07-09T13:17:33.098900", "lvl": "debug", "msg": "start: 2.1.1 check-fvp-version (timeout 00:30:00) [common]"}
- {"dt": "2025-07-09T13:17:33.098953", "lvl": "debug", "msg": "Calling: ['docker', 'image', 'inspect', '--format', 'Image fvp:aemva-11.28.23 exists locally', 'fvp:aemva-11.28.23']"}
- {"dt": "2025-07-09T13:17:33.172829", "lvl": "debug", "msg": ">> Image fvp:aemva-11.28.23 exists locally"}
- {"dt": "2025-07-09T13:17:33.174641", "lvl": "debug", "msg": "Returned 0 in 0 seconds"}
- {"dt": "2025-07-09T13:17:33.174728", "lvl": "debug", "msg": "docker run --rm fvp:aemva-11.28.23 /opt/model/FVP_AEMvA/models/Linux64_GCC-9.3/FVP_Base_RevC-2xAEMvA --version"}
- {"dt": "2025-07-09T13:17:33.568051", "lvl": "debug", "msg": "output: "}
- {"dt": "2025-07-09T13:17:33.568210", "lvl": "debug", "msg": "output: Fast Models [11.28.23 (Feb 17 2025)]"}
- {"dt": "2025-07-09T13:17:33.568288", "lvl": "debug", "msg": "output: Copyright 2000-2025 ARM Limited."}
- {"dt": "2025-07-09T13:17:33.568373", "lvl": "debug", "msg": "output: All Rights Reserved."}
- {"dt": "2025-07-09T13:17:33.568468", "lvl": "debug", "msg": "output: "}
- {"dt": "2025-07-09T13:17:33.568528", "lvl": "debug", "msg": "output: "}
- {"dt": "2025-07-09T13:17:33.568582", "lvl": "debug", "msg": "output: Info: /OSCI/SystemC: Simulation stopped by user."}
- {"dt": "2025-07-09T13:17:33.568639", "lvl": "debug", "msg": "output: "}
- {"dt": "2025-07-09T13:17:33.568828", "lvl": "results", "msg": {"definition": "lava", "case": "fvp-version", "level": "2.1.1", "extra": {"fvp-version": "\nFast Models [11.28.23 (Feb 17 2025)]\nCopyright 2000-2025 ARM Limited.\nAll Rights Reserved.\n\n\nInfo: /OSCI/SystemC: Simulation stopped by user.\n"}, "result": "pass", "duration": "0.47"}}
- {"dt": "2025-07-09T13:17:33.568977", "lvl": "debug", "msg": "end: 2.1.1 check-fvp-version (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:33.569045", "lvl": "debug", "msg": "start: 2.1.2 run-fvp (timeout 00:30:00) [common]"}
- {"dt": "2025-07-09T13:17:33.569100", "lvl": "debug", "msg": "Download action namespace keys are: dict_keys(['startup', 'uefi', 'bl1', 'dtb', 'kernel', 'rootfs', 'rootfs.modules', 'fip', 'file'])"}
- {"dt": "2025-07-09T13:17:33.569185", "lvl": "warning", "msg": "Empty value for action='download-action' label='file' key='file'"}
- {"dt": "2025-07-09T13:17:33.569230", "lvl": "warning", "msg": "'license_variable' or 'ubl_license' not set, model may not function."}
- {"dt": "2025-07-09T13:17:33.569431", "lvl": "debug", "msg": "FVP model arguments:"}
- {"dt": "2025-07-09T13:17:33.569480", "lvl": "debug", "msg": "/opt/model/FVP_AEMvA/models/Linux64_GCC-9.3/FVP_Base_RevC-2xAEMvA\n--stat \\\n-C bp.dram_size=4 \\\n-C bp.flashloader0.fname='/tuxrun-5pnzlwij-lava-1/fip.bin' \\\n-C bp.flashloader1.fname='/tuxrun-5pnzlwij-lava-1/FVP_AARCH64_EFI.fd' \\\n-C bp.hostbridge.userNetPorts=8022=22 \\\n-C bp.hostbridge.userNetworking=1 \\\n-C bp.refcounter.non_arch_start_at_default=1 \\\n-C bp.refcounter.use_real_time=0 \\\n-C bp.secure_memory=1 \\\n-C bp.secureflashloader.fname='/tuxrun-5pnzlwij-lava-1/bl1.bin' \\\n-C bp.smsc_91c111.enabled=1 \\\n-C bp.terminal_0.mode=telnet \\\n-C bp.terminal_0.start_telnet=0 \\\n-C bp.terminal_1.mode=raw \\\n-C bp.terminal_1.start_telnet=0 \\\n-C bp.terminal_2.mode=raw \\\n-C bp.terminal_2.start_telnet=0 \\\n-C bp.terminal_3.mode=raw \\\n-C bp.terminal_3.start_telnet=0 \\\n-C bp.ve_sysregs.exit_on_shutdown=1 \\\n-C bp.virtio_rng.enabled=1 \\\n-C bp.virtioblockdevice.image_path='/tuxrun-5pnzlwij-lava-1/https___storage.tuxboot.com_debian_20250617_trixie_arm64_rootfs.ext4' \\\n-C bp.virtiop9device.root_path= \\\n-C bp.vis.disable_visualisation=1 \\\n-C cache_state_modelled=0 \\\n-C cluster0.NUM_CORES=4 \\\n-C cluster0.PA_SIZE=48 \\\n-C cluster0.check_memory_attributes=0 \\\n-C cluster0.clear_reg_top_eret=2 \\\n-C cluster0.cpu0.semihosting-cwd=/tuxrun-5pnzlwij-lava-1 \\\n-C cluster0.ecv_support_level=2 \\\n-C cluster0.enhanced_pac2_level=3 \\\n-C cluster0.gicv3.cpuintf-mmap-access-level=2 \\\n-C cluster0.gicv3.without-DS-support=1 \\\n-C cluster0.gicv4.mask-virtual-interrupt=1 \\\n-C cluster0.has_16k_granule=1 \\\n-C cluster0.has_amu=1 \\\n-C cluster0.has_arm_v8-1=1 \\\n-C cluster0.has_arm_v8-2=1 \\\n-C cluster0.has_arm_v8-3=1 \\\n-C cluster0.has_arm_v8-4=1 \\\n-C cluster0.has_arm_v8-5=1 \\\n-C cluster0.has_arm_v8-6=1 \\\n-C cluster0.has_arm_v8-7=1 \\\n-C cluster0.has_arm_v8-8=1 \\\n-C cluster0.has_arm_v8-9=1 \\\n-C cluster0.has_arm_v9-0=1 \\\n-C cluster0.has_arm_v9-1=1 \\\n-C cluster0.has_arm_v9-2=1 \\\n-C cluster0.has_arm_v9-3=1 \\\n-C cluster0.has_arm_v9-4=1 \\\n-C cluster0.has_arm_v9-5=1 \\\n-C cluster0.has_branch_target_exception=1 \\\n-C cluster0.has_brbe=1 \\\n-C cluster0.has_brbe_v1p1=1 \\\n-C cluster0.has_const_pac=1 \\\n-C cluster0.has_gcs=1 \\\n-C cluster0.has_hpmn0=1 \\\n-C cluster0.has_large_system_ext=1 \\\n-C cluster0.has_large_va=1 \\\n-C cluster0.has_permission_indirection_s1=1 \\\n-C cluster0.has_permission_indirection_s2=1 \\\n-C cluster0.has_permission_overlay_s1=1 \\\n-C cluster0.has_permission_overlay_s2=1 \\\n-C cluster0.has_rndr=1 \\\n-C cluster0.has_sve=1 \\\n-C cluster0.max_32bit_el=0 \\\n-C cluster0.pmb_idr_external_abort=1 \\\n-C cluster0.stage12_tlb_size=1024 \\\n-C cluster0.sve.has_sme2=1 \\\n-C cluster0.sve.has_sme=1 \\\n-C cluster0.sve.has_sve2=1 \\\n-C cluster1.NUM_CORES=4 \\\n-C cluster1.PA_SIZE=48 \\\n-C cluster1.check_memory_attributes=0 \\\n-C cluster1.clear_reg_top_eret=2 \\\n-C cluster1.ecv_support_level=2 \\\n-C cluster1.enhanced_pac2_level=3 \\\n-C cluster1.gicv3.cpuintf-mmap-access-level=2 \\\n-C cluster1.gicv3.without-DS-support=1 \\\n-C cluster1.gicv4.mask-virtual-interrupt=1 \\\n-C cluster1.has_16k_granule=1 \\\n-C cluster1.has_amu=1 \\\n-C cluster1.has_arm_v8-1=1 \\\n-C cluster1.has_arm_v8-2=1 \\\n-C cluster1.has_arm_v8-3=1 \\\n-C cluster1.has_arm_v8-4=1 \\\n-C cluster1.has_arm_v8-5=1 \\\n-C cluster1.has_arm_v8-6=1 \\\n-C cluster1.has_arm_v8-7=1 \\\n-C cluster1.has_arm_v8-8=1 \\\n-C cluster1.has_arm_v8-9=1 \\\n-C cluster1.has_arm_v9-0=1 \\\n-C cluster1.has_arm_v9-1=1 \\\n-C cluster1.has_arm_v9-2=1 \\\n-C cluster1.has_arm_v9-3=1 \\\n-C cluster1.has_arm_v9-4=1 \\\n-C cluster1.has_arm_v9-5=1 \\\n-C cluster1.has_branch_target_exception=1 \\\n-C cluster1.has_brbe=1 \\\n-C cluster1.has_brbe_v1p1=1 \\\n-C cluster1.has_const_pac=1 \\\n-C cluster1.has_gcs=1 \\\n-C cluster1.has_hpmn0=1 \\\n-C cluster1.has_large_system_ext=1 \\\n-C cluster1.has_large_va=1 \\\n-C cluster1.has_permission_indirection_s1=1 \\\n-C cluster1.has_permission_indirection_s2=1 \\\n-C cluster1.has_permission_overlay_s1=1 \\\n-C cluster1.has_permission_overlay_s2=1 \\\n-C cluster1.has_rndr=1 \\\n-C cluster1.has_sve=1 \\\n-C cluster1.max_32bit_el=0 \\\n-C cluster1.pmb_idr_external_abort=1 \\\n-C cluster1.stage12_tlb_size=1024 \\\n-C cluster1.sve.has_sme2=1 \\\n-C cluster1.sve.has_sme=1 \\\n-C cluster1.sve.has_sve2=1 \\\n-C gic_distributor.has_nmi=1 \\\n-C pci.pci_smmuv3.mmu.SMMU_AIDR=2 \\\n-C pci.pci_smmuv3.mmu.SMMU_IDR0=135263935 \\\n-C pci.pci_smmuv3.mmu.SMMU_IDR1=216481056 \\\n-C pci.pci_smmuv3.mmu.SMMU_IDR3=5908 \\\n-C pci.pci_smmuv3.mmu.SMMU_IDR5=4294902901 \\\n-C pci.pci_smmuv3.mmu.SMMU_S_IDR1=2684354562 \\\n-C pci.pci_smmuv3.mmu.SMMU_S_IDR2=0 \\\n-C pci.pci_smmuv3.mmu.SMMU_S_IDR3=0 \\\n-C pctl.startup=0.0.0.0"}
- {"dt": "2025-07-09T13:17:33.569606", "lvl": "debug", "msg": "Boot command: lava-outerr docker run --rm --interactive --tty --hostname lava --name tuxrun-5pnzlwij-lava-1 --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/startup.nsh:/tuxrun-5pnzlwij-lava-1/startup.nsh --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/FVP_AARCH64_EFI.fd:/tuxrun-5pnzlwij-lava-1/FVP_AARCH64_EFI.fd --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/bl1.bin:/tuxrun-5pnzlwij-lava-1/bl1.bin --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/fvp-base-revc.dtb:/tuxrun-5pnzlwij-lava-1/fvp-base-revc.dtb --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/Image:/tuxrun-5pnzlwij-lava-1/Image --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/https___storage.tuxboot.com_debian_20250617_trixie_arm64_rootfs.ext4:/tuxrun-5pnzlwij-lava-1/https___storage.tuxboot.com_debian_20250617_trixie_arm64_rootfs.ext4 --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/rootfs.modules/modules.tar:/tuxrun-5pnzlwij-lava-1/modules.tar --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/fip.bin:/tuxrun-5pnzlwij-lava-1/fip.bin --volume /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/run-fvp-_6vasln5/script.sh:/root/script.sh fvp:aemva-11.28.23 sh /root/script.sh"}
- {"dt": "2025-07-09T13:17:33.573551", "lvl": "debug", "msg": "Setting prompt string to ['(?P<NAME>terminal_1): Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_2): Listening for serial connection on port (?P<PORT>\\\\d+)', 'terminal_0: Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_3): Listening for serial connection on port (?P<PORT>\\\\d+)']"}
- {"dt": "2025-07-09T13:17:33.573829", "lvl": "debug", "msg": "run-fvp: Wait for prompt ['(?P<NAME>terminal_1): Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_2): Listening for serial connection on port (?P<PORT>\\\\d+)', 'terminal_0: Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_3): Listening for serial connection on port (?P<PORT>\\\\d+)'] (timeout 00:30:00)"}
- {"dt": "2025-07-09T13:17:33.754950", "lvl": "target", "msg": "Starting the FVP model"}
- {"dt": "2025-07-09T13:17:33.860366", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:33.860579", "lvl": "target", "msg": "Info: FVP_Base_RevC_2xAEMvA: terminal_0: Listening for serial connection on port 5000"}
- {"dt": "2025-07-09T13:17:33.860657", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:33.860719", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:33.860778", "lvl": "target", "msg": "Info: FVP_Base_RevC_2xAEMvA: terminal_1: Listening for serial connection on port 5001"}
- {"dt": "2025-07-09T13:17:33.860852", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:33.861115", "lvl": "debug", "msg": "Connection group(0) terminal_0: Listening for serial connection on port 5000"}
- {"dt": "2025-07-09T13:17:33.861381", "lvl": "info", "msg": "Found FVP port 5000"}
- {"dt": "2025-07-09T13:17:33.861471", "lvl": "debug", "msg": "Setting prompt string to ['(?P<NAME>terminal_1): Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_2): Listening for serial connection on port (?P<PORT>\\\\d+)', 'terminal_0: Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_3): Listening for serial connection on port (?P<PORT>\\\\d+)']"}
- {"dt": "2025-07-09T13:17:33.861558", "lvl": "debug", "msg": "run-fvp: Wait for prompt ['(?P<NAME>terminal_1): Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_2): Listening for serial connection on port (?P<PORT>\\\\d+)', 'terminal_0: Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_3): Listening for serial connection on port (?P<PORT>\\\\d+)'] (timeout 00:30:00)"}
- {"dt": "2025-07-09T13:17:33.861659", "lvl": "debug", "msg": "Connection group(0) terminal_1: Listening for serial connection on port 5001"}
- {"dt": "2025-07-09T13:17:33.861735", "lvl": "info", "msg": "Found secondary FVP port 5001"}
- {"dt": "2025-07-09T13:17:33.861790", "lvl": "debug", "msg": "Setting prompt string to ['(?P<NAME>terminal_1): Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_2): Listening for serial connection on port (?P<PORT>\\\\d+)', 'terminal_0: Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_3): Listening for serial connection on port (?P<PORT>\\\\d+)']"}
- {"dt": "2025-07-09T13:17:33.861864", "lvl": "debug", "msg": "run-fvp: Wait for prompt ['(?P<NAME>terminal_1): Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_2): Listening for serial connection on port (?P<PORT>\\\\d+)', 'terminal_0: Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_3): Listening for serial connection on port (?P<PORT>\\\\d+)'] (timeout 00:30:00)"}
- {"dt": "2025-07-09T13:17:33.861957", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:33.862027", "lvl": "target", "msg": "Info: FVP_Base_RevC_2xAEMvA: terminal_2: Listening for serial connection on port 5002"}
- {"dt": "2025-07-09T13:17:33.862076", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:33.862140", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:33.862206", "lvl": "target", "msg": "Info: FVP_Base_RevC_2xAEMvA: terminal_3: Listening for serial connection on port 5003"}
- {"dt": "2025-07-09T13:17:33.862257", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:33.862508", "lvl": "debug", "msg": "Connection group(0) terminal_2: Listening for serial connection on port 5002"}
- {"dt": "2025-07-09T13:17:33.862562", "lvl": "info", "msg": "Found secondary FVP port 5002"}
- {"dt": "2025-07-09T13:17:33.862608", "lvl": "debug", "msg": "Setting prompt string to ['(?P<NAME>terminal_1): Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_2): Listening for serial connection on port (?P<PORT>\\\\d+)', 'terminal_0: Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_3): Listening for serial connection on port (?P<PORT>\\\\d+)']"}
- {"dt": "2025-07-09T13:17:33.862678", "lvl": "debug", "msg": "run-fvp: Wait for prompt ['(?P<NAME>terminal_1): Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_2): Listening for serial connection on port (?P<PORT>\\\\d+)', 'terminal_0: Listening for serial connection on port (?P<PORT>\\\\d+)', '(?P<NAME>terminal_3): Listening for serial connection on port (?P<PORT>\\\\d+)'] (timeout 00:30:00)"}
- {"dt": "2025-07-09T13:17:33.862763", "lvl": "debug", "msg": "Connection group(0) terminal_3: Listening for serial connection on port 5003"}
- {"dt": "2025-07-09T13:17:33.862835", "lvl": "info", "msg": "Found secondary FVP port 5003"}
- {"dt": "2025-07-09T13:17:33.862938", "lvl": "debug", "msg": "end: 2.1.2 run-fvp (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:33.863009", "lvl": "debug", "msg": "start: 2.1.3 fvp-serial-connect (timeout 00:29:59) [common]"}
- {"dt": "2025-07-09T13:17:33.863081", "lvl": "debug", "msg": "Feedback command: lava-outerr docker exec --interactive --tty tuxrun-5pnzlwij-lava-1 telnet localhost 5001"}
- {"dt": "2025-07-09T13:17:33.866775", "lvl": "debug", "msg": "Feedback command: lava-outerr docker exec --interactive --tty tuxrun-5pnzlwij-lava-1 telnet localhost 5002"}
- {"dt": "2025-07-09T13:17:33.870539", "lvl": "debug", "msg": "Feedback command: lava-outerr docker exec --interactive --tty tuxrun-5pnzlwij-lava-1 telnet localhost 5003"}
- {"dt": "2025-07-09T13:17:33.874550", "lvl": "debug", "msg": "Connect command: lava-outerr docker exec --interactive --tty tuxrun-5pnzlwij-lava-1 telnet localhost 5000"}
- {"dt": "2025-07-09T13:17:33.878106", "lvl": "debug", "msg": "end: 2.1.3 fvp-serial-connect (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:33.878343", "lvl": "debug", "msg": "start: 2.1.4 read-feedback (timeout 00:29:59) [common]"}
- {"dt": "2025-07-09T13:17:34.071328", "lvl": "feedback", "msg": "Trying ::1...", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:17:34.071505", "lvl": "feedback", "msg": "Trying 127.0.0.1...", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:17:34.071577", "lvl": "feedback", "msg": "Connected to localhost.", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:17:34.071919", "lvl": "feedback", "msg": "Escape character is '^]'.", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:17:34.072315", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:17:34.072452", "lvl": "feedback", "msg": "Trying ::1...", "ns": "terminal_2"}
- {"dt": "2025-07-09T13:17:34.072544", "lvl": "feedback", "msg": "Trying 127.0.0.1...", "ns": "terminal_2"}
- {"dt": "2025-07-09T13:17:34.072627", "lvl": "feedback", "msg": "Connected to localhost.", "ns": "terminal_2"}
- {"dt": "2025-07-09T13:17:34.072713", "lvl": "feedback", "msg": "Escape character is '^]'.", "ns": "terminal_2"}
- {"dt": "2025-07-09T13:17:34.072977", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_2' for up to 1s"}
- {"dt": "2025-07-09T13:17:34.073100", "lvl": "feedback", "msg": "Trying ::1...", "ns": "terminal_3"}
- {"dt": "2025-07-09T13:17:34.073160", "lvl": "feedback", "msg": "Trying 127.0.0.1...", "ns": "terminal_3"}
- {"dt": "2025-07-09T13:17:34.073210", "lvl": "feedback", "msg": "Connected to localhost.", "ns": "terminal_3"}
- {"dt": "2025-07-09T13:17:34.073256", "lvl": "feedback", "msg": "Escape character is '^]'.", "ns": "terminal_3"}
- {"dt": "2025-07-09T13:17:34.073487", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_3' for up to 1s"}
- {"dt": "2025-07-09T13:17:34.073601", "lvl": "debug", "msg": "end: 2.1.4 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:17:34.073673", "lvl": "debug", "msg": "end: 2.1 boot-fvp-main (duration 00:00:01) [common]"}
- {"dt": "2025-07-09T13:17:34.073744", "lvl": "debug", "msg": "start: 2.2 read-feedback (timeout 00:29:59) [common]"}
- {"dt": "2025-07-09T13:17:35.016060", "lvl": "feedback", "msg": "UEFI firmware (version  built at 13:03:53 on Mar 21 2025)", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:17:35.016345", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:17:37.018673", "lvl": "debug", "msg": "end: 2.2 read-feedback (duration 00:00:03) [common]"}
- {"dt": "2025-07-09T13:17:37.018802", "lvl": "debug", "msg": "start: 2.3 auto-login-action (timeout 00:29:56) [common]"}
- {"dt": "2025-07-09T13:17:37.018879", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2025-07-09T13:17:37.018956", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:30:00)"}
- {"dt": "2025-07-09T13:17:37.019214", "lvl": "target", "msg": "Trying ::1..."}
- {"dt": "2025-07-09T13:17:37.019396", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2025-07-09T13:17:37.019529", "lvl": "target", "msg": "Connected to localhost."}
- {"dt": "2025-07-09T13:17:37.019584", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2025-07-09T13:17:37.019635", "lvl": "target", "msg": "NOTICE:  Booting Trusted Firmware"}
- {"dt": "2025-07-09T13:17:37.019688", "lvl": "target", "msg": "NOTICE:  BL1: v2.12.0(release):v2.12.0"}
- {"dt": "2025-07-09T13:17:37.019740", "lvl": "target", "msg": "NOTICE:  BL1: Built : 13:04:04, Mar 21 2025"}
- {"dt": "2025-07-09T13:17:37.019788", "lvl": "target", "msg": "INFO:    BL1: RAM 0x4034000 - 0x403b000"}
- {"dt": "2025-07-09T13:17:37.019829", "lvl": "target", "msg": "INFO:    Loading image id=32 at address 0x4001010"}
- {"dt": "2025-07-09T13:17:37.019884", "lvl": "target", "msg": "INFO:    Image id=32 loaded: 0x4001010 - 0x4001213"}
- {"dt": "2025-07-09T13:17:37.019923", "lvl": "target", "msg": "INFO:    FCONF: Config file with image ID:32 loaded at address = 0x4001010"}
- {"dt": "2025-07-09T13:17:37.019959", "lvl": "target", "msg": "INFO:    Loading image id=24 at address 0x4001300"}
- {"dt": "2025-07-09T13:17:37.019994", "lvl": "target", "msg": "INFO:    Image id=24 loaded: 0x4001300 - 0x40015e4"}
- {"dt": "2025-07-09T13:17:37.020042", "lvl": "target", "msg": "INFO:    FCONF: Config file with image ID:24 loaded at address = 0x4001300"}
- {"dt": "2025-07-09T13:17:37.020085", "lvl": "target", "msg": "INFO:    BL1: Loading BL2"}
- {"dt": "2025-07-09T13:17:37.020129", "lvl": "target", "msg": "INFO:    Loading image id=1 at address 0x4020000"}
- {"dt": "2025-07-09T13:17:37.020173", "lvl": "target", "msg": "INFO:    Image id=1 loaded: 0x4020000 - 0x4028821"}
- {"dt": "2025-07-09T13:17:37.020224", "lvl": "target", "msg": "NOTICE:  BL1: Booting BL2"}
- {"dt": "2025-07-09T13:17:37.020268", "lvl": "target", "msg": "INFO:    Entry point address = 0x4020000"}
- {"dt": "2025-07-09T13:17:37.020311", "lvl": "target", "msg": "INFO:    SPSR = 0x3c5"}
- {"dt": "2025-07-09T13:17:37.020348", "lvl": "target", "msg": "INFO:    FCONF: Reading FW_CONFIG firmware configuration file from: 0x4001010"}
- {"dt": "2025-07-09T13:17:37.020395", "lvl": "target", "msg": "INFO:    FCONF: Reading firmware configuration information for: dyn_cfg"}
- {"dt": "2025-07-09T13:17:37.020447", "lvl": "target", "msg": "INFO:    FCONF: Reading TB_FW firmware configuration file from: 0x4001300"}
- {"dt": "2025-07-09T13:17:37.020483", "lvl": "target", "msg": "NOTICE:  BL2: v2.12.0(release):v2.12.0"}
- {"dt": "2025-07-09T13:17:37.020517", "lvl": "target", "msg": "NOTICE:  BL2: Built : 13:04:04, Mar 21 2025"}
- {"dt": "2025-07-09T13:17:37.020553", "lvl": "target", "msg": "INFO:    BL2: Doing platform setup"}
- {"dt": "2025-07-09T13:17:37.020585", "lvl": "target", "msg": "INFO:    Configuring TrustZone Controller"}
- {"dt": "2025-07-09T13:17:37.020617", "lvl": "target", "msg": "INFO:    Total 8 regions set."}
- {"dt": "2025-07-09T13:17:37.020649", "lvl": "target", "msg": "INFO:    BL2: Loading image id 3"}
- {"dt": "2025-07-09T13:17:37.020685", "lvl": "target", "msg": "INFO:    Loading image id=3 at address 0x4003000"}
- {"dt": "2025-07-09T13:17:37.020722", "lvl": "target", "msg": "INFO:    Image id=3 loaded: 0x4003000 - 0x40141ad"}
- {"dt": "2025-07-09T13:17:37.020756", "lvl": "target", "msg": "INFO:    BL2: Loading image id 23"}
- {"dt": "2025-07-09T13:17:37.020788", "lvl": "target", "msg": "INFO:    Loading image id=23 at address 0x7f00000"}
- {"dt": "2025-07-09T13:17:37.020820", "lvl": "target", "msg": "INFO:    Image id=23 loaded: 0x7f00000 - 0x7f02a57"}
- {"dt": "2025-07-09T13:17:37.020856", "lvl": "target", "msg": "INFO:    BL2: Loading image id 25"}
- {"dt": "2025-07-09T13:17:37.020894", "lvl": "target", "msg": "INFO:    Loading image id=25 at address 0x4001300"}
- {"dt": "2025-07-09T13:17:37.020928", "lvl": "target", "msg": "INFO:    Image id=25 loaded: 0x4001300 - 0x4001348"}
- {"dt": "2025-07-09T13:17:37.020962", "lvl": "target", "msg": "INFO:    BL2: Loading image id 5"}
- {"dt": "2025-07-09T13:17:37.020993", "lvl": "target", "msg": "INFO:    Loading image id=5 at address 0x88000000"}
- {"dt": "2025-07-09T13:17:37.021025", "lvl": "target", "msg": "INFO:    Image id=5 loaded: 0x88000000 - 0x88280000"}
- {"dt": "2025-07-09T13:17:37.021056", "lvl": "target", "msg": "INFO:    BL2: Loading image id 27"}
- {"dt": "2025-07-09T13:17:37.021088", "lvl": "target", "msg": "INFO:    Loading image id=27 at address 0x80000000"}
- {"dt": "2025-07-09T13:17:37.021121", "lvl": "target", "msg": "INFO:    Image id=27 loaded: 0x80000000 - 0x80000048"}
- {"dt": "2025-07-09T13:17:37.021154", "lvl": "target", "msg": "NOTICE:  BL1: Booting BL31"}
- {"dt": "2025-07-09T13:17:37.021380", "lvl": "target", "msg": "INFO:    Entry point address = 0x4003000"}
- {"dt": "2025-07-09T13:17:37.021432", "lvl": "target", "msg": "INFO:    SPSR = 0x3cd"}
- {"dt": "2025-07-09T13:17:37.021472", "lvl": "target", "msg": "INFO:    BL31 FCONF: FW_CONFIG address = 4001010"}
- {"dt": "2025-07-09T13:17:37.021507", "lvl": "target", "msg": "INFO:    FCONF: Reading FW_CONFIG firmware configuration file from: 0x4001010"}
- {"dt": "2025-07-09T13:17:37.021542", "lvl": "target", "msg": "INFO:    FCONF: Reading firmware configuration information for: dyn_cfg"}
- {"dt": "2025-07-09T13:17:37.021575", "lvl": "target", "msg": "INFO:    FCONF: Reading HW_CONFIG firmware configuration file from: 0x7f00000"}
- {"dt": "2025-07-09T13:17:37.021607", "lvl": "target", "msg": "INFO:    FCONF: Reading firmware configuration information for: dram_layout"}
- {"dt": "2025-07-09T13:17:37.021641", "lvl": "target", "msg": "INFO:    FCONF: Reading firmware configuration information for: cpu_timer"}
- {"dt": "2025-07-09T13:17:37.021674", "lvl": "target", "msg": "INFO:    FCONF: Reading firmware configuration information for: uart_config"}
- {"dt": "2025-07-09T13:17:37.021707", "lvl": "target", "msg": "INFO:    FCONF: Reading firmware configuration information for: topology"}
- {"dt": "2025-07-09T13:17:37.021740", "lvl": "target", "msg": "INFO:    FCONF: Reading firmware configuration information for: gicv3_config"}
- {"dt": "2025-07-09T13:17:37.021772", "lvl": "target", "msg": "NOTICE:  BL31: v2.12.0(release):v2.12.0"}
- {"dt": "2025-07-09T13:17:37.021814", "lvl": "target", "msg": "NOTICE:  BL31: Built : 13:04:05, Mar 21 2025"}
- {"dt": "2025-07-09T13:17:37.021852", "lvl": "target", "msg": "INFO:    GICv3 with legacy support detected."}
- {"dt": "2025-07-09T13:17:37.021885", "lvl": "target", "msg": "INFO:    ARM GICv3 driver initialized in EL3"}
- {"dt": "2025-07-09T13:17:37.021917", "lvl": "target", "msg": "INFO:    Maximum SPI INTID supported: 255"}
- {"dt": "2025-07-09T13:17:37.021949", "lvl": "target", "msg": "INFO:    BL31: Initializing runtime services"}
- {"dt": "2025-07-09T13:17:37.021991", "lvl": "target", "msg": "INFO:    BL31: Preparing for EL3 exit to normal world"}
- {"dt": "2025-07-09T13:17:37.022028", "lvl": "target", "msg": "INFO:    Entry point address = 0x88000000"}
- {"dt": "2025-07-09T13:17:37.022062", "lvl": "target", "msg": "INFO:    SPSR = 0x3c9"}
- {"dt": "2025-07-09T13:17:38.410455", "lvl": "target", "msg": "[2J[04D[=3h[2J[09DPress ESCAPE for boot options ...........[2J[45DUEFI Interactive Shell v2.2"}
- {"dt": "2025-07-09T13:17:38.412054", "lvl": "target", "msg": "EDK II"}
- {"dt": "2025-07-09T13:17:38.414315", "lvl": "target", "msg": "UEFI v2.70 (EDK II, 0x00010000)"}
- {"dt": "2025-07-09T13:17:38.421004", "lvl": "target", "msg": "[1m[33m[40mMapping table[0m[37m[40m"}
- {"dt": "2025-07-09T13:17:38.429948", "lvl": "target", "msg": "[1m[33m[40m      FS2:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40mF4:"}
- {"dt": "2025-07-09T13:17:38.430067", "lvl": "target", "msg": "          VenHw(C5B9C74A-6D72-4719-99AB-C59F199091EB)"}
- {"dt": "2025-07-09T13:17:38.437156", "lvl": "target", "msg": "[1m[33m[40m      FS1:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40mF1:"}
- {"dt": "2025-07-09T13:17:38.437247", "lvl": "target", "msg": "          MemoryMapped(0xB,0x88000000,0x8827FFFF)"}
- {"dt": "2025-07-09T13:17:38.445498", "lvl": "target", "msg": "[1m[33m[40m      FS0:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40mF0:"}
- {"dt": "2025-07-09T13:17:38.445593", "lvl": "target", "msg": "          Fv(87940482-FC81-41C3-87E6-399CF85AC8A0)"}
- {"dt": "2025-07-09T13:17:38.453918", "lvl": "target", "msg": "[1m[33m[40m     BLK2:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40m"}
- {"dt": "2025-07-09T13:17:38.454013", "lvl": "target", "msg": "          VenHw(DE6AE758-D662-4E17-A97C-4C5964DA4C41,00)"}
- {"dt": "2025-07-09T13:17:38.462277", "lvl": "target", "msg": "[1m[33m[40m     BLK3:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40m"}
- {"dt": "2025-07-09T13:17:38.462361", "lvl": "target", "msg": "          VenHw(DE6AE758-D662-4E17-A97C-4C5964DA4C41,01)"}
- {"dt": "2025-07-09T13:17:38.470640", "lvl": "target", "msg": "[1m[33m[40m     BLK4:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40m"}
- {"dt": "2025-07-09T13:17:38.470722", "lvl": "target", "msg": "          VenHw(DE6AE758-D662-4E17-A97C-4C5964DA4C41,02)"}
- {"dt": "2025-07-09T13:17:38.478991", "lvl": "target", "msg": "[1m[33m[40m     BLK5:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40m"}
- {"dt": "2025-07-09T13:17:38.479070", "lvl": "target", "msg": "          VenHw(DE6AE758-D662-4E17-A97C-4C5964DA4C41,03)"}
- {"dt": "2025-07-09T13:17:38.486863", "lvl": "target", "msg": "[1m[33m[40m     BLK0:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40m"}
- {"dt": "2025-07-09T13:17:38.486943", "lvl": "target", "msg": "          VenHw(09831032-6FA3-4484-AF4F-0A000A8D3A82)"}
- {"dt": "2025-07-09T13:17:38.494645", "lvl": "target", "msg": "[1m[33m[40m     BLK1:[0m[37m[40m [1m[37m[40mAlias(s):[0m[37m[40m"}
- {"dt": "2025-07-09T13:17:38.494932", "lvl": "target", "msg": "          VenHw(405B2307-6839-4D52-AEB9-BECE64252800)"}
- {"dt": "2025-07-09T13:17:38.514733", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:38.609380", "lvl": "target", "msg": "[1m[33m[40mShell> [0m[37m[40mImage dtb=fvp-base-revc.dtb systemd.log_level=warning rw console=ttyAMA0 "}
- {"dt": "2025-07-09T13:17:38.609548", "lvl": "target", "msg": "earlycon=pl011,0x1c090000 root=/dev/vda ip=dhcp"}
- {"dt": "2025-07-09T13:17:39.173402", "lvl": "target", "msg": "EFI stub: Booting Linux Kernel..."}
- {"dt": "2025-07-09T13:17:39.326802", "lvl": "target", "msg": "EFI stub: Using DTB from command line"}
- {"dt": "2025-07-09T13:17:39.326937", "lvl": "target", "msg": "EFI stub: Exiting boot services..."}
- {"dt": "2025-07-09T13:17:39.382956", "lvl": "target", "msg": "Booting Linux on physical CPU 0x0000000000 [0x410fd0f0]"}
- {"dt": "2025-07-09T13:17:39.383289", "lvl": "debug", "msg": "start: 2.3.1 login-action (timeout 00:29:54) [common]"}
- {"dt": "2025-07-09T13:17:39.383375", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2025-07-09T13:17:39.383441", "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": "2025-07-09T13:17:39.383494", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2025-07-09T13:17:39.383541", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2025-07-09T13:17:39.383595", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2025-07-09T13:17:39.383636", "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", "root@(.*):[/~]#", "login:", "Login incorrect"]}
- {"dt": "2025-07-09T13:17:39.383716", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:29:54)"}
- {"dt": "2025-07-09T13:17:39.383764", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:14:57)"}
- {"dt": "2025-07-09T13:17:39.384163", "lvl": "target", "msg": "Linux version 6.4.16 (root@runner-bplhzw6g-project-23449704-concurrent-3) (aarch64-linux-gcc.br_real (Buildroot 2021.11-11272-ge2962af) 13.2.0, GNU ld (GNU Binutils) 2.42) #1 SMP Tue Nov 19 14:45:23 UTC 2024"}
- {"dt": "2025-07-09T13:17:39.384216", "lvl": "target", "msg": "Machine model: FVP Base RevC"}
- {"dt": "2025-07-09T13:17:39.384257", "lvl": "target", "msg": "earlycon: pl11 at MMIO 0x000000001c090000 (options '')"}
- {"dt": "2025-07-09T13:17:39.384296", "lvl": "target", "msg": "printk: bootconsole [pl11] enabled"}
- {"dt": "2025-07-09T13:17:39.385129", "lvl": "target", "msg": "efi: EFI v2.7 by EDK II"}
- {"dt": "2025-07-09T13:17:39.385677", "lvl": "target", "msg": "efi: ACPI 2.0=0xf2f8b018 MEMATTR=0xf6ed0018 MEMRESERVE=0xf2b61f18 "}
- {"dt": "2025-07-09T13:17:39.387929", "lvl": "target", "msg": "Reserved memory: created DMA memory pool at 0x0000000018000000, size 8 MiB"}
- {"dt": "2025-07-09T13:17:39.388196", "lvl": "target", "msg": "OF: reserved mem: initialized node vram@18000000, compatible id shared-dma-pool"}
- {"dt": "2025-07-09T13:17:39.388700", "lvl": "target", "msg": "OF: reserved mem: 0x0000000018000000..0x00000000187fffff (8192 KiB) nomap non-reusable vram@18000000"}
- {"dt": "2025-07-09T13:17:40.022337", "lvl": "target", "msg": "Zone ranges:"}
- {"dt": "2025-07-09T13:17:40.023057", "lvl": "target", "msg": "  DMA      [mem 0x0000000080000000-0x00000000ffffffff]"}
- {"dt": "2025-07-09T13:17:40.023115", "lvl": "target", "msg": "  DMA32    empty"}
- {"dt": "2025-07-09T13:17:40.023160", "lvl": "target", "msg": "  Normal   [mem 0x0000000100000000-0x00000008ffffffff]"}
- {"dt": "2025-07-09T13:17:40.023460", "lvl": "target", "msg": "Movable zone start for each node"}
- {"dt": "2025-07-09T13:17:40.023522", "lvl": "target", "msg": "Early memory node ranges"}
- {"dt": "2025-07-09T13:17:40.023568", "lvl": "target", "msg": "  node   0: [mem 0x0000000080000000-0x00000000f299ffff]"}
- {"dt": "2025-07-09T13:17:40.023606", "lvl": "target", "msg": "  node   0: [mem 0x00000000f29a0000-0x00000000f2a0ffff]"}
- {"dt": "2025-07-09T13:17:40.023976", "lvl": "target", "msg": "  node   0: [mem 0x00000000f2a10000-0x00000000f2b1ffff]"}
- {"dt": "2025-07-09T13:17:40.024037", "lvl": "target", "msg": "  node   0: [mem 0x00000000f2b20000-0x00000000f2b5ffff]"}
- {"dt": "2025-07-09T13:17:40.024280", "lvl": "target", "msg": "  node   0: [mem 0x00000000f2b60000-0x00000000f2b9ffff]"}
- {"dt": "2025-07-09T13:17:40.024331", "lvl": "target", "msg": "  node   0: [mem 0x00000000f2ba0000-0x00000000f2f7ffff]"}
- {"dt": "2025-07-09T13:17:40.024372", "lvl": "target", "msg": "  node   0: [mem 0x00000000f2f80000-0x00000000f7f4ffff]"}
- {"dt": "2025-07-09T13:17:40.024608", "lvl": "target", "msg": "  node   0: [mem 0x00000000f7f50000-0x00000000f7f8ffff]"}
- {"dt": "2025-07-09T13:17:40.024662", "lvl": "target", "msg": "  node   0: [mem 0x00000000f7f90000-0x00000000f7f9ffff]"}
- {"dt": "2025-07-09T13:17:40.024702", "lvl": "target", "msg": "  node   0: [mem 0x00000000f7fa0000-0x00000000f7feffff]"}
- {"dt": "2025-07-09T13:17:40.024740", "lvl": "target", "msg": "  node   0: [mem 0x00000000f7ff0000-0x00000000fbffffff]"}
- {"dt": "2025-07-09T13:17:40.024777", "lvl": "target", "msg": "  node   0: [mem 0x0000000880000000-0x00000008ffffffff]"}
- {"dt": "2025-07-09T13:17:40.025135", "lvl": "target", "msg": "Initmem setup node 0 [mem 0x0000000080000000-0x00000008ffffffff]"}
- {"dt": "2025-07-09T13:17:40.269019", "lvl": "target", "msg": "On node 0, zone Normal: 16384 pages in unavailable ranges"}
- {"dt": "2025-07-09T13:17:40.269201", "lvl": "target", "msg": "psci: probing for conduit method from DT."}
- {"dt": "2025-07-09T13:17:40.270562", "lvl": "target", "msg": "psci: PSCIv1.1 detected in firmware."}
- {"dt": "2025-07-09T13:17:40.271108", "lvl": "target", "msg": "psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2025-07-09T13:17:40.271166", "lvl": "target", "msg": "psci: MIGRATE_INFO_TYPE not supported."}
- {"dt": "2025-07-09T13:17:40.272226", "lvl": "target", "msg": "psci: SMC Calling Convention v1.5"}
- {"dt": "2025-07-09T13:17:40.277276", "lvl": "target", "msg": "percpu: Embedded 18 pages/cpu s35112 r8192 d30424 u73728"}
- {"dt": "2025-07-09T13:17:40.280067", "lvl": "target", "msg": "Detected PIPT I-cache on CPU0"}
- {"dt": "2025-07-09T13:17:40.297915", "lvl": "target", "msg": "CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2025-07-09T13:17:40.297996", "lvl": "target", "msg": "CPU features: detected: GIC system register CPU interface"}
- {"dt": "2025-07-09T13:17:40.298473", "lvl": "target", "msg": "CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2025-07-09T13:17:40.298801", "lvl": "target", "msg": "CPU features: detected: Hardware dirty bit management"}
- {"dt": "2025-07-09T13:17:40.299139", "lvl": "target", "msg": "CPU features: detected: Spectre-BHB"}
- {"dt": "2025-07-09T13:17:40.299588", "lvl": "target", "msg": "alternatives: applying boot alternatives"}
- {"dt": "2025-07-09T13:17:40.310436", "lvl": "target", "msg": "Kernel command line: Image dtb=fvp-base-revc.dtb systemd.log_level=warning rw console=ttyAMA0 earlycon=pl011,0x1c090000 root=/dev/vda ip=dhcp"}
- {"dt": "2025-07-09T13:17:40.311896", "lvl": "target", "msg": "Unknown kernel command line parameters \"Image dtb=fvp-base-revc.dtb ip=dhcp\", will be passed to user space."}
- {"dt": "2025-07-09T13:17:40.314149", "lvl": "target", "msg": "random: crng init done"}
- {"dt": "2025-07-09T13:17:40.324746", "lvl": "target", "msg": "Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2025-07-09T13:17:40.329226", "lvl": "target", "msg": "Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2025-07-09T13:17:40.330332", "lvl": "target", "msg": "Built 1 zonelists, mobility grouping on.  Total pages: 1015808"}
- {"dt": "2025-07-09T13:17:40.330897", "lvl": "target", "msg": "mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2025-07-09T13:17:40.330951", "lvl": "target", "msg": "software IO TLB: area num 8."}
- {"dt": "2025-07-09T13:17:40.521813", "lvl": "target", "msg": "software IO TLB: mapped [mem 0x00000000f8000000-0x00000000fc000000] (64MB)"}
- {"dt": "2025-07-09T13:17:40.681389", "lvl": "target", "msg": "Memory: 3964360K/4128768K available (7680K kernel code, 876K rwdata, 1836K rodata, 1408K init, 438K bss, 164408K reserved, 0K cma-reserved)"}
- {"dt": "2025-07-09T13:17:40.696882", "lvl": "target", "msg": "SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2025-07-09T13:17:40.706900", "lvl": "target", "msg": "rcu: Hierarchical RCU implementation."}
- {"dt": "2025-07-09T13:17:40.707260", "lvl": "target", "msg": "rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2025-07-09T13:17:40.707597", "lvl": "target", "msg": "rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2025-07-09T13:17:40.707661", "lvl": "target", "msg": "rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2025-07-09T13:17:40.709117", "lvl": "target", "msg": "NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2025-07-09T13:17:40.716384", "lvl": "target", "msg": "GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2025-07-09T13:17:40.716951", "lvl": "target", "msg": "GICv3: 224 SPIs implemented"}
- {"dt": "2025-07-09T13:17:40.717292", "lvl": "target", "msg": "GICv3: 0 Extended SPIs implemented"}
- {"dt": "2025-07-09T13:17:40.718614", "lvl": "target", "msg": "Root IRQ handler: gic_handle_irq"}
- {"dt": "2025-07-09T13:17:40.719186", "lvl": "target", "msg": "GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2025-07-09T13:17:40.719737", "lvl": "target", "msg": "GICv3: CPU0: found redistributor 0 region 0:0x000000002f100000"}
- {"dt": "2025-07-09T13:17:40.725372", "lvl": "target", "msg": "ITS [mem 0x2f020000-0x2f03ffff]"}
- {"dt": "2025-07-09T13:17:40.726492", "lvl": "target", "msg": "ITS@0x000000002f020000: allocated 8192 Devices @880050000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-07-09T13:17:40.727035", "lvl": "target", "msg": "ITS@0x000000002f020000: allocated 8192 Virtual CPUs @880060000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-07-09T13:17:40.727595", "lvl": "target", "msg": "ITS@0x000000002f020000: allocated 8192 Interrupt Collections @880070000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-07-09T13:17:40.729260", "lvl": "target", "msg": "GICv3: using LPI property table @0x0000000880080000"}
- {"dt": "2025-07-09T13:17:40.733742", "lvl": "target", "msg": "GICv3: CPU0: using allocated LPI pending table @0x0000000880090000"}
- {"dt": "2025-07-09T13:17:40.737649", "lvl": "target", "msg": "rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2025-07-09T13:17:40.743784", "lvl": "target", "msg": "arch_timer: cp15 timer(s) running at 100.00MHz (phys)."}
- {"dt": "2025-07-09T13:17:40.744348", "lvl": "target", "msg": "clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x171024e7e0, max_idle_ns: 440795205315 ns"}
- {"dt": "2025-07-09T13:17:40.744915", "lvl": "target", "msg": "sched_clock: 57 bits at 100MHz, resolution 10ns, wraps every 4398046511100ns"}
- {"dt": "2025-07-09T13:17:40.751647", "lvl": "target", "msg": "Console: colour dummy device 80x25"}
- {"dt": "2025-07-09T13:17:40.752979", "lvl": "target", "msg": "Calibrating delay loop (skipped), value calculated using timer frequency.. 200.00 BogoMIPS (lpj=400000)"}
- {"dt": "2025-07-09T13:17:40.753364", "lvl": "target", "msg": "pid_max: default: 32768 minimum: 301"}
- {"dt": "2025-07-09T13:17:40.757297", "lvl": "target", "msg": "Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-07-09T13:17:40.757627", "lvl": "target", "msg": "Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-07-09T13:17:40.791398", "lvl": "target", "msg": "cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2025-07-09T13:17:40.809805", "lvl": "target", "msg": "rcu: Hierarchical SRCU implementation."}
- {"dt": "2025-07-09T13:17:40.809928", "lvl": "target", "msg": "rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2025-07-09T13:17:40.813682", "lvl": "target", "msg": "Platform MSI: msi-controller@2f020000 domain created"}
- {"dt": "2025-07-09T13:17:40.814780", "lvl": "target", "msg": "PCI/MSI: /interrupt-controller@2f000000/msi-controller@2f020000 domain created"}
- {"dt": "2025-07-09T13:17:40.815311", "lvl": "target", "msg": "Remapping and enabling EFI services."}
- {"dt": "2025-07-09T13:17:40.824236", "lvl": "target", "msg": "smp: Bringing up secondary CPUs ..."}
- {"dt": "2025-07-09T13:17:41.222669", "lvl": "target", "msg": "Detected PIPT I-cache on CPU1"}
- {"dt": "2025-07-09T13:17:41.223670", "lvl": "target", "msg": "GICv3: CPU1: found redistributor 100 region 0:0x000000002f120000"}
- {"dt": "2025-07-09T13:17:41.224818", "lvl": "target", "msg": "GICv3: CPU1: using allocated LPI pending table @0x00000008800a0000"}
- {"dt": "2025-07-09T13:17:41.225159", "lvl": "target", "msg": "CPU1: Booted secondary processor 0x0000000100 [0x410fd0f0]"}
- {"dt": "2025-07-09T13:17:41.225240", "lvl": "target", "msg": "Detected PIPT I-cache on CPU2"}
- {"dt": "2025-07-09T13:17:41.225339", "lvl": "target", "msg": "GICv3: CPU2: found redistributor 200 region 0:0x000000002f140000"}
- {"dt": "2025-07-09T13:17:41.225503", "lvl": "target", "msg": "GICv3: CPU2: using allocated LPI pending table @0x00000008800b0000"}
- {"dt": "2025-07-09T13:17:41.225601", "lvl": "target", "msg": "CPU2: Booted secondary processor 0x0000000200 [0x410fd0f0]"}
- {"dt": "2025-07-09T13:17:41.225687", "lvl": "target", "msg": "Detected PIPT I-cache on CPU3"}
- {"dt": "2025-07-09T13:17:41.225761", "lvl": "target", "msg": "GICv3: CPU3: found redistributor 300 region 0:0x000000002f160000"}
- {"dt": "2025-07-09T13:17:41.225837", "lvl": "target", "msg": "GICv3: CPU3: using allocated LPI pending table @0x00000008800c0000"}
- {"dt": "2025-07-09T13:17:41.225931", "lvl": "target", "msg": "CPU3: Booted secondary processor 0x0000000300 [0x410fd0f0]"}
- {"dt": "2025-07-09T13:17:41.226004", "lvl": "target", "msg": "Detected PIPT I-cache on CPU4"}
- {"dt": "2025-07-09T13:17:41.226061", "lvl": "target", "msg": "GICv3: CPU4: found redistributor 10000 region 0:0x000000002f180000"}
- {"dt": "2025-07-09T13:17:41.226137", "lvl": "target", "msg": "GICv3: CPU4: using allocated LPI pending table @0x00000008800d0000"}
- {"dt": "2025-07-09T13:17:41.226199", "lvl": "target", "msg": "CPU4: Booted secondary processor 0x0000010000 [0x410fd0f0]"}
- {"dt": "2025-07-09T13:17:41.226256", "lvl": "target", "msg": "Detected PIPT I-cache on CPU5"}
- {"dt": "2025-07-09T13:17:41.226309", "lvl": "target", "msg": "GICv3: CPU5: found redistributor 10100 region 0:0x000000002f1a0000"}
- {"dt": "2025-07-09T13:17:41.226358", "lvl": "target", "msg": "GICv3: CPU5: using allocated LPI pending table @0x00000008800e0000"}
- {"dt": "2025-07-09T13:17:41.226638", "lvl": "target", "msg": "CPU5: Booted secondary processor 0x0000010100 [0x410fd0f0]"}
- {"dt": "2025-07-09T13:17:41.226683", "lvl": "target", "msg": "Detected PIPT I-cache on CPU6"}
- {"dt": "2025-07-09T13:17:41.226720", "lvl": "target", "msg": "GICv3: CPU6: found redistributor 10200 region 0:0x000000002f1c0000"}
- {"dt": "2025-07-09T13:17:41.226756", "lvl": "target", "msg": "GICv3: CPU6: using allocated LPI pending table @0x00000008800f0000"}
- {"dt": "2025-07-09T13:17:41.226789", "lvl": "target", "msg": "CPU6: Booted secondary processor 0x0000010200 [0x410fd0f0]"}
- {"dt": "2025-07-09T13:17:41.226825", "lvl": "target", "msg": "Detected PIPT I-cache on CPU7"}
- {"dt": "2025-07-09T13:17:41.226863", "lvl": "target", "msg": "GICv3: CPU7: found redistributor 10300 region 0:0x000000002f1e0000"}
- {"dt": "2025-07-09T13:17:41.226899", "lvl": "target", "msg": "GICv3: CPU7: using allocated LPI pending table @0x0000000880100000"}
- {"dt": "2025-07-09T13:17:41.227189", "lvl": "target", "msg": "CPU7: Booted secondary processor 0x0000010300 [0x410fd0f0]"}
- {"dt": "2025-07-09T13:17:41.227252", "lvl": "target", "msg": "smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2025-07-09T13:17:41.227298", "lvl": "target", "msg": "SMP: Total of 8 processors activated."}
- {"dt": "2025-07-09T13:17:41.227702", "lvl": "target", "msg": "CPU features: detected: Branch Target Identification"}
- {"dt": "2025-07-09T13:17:41.227756", "lvl": "target", "msg": "CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2025-07-09T13:17:41.228014", "lvl": "target", "msg": "CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2025-07-09T13:17:41.228295", "lvl": "target", "msg": "CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2025-07-09T13:17:41.228344", "lvl": "target", "msg": "CPU features: detected: Common not Private translations"}
- {"dt": "2025-07-09T13:17:41.228383", "lvl": "target", "msg": "CPU features: detected: CRC32 instructions"}
- {"dt": "2025-07-09T13:17:41.228878", "lvl": "target", "msg": "CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2025-07-09T13:17:41.229141", "lvl": "target", "msg": "CPU features: detected: E0PD"}
- {"dt": "2025-07-09T13:17:41.229184", "lvl": "target", "msg": "CPU features: detected: Enhanced Counter Virtualization"}
- {"dt": "2025-07-09T13:17:41.229220", "lvl": "target", "msg": "CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)"}
- {"dt": "2025-07-09T13:17:41.229992", "lvl": "target", "msg": "CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2025-07-09T13:17:41.231699", "lvl": "target", "msg": "CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2025-07-09T13:17:41.231781", "lvl": "target", "msg": "CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2025-07-09T13:17:41.232268", "lvl": "target", "msg": "CPU features: detected: LSE atomic instructions"}
- {"dt": "2025-07-09T13:17:41.232346", "lvl": "target", "msg": "CPU features: detected: Privileged Access Never"}
- {"dt": "2025-07-09T13:17:41.232625", "lvl": "target", "msg": "CPU features: detected: RAS Extension Support"}
- {"dt": "2025-07-09T13:17:41.232677", "lvl": "target", "msg": "CPU features: detected: Random Number Generator"}
- {"dt": "2025-07-09T13:17:41.232717", "lvl": "target", "msg": "CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2025-07-09T13:17:41.233005", "lvl": "target", "msg": "CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2025-07-09T13:17:41.233058", "lvl": "target", "msg": "CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2025-07-09T13:17:41.233098", "lvl": "target", "msg": "CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2025-07-09T13:17:41.233157", "lvl": "target", "msg": "CPU features: detected: WFx with timeout"}
- {"dt": "2025-07-09T13:17:41.233222", "lvl": "target", "msg": "CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2025-07-09T13:17:41.233507", "lvl": "target", "msg": "CPU features: detected: SME2"}
- {"dt": "2025-07-09T13:17:41.233561", "lvl": "target", "msg": "CPU features: detected: Scalable Vector Extension"}
- {"dt": "2025-07-09T13:17:41.267983", "lvl": "target", "msg": "CPU features: detected CPU5: Activity Monitors Unit (AMU)"}
- {"dt": "2025-07-09T13:17:41.269082", "lvl": "target", "msg": "CPU features: detected CPU7: Activity Monitors Unit (AMU)"}
- {"dt": "2025-07-09T13:17:41.270215", "lvl": "target", "msg": "CPU features: detected CPU1: Activity Monitors Unit (AMU)"}
- {"dt": "2025-07-09T13:17:41.271341", "lvl": "target", "msg": "CPU features: detected CPU3: Activity Monitors Unit (AMU)"}
- {"dt": "2025-07-09T13:17:41.271909", "lvl": "target", "msg": "CPU features: detected CPU6: Activity Monitors Unit (AMU)"}
- {"dt": "2025-07-09T13:17:41.273039", "lvl": "target", "msg": "CPU features: detected CPU4: Activity Monitors Unit (AMU)"}
- {"dt": "2025-07-09T13:17:41.273605", "lvl": "target", "msg": "CPU features: detected CPU2: Activity Monitors Unit (AMU)"}
- {"dt": "2025-07-09T13:17:41.274733", "lvl": "target", "msg": "CPU features: detected CPU0: Activity Monitors Unit (AMU)"}
- {"dt": "2025-07-09T13:17:41.331076", "lvl": "target", "msg": "SVE: maximum available vector length 64 bytes per vector"}
- {"dt": "2025-07-09T13:17:41.331543", "lvl": "target", "msg": "SVE: default vector length 64 bytes per vector"}
- {"dt": "2025-07-09T13:17:41.333179", "lvl": "target", "msg": "SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2025-07-09T13:17:41.335991", "lvl": "target", "msg": "SME: maximum available vector length 64 bytes per vector"}
- {"dt": "2025-07-09T13:17:41.336303", "lvl": "target", "msg": "SME: default vector length 32 bytes per vector"}
- {"dt": "2025-07-09T13:17:41.336657", "lvl": "target", "msg": "CPU: All CPU(s) started at EL2"}
- {"dt": "2025-07-09T13:17:41.336733", "lvl": "target", "msg": "alternatives: applying system-wide alternatives"}
- {"dt": "2025-07-09T13:17:41.676147", "lvl": "target", "msg": "devtmpfs: initialized"}
- {"dt": "2025-07-09T13:17:41.736854", "lvl": "target", "msg": "clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2025-07-09T13:17:41.737873", "lvl": "target", "msg": "futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-07-09T13:17:41.742316", "lvl": "target", "msg": "DMI not present or invalid."}
- {"dt": "2025-07-09T13:17:41.752344", "lvl": "target", "msg": "NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2025-07-09T13:17:41.780383", "lvl": "target", "msg": "DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2025-07-09T13:17:41.783018", "lvl": "target", "msg": "DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2025-07-09T13:17:41.789887", "lvl": "target", "msg": "DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2025-07-09T13:17:41.799787", "lvl": "target", "msg": "thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2025-07-09T13:17:41.799900", "lvl": "target", "msg": "cpuidle: using governor menu"}
- {"dt": "2025-07-09T13:17:41.802001", "lvl": "target", "msg": "hw-breakpoint: found 16 breakpoint and 16 watchpoint registers."}
- {"dt": "2025-07-09T13:17:41.810914", "lvl": "target", "msg": "ASID allocator initialised with 65536 entries"}
- {"dt": "2025-07-09T13:17:41.811005", "lvl": "target", "msg": "Serial: AMBA PL011 UART driver"}
- {"dt": "2025-07-09T13:17:41.867239", "lvl": "target", "msg": "1c090000.serial: ttyAMA0 at MMIO 0x1c090000 (irq = 14, base_baud = 0) is a PL011 rev2"}
- {"dt": "2025-07-09T13:17:41.877178", "lvl": "target", "msg": "printk: console [ttyAMA0] enabled"}
- {"dt": "2025-07-09T13:17:41.877330", "lvl": "target", "msg": "printk: console [ttyAMA0] enabled"}
- {"dt": "2025-07-09T13:17:41.877779", "lvl": "target", "msg": "printk: bootconsole [pl11] disabled"}
- {"dt": "2025-07-09T13:17:41.877871", "lvl": "target", "msg": "printk: bootconsole [pl11] disabled"}
- {"dt": "2025-07-09T13:17:41.892264", "lvl": "target", "msg": "1c0a0000.serial: ttyAMA1 at MMIO 0x1c0a0000 (irq = 15, base_baud = 0) is a PL011 rev2"}
- {"dt": "2025-07-09T13:17:41.896694", "lvl": "target", "msg": "1c0b0000.serial: ttyAMA2 at MMIO 0x1c0b0000 (irq = 16, base_baud = 0) is a PL011 rev2"}
- {"dt": "2025-07-09T13:17:41.902505", "lvl": "target", "msg": "1c0c0000.serial: ttyAMA3 at MMIO 0x1c0c0000 (irq = 17, base_baud = 0) is a PL011 rev2"}
- {"dt": "2025-07-09T13:17:41.922988", "lvl": "target", "msg": "platform panel: Fixed dependency cycle(s) with /bus@8000000/motherboard-bus@8000000/iofpga-bus@300000000/clcd@1f0000/port/endpoint"}
- {"dt": "2025-07-09T13:17:41.957277", "lvl": "target", "msg": "ACPI: Interpreter disabled."}
- {"dt": "2025-07-09T13:17:41.960044", "lvl": "target", "msg": "iommu: Default domain type: Translated "}
- {"dt": "2025-07-09T13:17:41.960372", "lvl": "target", "msg": "iommu: DMA domain TLB invalidation policy: strict mode "}
- {"dt": "2025-07-09T13:17:41.963401", "lvl": "target", "msg": "SCSI subsystem initialized"}
- {"dt": "2025-07-09T13:17:41.967309", "lvl": "target", "msg": "pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2025-07-09T13:17:41.967642", "lvl": "target", "msg": "pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2025-07-09T13:17:41.967995", "lvl": "target", "msg": "PTP clock support registered"}
- {"dt": "2025-07-09T13:17:41.974567", "lvl": "target", "msg": "efivars: Registered efivars operations"}
- {"dt": "2025-07-09T13:17:41.990118", "lvl": "target", "msg": "vgaarb: loaded"}
- {"dt": "2025-07-09T13:17:41.996798", "lvl": "target", "msg": "clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2025-07-09T13:17:42.001267", "lvl": "target", "msg": "pnp: PnP ACPI: disabled"}
- {"dt": "2025-07-09T13:17:42.113117", "lvl": "target", "msg": "NET: Registered PF_INET protocol family"}
- {"dt": "2025-07-09T13:17:42.116366", "lvl": "target", "msg": "IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2025-07-09T13:17:42.138797", "lvl": "target", "msg": "tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-07-09T13:17:42.139167", "lvl": "target", "msg": "Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-07-09T13:17:42.139253", "lvl": "target", "msg": "TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-07-09T13:17:42.141330", "lvl": "target", "msg": "TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2025-07-09T13:17:42.146906", "lvl": "target", "msg": "TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2025-07-09T13:17:42.149073", "lvl": "target", "msg": "UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-07-09T13:17:42.150200", "lvl": "target", "msg": "UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-07-09T13:17:42.152443", "lvl": "target", "msg": "NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2025-07-09T13:17:42.153554", "lvl": "target", "msg": "PCI: CLS 0 bytes, default 64"}
- {"dt": "2025-07-09T13:17:42.226026", "lvl": "target", "msg": "workingset: timestamp_bits=46 max_order=20 bucket_order=0"}
- {"dt": "2025-07-09T13:17:42.230391", "lvl": "target", "msg": "fuse: init (API version 7.38)"}
- {"dt": "2025-07-09T13:17:42.235428", "lvl": "target", "msg": "Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)"}
- {"dt": "2025-07-09T13:17:42.235965", "lvl": "target", "msg": "io scheduler mq-deadline registered"}
- {"dt": "2025-07-09T13:17:42.236037", "lvl": "target", "msg": "io scheduler kyber registered"}
- {"dt": "2025-07-09T13:17:42.249934", "lvl": "target", "msg": "pci-host-generic 40000000.pci: host bridge /pci@40000000 ranges:"}
- {"dt": "2025-07-09T13:17:42.250462", "lvl": "target", "msg": "pci-host-generic 40000000.pci:      MEM 0x0050000000..0x005fffffff -> 0x0050000000"}
- {"dt": "2025-07-09T13:17:42.252130", "lvl": "target", "msg": "pci-host-generic 40000000.pci: ECAM at [mem 0x40000000-0x4fffffff] for [bus 00-01]"}
- {"dt": "2025-07-09T13:17:42.253819", "lvl": "target", "msg": "pci-host-generic 40000000.pci: PCI host bridge to bus 0000:00"}
- {"dt": "2025-07-09T13:17:42.254379", "lvl": "target", "msg": "pci_bus 0000:00: root bus resource [bus 00-01]"}
- {"dt": "2025-07-09T13:17:42.254641", "lvl": "target", "msg": "pci_bus 0000:00: root bus resource [mem 0x50000000-0x5fffffff]"}
- {"dt": "2025-07-09T13:17:42.256624", "lvl": "target", "msg": "pci 0000:00:00.0: [13b5:00ba] type 00 class 0x060001"}
- {"dt": "2025-07-09T13:17:42.269453", "lvl": "target", "msg": "pci 0000:00:00.0: reg 0x10: [mem 0x5080e000-0x5080efff]"}
- {"dt": "2025-07-09T13:17:42.271123", "lvl": "target", "msg": "pci 0000:00:00.0: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.272798", "lvl": "target", "msg": "pci 0000:00:00.0: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.278372", "lvl": "target", "msg": "pci 0000:00:01.0: [13b5:0def] type 01 class 0x060400"}
- {"dt": "2025-07-09T13:17:42.278933", "lvl": "target", "msg": "pci 0000:00:01.0: reg 0x10: [mem 0x5080d000-0x5080dfff]"}
- {"dt": "2025-07-09T13:17:42.280031", "lvl": "target", "msg": "pci 0000:00:01.0: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.281164", "lvl": "target", "msg": "pci 0000:00:01.0: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.284526", "lvl": "target", "msg": "pci 0000:00:02.0: [13b5:0def] type 01 class 0x060400"}
- {"dt": "2025-07-09T13:17:42.285635", "lvl": "target", "msg": "pci 0000:00:02.0: reg 0x10: [mem 0x50808000-0x50808fff pref]"}
- {"dt": "2025-07-09T13:17:42.286758", "lvl": "target", "msg": "pci 0000:00:02.0: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.287319", "lvl": "target", "msg": "pci 0000:00:02.0: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.289563", "lvl": "target", "msg": "pci 0000:00:03.0: [13b5:0def] type 01 class 0x060400"}
- {"dt": "2025-07-09T13:17:42.290369", "lvl": "target", "msg": "pci 0000:00:03.0: reg 0x10: [mem 0x50809000-0x50809fff pref]"}
- {"dt": "2025-07-09T13:17:42.291252", "lvl": "target", "msg": "pci 0000:00:03.0: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.291827", "lvl": "target", "msg": "pci 0000:00:03.0: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.294630", "lvl": "target", "msg": "pci 0000:00:04.0: [13b5:0def] type 01 class 0x060400"}
- {"dt": "2025-07-09T13:17:42.295744", "lvl": "target", "msg": "pci 0000:00:04.0: reg 0x10: [mem 0x5080a000-0x5080afff pref]"}
- {"dt": "2025-07-09T13:17:42.296303", "lvl": "target", "msg": "pci 0000:00:04.0: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.297425", "lvl": "target", "msg": "pci 0000:00:04.0: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.308022", "lvl": "target", "msg": "pci 0000:00:1e.0: [13b5:ff80] type 00 class 0xff0000"}
- {"dt": "2025-07-09T13:17:42.309076", "lvl": "target", "msg": "pci 0000:00:1e.0: reg 0x10: [mem 0x4000040000-0x400007ffff 64bit]"}
- {"dt": "2025-07-09T13:17:42.310190", "lvl": "target", "msg": "pci 0000:00:1e.0: reg 0x18: [mem 0x4000088000-0x400008ffff 64bit]"}
- {"dt": "2025-07-09T13:17:42.310998", "lvl": "target", "msg": "pci 0000:00:1e.0: reg 0x20: [mem 0x4000091000-0x4000091fff 64bit]"}
- {"dt": "2025-07-09T13:17:42.311330", "lvl": "target", "msg": "pci 0000:00:1e.0: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.312440", "lvl": "target", "msg": "pci 0000:00:1e.0: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.315241", "lvl": "target", "msg": "pci 0000:00:1e.1: [13b5:ff80] type 00 class 0xff0000"}
- {"dt": "2025-07-09T13:17:42.316352", "lvl": "target", "msg": "pci 0000:00:1e.1: reg 0x10: [mem 0x4000000000-0x400003ffff 64bit]"}
- {"dt": "2025-07-09T13:17:42.316905", "lvl": "target", "msg": "pci 0000:00:1e.1: reg 0x18: [mem 0x4000080000-0x4000087fff 64bit]"}
- {"dt": "2025-07-09T13:17:42.317470", "lvl": "target", "msg": "pci 0000:00:1e.1: reg 0x20: [mem 0x4000090000-0x4000090fff 64bit]"}
- {"dt": "2025-07-09T13:17:42.318587", "lvl": "target", "msg": "pci 0000:00:1e.1: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.319152", "lvl": "target", "msg": "pci 0000:00:1e.1: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.321980", "lvl": "target", "msg": "pci 0000:00:1f.0: [0abc:aced] type 00 class 0x010601"}
- {"dt": "2025-07-09T13:17:42.322549", "lvl": "target", "msg": "pci 0000:00:1f.0: reg 0x10: [mem 0x50806000-0x50807fff]"}
- {"dt": "2025-07-09T13:17:42.324787", "lvl": "target", "msg": "pci 0000:00:1f.0: reg 0x14: [mem 0x50804000-0x50805fff]"}
- {"dt": "2025-07-09T13:17:42.326462", "lvl": "target", "msg": "pci 0000:00:1f.0: reg 0x18: [mem 0x5080c000-0x5080cfff]"}
- {"dt": "2025-07-09T13:17:42.327033", "lvl": "target", "msg": "pci 0000:00:1f.0: reg 0x1c: [mem 0x50802000-0x50803fff]"}
- {"dt": "2025-07-09T13:17:42.328138", "lvl": "target", "msg": "pci 0000:00:1f.0: reg 0x20: [mem 0x5080b000-0x5080bfff]"}
- {"dt": "2025-07-09T13:17:42.328717", "lvl": "target", "msg": "pci 0000:00:1f.0: reg 0x24: [mem 0x50800000-0x50801fff]"}
- {"dt": "2025-07-09T13:17:42.329278", "lvl": "target", "msg": "pci 0000:00:1f.0: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.330388", "lvl": "target", "msg": "pci 0000:00:1f.0: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.336568", "lvl": "target", "msg": "pci 0000:01:00.0: [0abc:aced] type 00 class 0x010601"}
- {"dt": "2025-07-09T13:17:42.337102", "lvl": "target", "msg": "pci 0000:01:00.0: reg 0x10: [mem 0x50706000-0x50707fff]"}
- {"dt": "2025-07-09T13:17:42.338190", "lvl": "target", "msg": "pci 0000:01:00.0: reg 0x14: [mem 0x50704000-0x50705fff]"}
- {"dt": "2025-07-09T13:17:42.338766", "lvl": "target", "msg": "pci 0000:01:00.0: reg 0x18: [mem 0x50709000-0x50709fff]"}
- {"dt": "2025-07-09T13:17:42.339332", "lvl": "target", "msg": "pci 0000:01:00.0: reg 0x1c: [mem 0x50702000-0x50703fff]"}
- {"dt": "2025-07-09T13:17:42.339881", "lvl": "target", "msg": "pci 0000:01:00.0: reg 0x20: [mem 0x50708000-0x50708fff]"}
- {"dt": "2025-07-09T13:17:42.341017", "lvl": "target", "msg": "pci 0000:01:00.0: reg 0x24: [mem 0x50700000-0x50701fff]"}
- {"dt": "2025-07-09T13:17:42.341580", "lvl": "target", "msg": "pci 0000:01:00.0: enabling Extended Tags"}
- {"dt": "2025-07-09T13:17:42.342131", "lvl": "target", "msg": "pci 0000:01:00.0: PME# supported from D3hot"}
- {"dt": "2025-07-09T13:17:42.346324", "lvl": "target", "msg": "pci_bus 0000:01: busn_res: [bus 01] end is updated to 01"}
- {"dt": "2025-07-09T13:17:42.349097", "lvl": "target", "msg": "pci_bus 0000:02: busn_res: can not insert [bus 02-01] under [bus 00-01] (conflicts with (null) [bus 00-01])"}
- {"dt": "2025-07-09T13:17:42.349458", "lvl": "target", "msg": "pci_bus 0000:02: busn_res: [bus 02-01] end is updated to 02"}
- {"dt": "2025-07-09T13:17:42.350166", "lvl": "target", "msg": "pci_bus 0000:02: busn_res: can not insert [bus 02] under [bus 00-01] (conflicts with (null) [bus 00-01])"}
- {"dt": "2025-07-09T13:17:42.351873", "lvl": "target", "msg": "pci_bus 0000:03: busn_res: can not insert [bus 03-01] under [bus 00-01] (conflicts with (null) [bus 00-01])"}
- {"dt": "2025-07-09T13:17:42.352236", "lvl": "target", "msg": "pci_bus 0000:03: busn_res: [bus 03-01] end is updated to 03"}
- {"dt": "2025-07-09T13:17:42.352990", "lvl": "target", "msg": "pci_bus 0000:03: busn_res: can not insert [bus 03] under [bus 00-01] (conflicts with (null) [bus 00-01])"}
- {"dt": "2025-07-09T13:17:42.355276", "lvl": "target", "msg": "pci_bus 0000:04: busn_res: can not insert [bus 04-01] under [bus 00-01] (conflicts with (null) [bus 00-01])"}
- {"dt": "2025-07-09T13:17:42.355628", "lvl": "target", "msg": "pci_bus 0000:04: busn_res: [bus 04-01] end is updated to 04"}
- {"dt": "2025-07-09T13:17:42.355694", "lvl": "target", "msg": "pci_bus 0000:04: busn_res: can not insert [bus 04] under [bus 00-01] (conflicts with (null) [bus 00-01])"}
- {"dt": "2025-07-09T13:17:42.358450", "lvl": "target", "msg": "pci 0000:00:01.0: BAR 8: assigned [mem 0x50000000-0x500fffff]"}
- {"dt": "2025-07-09T13:17:42.358994", "lvl": "target", "msg": "pci 0000:00:1e.0: BAR 0: assigned [mem 0x50100000-0x5013ffff 64bit]"}
- {"dt": "2025-07-09T13:17:42.359557", "lvl": "target", "msg": "pci 0000:00:1e.1: BAR 0: assigned [mem 0x50140000-0x5017ffff 64bit]"}
- {"dt": "2025-07-09T13:17:42.360660", "lvl": "target", "msg": "pci 0000:00:1e.0: BAR 2: assigned [mem 0x50180000-0x50187fff 64bit]"}
- {"dt": "2025-07-09T13:17:42.361221", "lvl": "target", "msg": "pci 0000:00:1e.1: BAR 2: assigned [mem 0x50188000-0x5018ffff 64bit]"}
- {"dt": "2025-07-09T13:17:42.362097", "lvl": "target", "msg": "pci 0000:00:1f.0: BAR 0: assigned [mem 0x50190000-0x50191fff]"}
- {"dt": "2025-07-09T13:17:42.362442", "lvl": "target", "msg": "pci 0000:00:1f.0: BAR 1: assigned [mem 0x50192000-0x50193fff]"}
- {"dt": "2025-07-09T13:17:42.362969", "lvl": "target", "msg": "pci 0000:00:1f.0: BAR 3: assigned [mem 0x50194000-0x50195fff]"}
- {"dt": "2025-07-09T13:17:42.363519", "lvl": "target", "msg": "pci 0000:00:1f.0: BAR 5: assigned [mem 0x50196000-0x50197fff]"}
- {"dt": "2025-07-09T13:17:42.364075", "lvl": "target", "msg": "pci 0000:00:01.0: BAR 0: assigned [mem 0x50198000-0x50198fff]"}
- {"dt": "2025-07-09T13:17:42.365746", "lvl": "target", "msg": "pci 0000:00:02.0: BAR 0: assigned [mem 0x50199000-0x50199fff pref]"}
- {"dt": "2025-07-09T13:17:42.366830", "lvl": "target", "msg": "pci 0000:00:03.0: BAR 0: assigned [mem 0x5019a000-0x5019afff pref]"}
- {"dt": "2025-07-09T13:17:42.367948", "lvl": "target", "msg": "pci 0000:00:04.0: BAR 0: assigned [mem 0x5019b000-0x5019bfff pref]"}
- {"dt": "2025-07-09T13:17:42.368523", "lvl": "target", "msg": "pci 0000:00:1e.0: BAR 4: assigned [mem 0x5019c000-0x5019cfff 64bit]"}
- {"dt": "2025-07-09T13:17:42.369098", "lvl": "target", "msg": "pci 0000:00:1e.1: BAR 4: assigned [mem 0x5019d000-0x5019dfff 64bit]"}
- {"dt": "2025-07-09T13:17:42.370220", "lvl": "target", "msg": "pci 0000:00:1f.0: BAR 2: assigned [mem 0x5019e000-0x5019efff]"}
- {"dt": "2025-07-09T13:17:42.370793", "lvl": "target", "msg": "pci 0000:00:1f.0: BAR 4: assigned [mem 0x5019f000-0x5019ffff]"}
- {"dt": "2025-07-09T13:17:42.371363", "lvl": "target", "msg": "pci 0000:01:00.0: BAR 0: assigned [mem 0x50000000-0x50001fff]"}
- {"dt": "2025-07-09T13:17:42.371930", "lvl": "target", "msg": "pci 0000:01:00.0: BAR 1: assigned [mem 0x50002000-0x50003fff]"}
- {"dt": "2025-07-09T13:17:42.372505", "lvl": "target", "msg": "pci 0000:01:00.0: BAR 3: assigned [mem 0x50004000-0x50005fff]"}
- {"dt": "2025-07-09T13:17:42.373050", "lvl": "target", "msg": "pci 0000:01:00.0: BAR 5: assigned [mem 0x50006000-0x50007fff]"}
- {"dt": "2025-07-09T13:17:42.373614", "lvl": "target", "msg": "pci 0000:01:00.0: BAR 2: assigned [mem 0x50008000-0x50008fff]"}
- {"dt": "2025-07-09T13:17:42.374176", "lvl": "target", "msg": "pci 0000:01:00.0: BAR 4: assigned [mem 0x50009000-0x50009fff]"}
- {"dt": "2025-07-09T13:17:42.374747", "lvl": "target", "msg": "pci 0000:00:01.0: PCI bridge to [bus 01]"}
- {"dt": "2025-07-09T13:17:42.375310", "lvl": "target", "msg": "pci 0000:00:01.0:   bridge window [mem 0x50000000-0x500fffff]"}
- {"dt": "2025-07-09T13:17:42.375871", "lvl": "target", "msg": "pci 0000:00:02.0: PCI bridge to [bus 02]"}
- {"dt": "2025-07-09T13:17:42.377001", "lvl": "target", "msg": "pci 0000:00:03.0: PCI bridge to [bus 03]"}
- {"dt": "2025-07-09T13:17:42.377565", "lvl": "target", "msg": "pci 0000:00:04.0: PCI bridge to [bus 04]"}
- {"dt": "2025-07-09T13:17:42.916895", "lvl": "target", "msg": "arm-smmu-v3 2b400000.iommu: ias 48-bit, oas 48-bit (features 0x000cdfcf)"}
- {"dt": "2025-07-09T13:17:42.917380", "lvl": "target", "msg": "arm-smmu-v3 2b400000.iommu: allocated 128 entries for cmdq"}
- {"dt": "2025-07-09T13:17:42.917897", "lvl": "target", "msg": "arm-smmu-v3 2b400000.iommu: allocated 128 entries for evtq"}
- {"dt": "2025-07-09T13:17:42.918167", "lvl": "target", "msg": "arm-smmu-v3 2b400000.iommu: 2-level strtab only covers 25/32 bits of SID"}
- {"dt": "2025-07-09T13:17:43.020364", "lvl": "target", "msg": "virtio_blk virtio0: 1/0/0 default/read/poll queues"}
- {"dt": "2025-07-09T13:17:43.030324", "lvl": "target", "msg": "virtio_blk virtio0: [vda] 5640000 512-byte logical blocks (2.89 GB/2.69 GiB)"}
- {"dt": "2025-07-09T13:17:43.058797", "lvl": "target", "msg": "basic-mmio-gpio: Failed to locate of_node [id: -2]"}
- {"dt": "2025-07-09T13:17:43.061546", "lvl": "target", "msg": "basic-mmio-gpio: Failed to locate of_node [id: -2]"}
- {"dt": "2025-07-09T13:17:43.063767", "lvl": "target", "msg": "basic-mmio-gpio: Failed to locate of_node [id: -2]"}
- {"dt": "2025-07-09T13:17:43.092188", "lvl": "target", "msg": "rtc-pl031 1c170000.rtc: registered as rtc0"}
- {"dt": "2025-07-09T13:17:43.093242", "lvl": "target", "msg": "rtc-pl031 1c170000.rtc: setting system clock to 2025-07-09T13:17:34 UTC (1752067054)"}
- {"dt": "2025-07-09T13:17:43.096043", "lvl": "target", "msg": "SMCCC: SOC_ID: ID = jep106:043b:0000 Revision = 0x00000002"}
- {"dt": "2025-07-09T13:17:43.115602", "lvl": "target", "msg": "hw perfevents: enabled with armv8_pmuv3 PMU driver, 9 counters available"}
- {"dt": "2025-07-09T13:17:43.119491", "lvl": "target", "msg": "NET: Registered PF_INET6 protocol family"}
- {"dt": "2025-07-09T13:17:43.133999", "lvl": "target", "msg": "Segment Routing with IPv6"}
- {"dt": "2025-07-09T13:17:43.134538", "lvl": "target", "msg": "In-situ OAM (IOAM) with IPv6"}
- {"dt": "2025-07-09T13:17:43.135100", "lvl": "target", "msg": "sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2025-07-09T13:17:43.140678", "lvl": "target", "msg": "NET: Registered PF_PACKET protocol family"}
- {"dt": "2025-07-09T13:17:43.141218", "lvl": "target", "msg": "NET: Registered PF_KEY protocol family"}
- {"dt": "2025-07-09T13:17:43.145146", "lvl": "target", "msg": "NET: Registered PF_VSOCK protocol family"}
- {"dt": "2025-07-09T13:17:43.191915", "lvl": "target", "msg": "registered taskstats version 1"}
- {"dt": "2025-07-09T13:17:43.265287", "lvl": "target", "msg": "clk: Disabling unused clocks"}
- {"dt": "2025-07-09T13:17:43.327127", "lvl": "target", "msg": "EXT4-fs (vda): mounted filesystem 4957d22b-b852-41f1-8edf-579d372c2e16 r/w with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-07-09T13:17:43.329250", "lvl": "target", "msg": "VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2025-07-09T13:17:43.332619", "lvl": "target", "msg": "devtmpfs: mounted"}
- {"dt": "2025-07-09T13:17:43.339298", "lvl": "target", "msg": "Freeing unused kernel memory: 1408K"}
- {"dt": "2025-07-09T13:17:43.361561", "lvl": "target", "msg": "Run /sbin/init as init process"}
- {"dt": "2025-07-09T13:17:44.289619", "lvl": "target", "msg": "systemd[1]: Failed to find module 'autofs4'"}
- {"dt": "2025-07-09T13:17:44.740921", "lvl": "target", "msg": "[!p]104\a[?7h"}
- {"dt": "2025-07-09T13:17:44.741071", "lvl": "target", "msg": "[0;1;39mWelcome to [0m[1mDebian GNU/Linux 13 (trixie)[0m[0;1;39m![0m"}
- {"dt": "2025-07-09T13:17:44.741155", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:17:54.241601", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty."}
- {"dt": "2025-07-09T13:17:54.263813", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe."}
- {"dt": "2025-07-09T13:17:54.285155", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-serial\\x2dget\u2026slice[0m - Slice /system/serial-getty."}
- {"dt": "2025-07-09T13:17:54.304370", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-xfs_scrub.sli\u2026[0mxfs_scrub background service slice."}
- {"dt": "2025-07-09T13:17:54.318847", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice."}
- {"dt": "2025-07-09T13:17:54.324975", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-conso\u2026[0mequests to Console Directory Watch."}
- {"dt": "2025-07-09T13:17:54.329423", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-wall.\u2026[0md Requests to Wall Directory Watch."}
- {"dt": "2025-07-09T13:17:54.332464", "lvl": "target", "msg": "systemd[1]: Starting of proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point unsupported."}
- {"dt": "2025-07-09T13:17:54.333392", "lvl": "target", "msg": "[[0;1;38:5:185mUNSUPP[0m] Starting of [0;1;39mproc-sys-fs-binfmt_mis\u2026[0mSystem Automount Point unsupported."}
- {"dt": "2025-07-09T13:17:54.336745", "lvl": "target", "msg": "         Expecting device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0..."}
- {"dt": "2025-07-09T13:17:54.338990", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork.target[0m - Network."}
- {"dt": "2025-07-09T13:17:54.340652", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online."}
- {"dt": "2025-07-09T13:17:54.342321", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnss-lookup.target[0m - Host and Network Name Lookups."}
- {"dt": "2025-07-09T13:17:54.343463", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units."}
- {"dt": "2025-07-09T13:17:54.345703", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units."}
- {"dt": "2025-07-09T13:17:54.348507", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps."}
- {"dt": "2025-07-09T13:17:54.362445", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mrpcbind.socket[0m - RPCbind Server Activation Socket."}
- {"dt": "2025-07-09T13:17:54.411465", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-creds.socket[0m - Credential Encryption/Decryption."}
- {"dt": "2025-07-09T13:17:54.418147", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initctl.socke\u2026[0m- initctl Compatibility Named Pipe."}
- {"dt": "2025-07-09T13:17:54.423735", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald-dev-\u2026socket[0m - Journal Socket (/dev/log)."}
- {"dt": "2025-07-09T13:17:54.429870", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Sockets."}
- {"dt": "2025-07-09T13:17:54.437338", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket."}
- {"dt": "2025-07-09T13:17:54.440469", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket."}
- {"dt": "2025-07-09T13:17:54.514564", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System..."}
- {"dt": "2025-07-09T13:17:54.611693", "lvl": "target", "msg": "         Mounting [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock..."}
- {"dt": "2025-07-09T13:17:54.790385", "lvl": "target", "msg": "         Mounting [0;1;39mtmp.mount[0m - Temporary Directory /tmp..."}
- {"dt": "2025-07-09T13:17:54.981342", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs..."}
- {"dt": "2025-07-09T13:17:55.156238", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm..."}
- {"dt": "2025-07-09T13:17:55.351173", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_pstore.servi\u2026[0m - Load Kernel Module efi_pstore..."}
- {"dt": "2025-07-09T13:17:55.572062", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse..."}
- {"dt": "2025-07-09T13:17:55.683241", "lvl": "target", "msg": "systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling."}
- {"dt": "2025-07-09T13:17:55.684340", "lvl": "target", "msg": "systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.)"}
- {"dt": "2025-07-09T13:17:55.973420", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service..."}
- {"dt": "2025-07-09T13:17:56.328165", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules..."}
- {"dt": "2025-07-09T13:17:57.227347", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-fs.servic\u2026[0munt Root and Kernel File Systems..."}
- {"dt": "2025-07-09T13:17:57.684165", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0m Device Nodes in /dev gracefully..."}
- {"dt": "2025-07-09T13:17:58.084058", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-load-credent\u2026[0mLoad udev Rules from Credentials..."}
- {"dt": "2025-07-09T13:17:58.573468", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices..."}
- {"dt": "2025-07-09T13:17:59.072913", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System."}
- {"dt": "2025-07-09T13:17:59.112483", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock."}
- {"dt": "2025-07-09T13:17:59.510545", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mtmp.mount[0m - Temporary Directory /tmp."}
- {"dt": "2025-07-09T13:17:59.662787", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs."}
- {"dt": "2025-07-09T13:18:00.877512", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse."}
- {"dt": "2025-07-09T13:18:01.342938", "lvl": "target", "msg": "         Mounting [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System..."}
- {"dt": "2025-07-09T13:18:01.932363", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore."}
- {"dt": "2025-07-09T13:18:02.317816", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm."}
- {"dt": "2025-07-09T13:18:03.055502", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules."}
- {"dt": "2025-07-09T13:18:03.104467", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-fs.servic\u2026[0mmount Root and Kernel File Systems."}
- {"dt": "2025-07-09T13:18:03.214485", "lvl": "target", "msg": "         Mounting [0;1;39mproc-fs-nfsd.mount[0m - NFSD configuration filesystem..."}
- {"dt": "2025-07-09T13:18:03.522129", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed..."}
- {"dt": "2025-07-09T13:18:03.959611", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables..."}
- {"dt": "2025-07-09T13:18:04.881244", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-load-credent\u2026[0m- Load udev Rules from Credentials."}
- {"dt": "2025-07-09T13:18:05.083161", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mic Device Nodes in /dev gracefully."}
- {"dt": "2025-07-09T13:18:05.319891", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.service[0m - Create System Users..."}
- {"dt": "2025-07-09T13:18:05.554661", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System."}
- {"dt": "2025-07-09T13:18:07.454841", "lvl": "target", "msg": "systemd[1]: proc-fs-nfsd.mount: Failed with result 'exit-code'."}
- {"dt": "2025-07-09T13:18:07.507250", "lvl": "target", "msg": "systemd[1]: Failed to mount proc-fs-nfsd.mount - NFSD configuration filesystem."}
- {"dt": "2025-07-09T13:18:07.510521", "lvl": "target", "msg": "[[0;1;31mFAILED[0m] Failed to mount [0;1;39mproc-fs-nfsd.mount[0m - NFSD configuration filesystem."}
- {"dt": "2025-07-09T13:18:07.513858", "lvl": "target", "msg": "See 'systemctl status proc-fs-nfsd.mount' for details."}
- {"dt": "2025-07-09T13:18:07.516070", "lvl": "target", "msg": "systemd[1]: Dependency failed for nfsdcld.service - NFSv4 Client Tracking Daemon."}
- {"dt": "2025-07-09T13:18:07.518870", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfsdcld.service[0m - NFSv4 Client Tracking Daemon."}
- {"dt": "2025-07-09T13:18:07.522770", "lvl": "target", "msg": "systemd[1]: Dependency failed for nfs-mountd.service - NFS Mount Daemon."}
- {"dt": "2025-07-09T13:18:07.525577", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-mountd.service[0m - NFS Mount Daemon."}
- {"dt": "2025-07-09T13:18:07.530047", "lvl": "target", "msg": "systemd[1]: Dependency failed for nfs-server.service - NFS server and services."}
- {"dt": "2025-07-09T13:18:07.533438", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-server.service[0m - NFS server and services."}
- {"dt": "2025-07-09T13:18:07.537864", "lvl": "target", "msg": "systemd[1]: Dependency failed for nfs-idmapd.service - NFSv4 ID-name mapping service."}
- {"dt": "2025-07-09T13:18:07.541223", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-idmapd.s\u2026ce[0m - NFSv4 ID-name mapping service."}
- {"dt": "2025-07-09T13:18:08.049166", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed."}
- {"dt": "2025-07-09T13:18:08.342739", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables."}
- {"dt": "2025-07-09T13:18:09.554399", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users."}
- {"dt": "2025-07-09T13:18:09.678430", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0meate Static Device Nodes in /dev..."}
- {"dt": "2025-07-09T13:18:12.868466", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mCreate Static Device Nodes in /dev."}
- {"dt": "2025-07-09T13:18:12.941155", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pre.target[0m\u2026Preparation for Local File Systems."}
- {"dt": "2025-07-09T13:18:12.947190", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems."}
- {"dt": "2025-07-09T13:18:13.232242", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-sysext.socket[0m\u2026 System Extension Image Management."}
- {"dt": "2025-07-09T13:18:13.345726", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd-notify.service[0m - Notify NFS peers of a restart..."}
- {"dt": "2025-07-09T13:18:13.613627", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.service[0m - R\u2026ager for Device Events and Files..."}
- {"dt": "2025-07-09T13:18:14.228641", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service."}
- {"dt": "2025-07-09T13:18:16.112276", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd-notify.service[0m - Notify NFS peers of a restart."}
- {"dt": "2025-07-09T13:18:16.488629", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-flush.ser\u2026[0msh Journal to Persistent Storage..."}
- {"dt": "2025-07-09T13:18:20.043415", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-flush.ser\u2026[0mlush Journal to Persistent Storage."}
- {"dt": "2025-07-09T13:18:20.182411", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup.se\u2026[0mate System Files and Directories..."}
- {"dt": "2025-07-09T13:18:24.050854", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.service[0m - Ru\u2026anager for Device Events and Files."}
- {"dt": "2025-07-09T13:18:24.223199", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup.se\u2026[0mreate System Files and Directories."}
- {"dt": "2025-07-09T13:18:24.333221", "lvl": "target", "msg": "         Mounting [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System..."}
- {"dt": "2025-07-09T13:18:24.448127", "lvl": "target", "msg": "         Starting [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache..."}
- {"dt": "2025-07-09T13:18:24.607379", "lvl": "target", "msg": "         Starting [0;1;39mrpcbind.service[0m - RPC bind portmap service..."}
- {"dt": "2025-07-09T13:18:24.791972", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-catalog-u\u2026ervice[0m - Rebuild Journal Catalog..."}
- {"dt": "2025-07-09T13:18:27.037731", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpcbind.service[0m - RPC bind portmap service."}
- {"dt": "2025-07-09T13:18:27.119497", "lvl": "target", "msg": "[[0;1;31mFAILED[0m] Failed to mount [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System."}
- {"dt": "2025-07-09T13:18:27.122303", "lvl": "target", "msg": "See 'systemctl status run-rpc_pipefs.mount' for details."}
- {"dt": "2025-07-09T13:18:27.129532", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mrpc_pipefs.target[0m."}
- {"dt": "2025-07-09T13:18:27.137409", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mrpc-gssd.ser\u2026[0m service for NFS client and server."}
- {"dt": "2025-07-09T13:18:27.144046", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-blkmap.s\u2026[0m- pNFS block layout mapping daemon."}
- {"dt": "2025-07-09T13:18:27.179810", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnfs-client.target[0m - NFS client services."}
- {"dt": "2025-07-09T13:18:27.186394", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs-pre.targe\u2026[0mreparation for Remote File Systems."}
- {"dt": "2025-07-09T13:18:27.191427", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems."}
- {"dt": "2025-07-09T13:18:27.196434", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpcbind.target[0m - RPC Port Mapper."}
- {"dt": "2025-07-09T13:18:27.326492", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd.service[0m - NFS s\u2026tus monitor for NFSv2/3 locking...."}
- {"dt": "2025-07-09T13:18:33.290729", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd.service[0m - NFS status monitor for NFSv2/3 locking.."}
- {"dt": "2025-07-09T13:18:34.473378", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices."}
- {"dt": "2025-07-09T13:18:35.257408", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache."}
- {"dt": "2025-07-09T13:18:37.650503", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-catalog-u\u2026.service[0m - Rebuild Journal Catalog."}
- {"dt": "2025-07-09T13:18:46.431818", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0."}
- {"dt": "2025-07-09T13:19:07.459376", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-done.service[0m - Update is Completed..."}
- {"dt": "2025-07-09T13:19:08.102377", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-done.service[0m - Update is Completed."}
- {"dt": "2025-07-09T13:19:08.113978", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msysinit.target[0m - System Initialization."}
- {"dt": "2025-07-09T13:19:08.144049", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities."}
- {"dt": "2025-07-09T13:19:08.152364", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - \u2026y apt upgrade and clean activities."}
- {"dt": "2025-07-09T13:19:08.156253", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer."}
- {"dt": "2025-07-09T13:19:08.165113", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39me2scrub_all.timer[0m - Period\u2026Metadata Check for All Filesystems."}
- {"dt": "2025-07-09T13:19:08.173454", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week."}
- {"dt": "2025-07-09T13:19:08.177372", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files."}
- {"dt": "2025-07-09T13:19:08.179240", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-tmpfiles-clean.tim\u2026[0my Cleanup of Temporary Directories."}
- {"dt": "2025-07-09T13:19:08.193561", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mxfs_scrub_all.timer[0m - Peri\u2026Metadata Check for All Filesystems."}
- {"dt": "2025-07-09T13:19:08.195781", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units."}
- {"dt": "2025-07-09T13:19:08.206941", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket."}
- {"dt": "2025-07-09T13:19:08.211976", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-hostnamed.socket[0m - Hostname Service Socket."}
- {"dt": "2025-07-09T13:19:08.216982", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39muuidd.socket[0m - UUID daemon activation socket."}
- {"dt": "2025-07-09T13:19:08.218303", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units."}
- {"dt": "2025-07-09T13:19:08.222527", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mbasic.target[0m - Basic System."}
- {"dt": "2025-07-09T13:19:08.317615", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon."}
- {"dt": "2025-07-09T13:19:08.414009", "lvl": "target", "msg": "         Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus..."}
- {"dt": "2025-07-09T13:19:08.598638", "lvl": "target", "msg": "         Starting [0;1;39me2scrub_reap.service[0m - Re\u2026ne ext4 Metadata Check Snapshots..."}
- {"dt": "2025-07-09T13:19:08.763789", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfsidd.service[0m - NFS FSID Daemon."}
- {"dt": "2025-07-09T13:19:08.987262", "lvl": "target", "msg": "         Starting [0;1;39msystemd-logind.service[0m - User Login Management..."}
- {"dt": "2025-07-09T13:19:09.161847", "lvl": "target", "msg": "         Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions..."}
- {"dt": "2025-07-09T13:19:12.406411", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus."}
- {"dt": "2025-07-09T13:19:13.186011", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions."}
- {"dt": "2025-07-09T13:19:13.523856", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1."}
- {"dt": "2025-07-09T13:19:13.683410", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mserial-getty@ttyAMA0.service[0m - Serial Getty on ttyAMA0."}
- {"dt": "2025-07-09T13:19:13.692340", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts."}
- {"dt": "2025-07-09T13:19:13.794221", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39me2scrub_reap.service[0m - Re\u2026line ext4 Metadata Check Snapshots."}
- {"dt": "2025-07-09T13:19:15.848748", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management."}
- {"dt": "2025-07-09T13:19:15.859950", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System."}
- {"dt": "2025-07-09T13:19:15.863824", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface."}
- {"dt": "2025-07-09T13:19:17.655861", "lvl": "target", "msg": "[!p]104\a[?7h[6n[32766;32766H[6n[!p]104\a[?7h[6n[32766;32766H[6n"}
- {"dt": "2025-07-09T13:19:17.656271", "lvl": "target", "msg": "Debian GNU/Linux 13 runner-ns46nmmj-project-40964107-concurrent-0 ttyAMA0"}
- {"dt": "2025-07-09T13:19:17.656352", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:19:17.657099", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2025-07-09T13:19:17.657174", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.3.1", "duration": "98.27", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2025-07-09T13:19:17.657270", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2025-07-09T13:19:17.657338", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2025-07-09T13:19:17.757595", "lvl": "input", "msg": "root"}
- {"dt": "2025-07-09T13:19:17.757824", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-07-09T13:19:17.757908", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:28:15)"}
- {"dt": "2025-07-09T13:19:17.757986", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:14:08)"}
- {"dt": "2025-07-09T13:19:17.832671", "lvl": "target", "msg": "runner-ns46nmmj-project-40964107-concurrent-0 login: root"}
- {"dt": "2025-07-09T13:19:19.212858", "lvl": "target", "msg": "Linux runner-ns46nmmj-project-40964107-concurrent-0 6.4.16 #1 SMP Tue Nov 19 14:45:23 UTC 2024 aarch64"}
- {"dt": "2025-07-09T13:19:19.215592", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:19:19.215694", "lvl": "target", "msg": "The programs included with the Debian GNU/Linux system are free software;"}
- {"dt": "2025-07-09T13:19:19.216091", "lvl": "target", "msg": "the exact distribution terms for each program are described in the"}
- {"dt": "2025-07-09T13:19:19.216165", "lvl": "target", "msg": "individual files in /usr/share/doc/*/copyright."}
- {"dt": "2025-07-09T13:19:19.216224", "lvl": "target", "msg": ""}
- {"dt": "2025-07-09T13:19:19.216455", "lvl": "target", "msg": "Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent"}
- {"dt": "2025-07-09T13:19:19.216525", "lvl": "target", "msg": "permitted by applicable law."}
- {"dt": "2025-07-09T13:19:42.454831", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-07-09T13:19:42.455013", "lvl": "debug", "msg": "end: 2.3.1 login-action (duration 00:02:03) [common]"}
- {"dt": "2025-07-09T13:19:42.455104", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.3.1", "duration": "123.07", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2025-07-09T13:19:42.455253", "lvl": "debug", "msg": "end: 2.3 auto-login-action (duration 00:02:05) [common]"}
- {"dt": "2025-07-09T13:19:42.455348", "lvl": "debug", "msg": "start: 2.4 expect-shell-connection (timeout 00:27:51) [common]"}
- {"dt": "2025-07-09T13:19:42.455418", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-07-09T13:19:42.455479", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2025-07-09T13:19:42.455540", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2025-07-09T13:19:42.505692", "lvl": "input", "msg": ""}
- {"dt": "2025-07-09T13:19:42.505928", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:30:00)"}
- {"dt": "2025-07-09T13:19:42.506009", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2025-07-09T13:19:42.510684", "lvl": "target", "msg": "[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# "}
- {"dt": "2025-07-09T13:19:42.513647", "lvl": "debug", "msg": "end: 2.4 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:19:42.513763", "lvl": "info", "msg": "end: 2 boot-fvp (duration 00:02:09) [common]"}
- {"dt": "2025-07-09T13:19:42.513857", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:02:00) [common]"}
- {"dt": "2025-07-09T13:19:42.513947", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:02:00) [common]"}
- {"dt": "2025-07-09T13:19:42.514022", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2025-07-09T13:19:42.514101", "lvl": "debug", "msg": "Using a character delay of 50 (ms)"}
- {"dt": "2025-07-09T13:19:42.514168", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2025-07-09T13:19:42.614434", "lvl": "input", "msg": "#"}
- {"dt": "2025-07-09T13:19:42.614681", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:02:00)"}
- {"dt": "2025-07-09T13:19:42.628980", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# #"}
- {"dt": "2025-07-09T13:19:42.633546", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2025-07-09T13:19:42.633631", "lvl": "debug", "msg": "Sending with 50 millisecond of delay"}
- {"dt": "2025-07-09T13:19:42.633709", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2025-07-09T13:19:44.686688", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2025-07-09T13:19:44.736982", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# export SHELL=/bin/sh"}
- {"dt": "2025-07-09T13:19:44.737293", "lvl": "debug", "msg": "Sending with 50 millisecond of delay"}
- {"dt": "2025-07-09T13:19:44.737366", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2025-07-09T13:19:46.890389", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2025-07-09T13:19:46.940702", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# . /lava-1/environment"}
- {"dt": "2025-07-09T13:19:46.941034", "lvl": "debug", "msg": "Will listen to feedbacks from 'terminal_1' for 1 second"}
- {"dt": "2025-07-09T13:19:46.941115", "lvl": "debug", "msg": "Will listen to feedbacks from 'terminal_2' for 1 second"}
- {"dt": "2025-07-09T13:19:46.941178", "lvl": "debug", "msg": "Will listen to feedbacks from 'terminal_3' for 1 second"}
- {"dt": "2025-07-09T13:19:46.941240", "lvl": "debug", "msg": "Sending with 50 millisecond of delay"}
- {"dt": "2025-07-09T13:19:46.941290", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2025-07-09T13:19:50.796831", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-07-09T13:19:50.847083", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2025-07-09T13:19:50.847492", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# /lava-1/bin/lava-test-runn \rer /lava-1/0"}
- {"dt": "2025-07-09T13:19:51.748886", "lvl": "target", "msg": "[?2004l\r+ export TESTRUN_ID=0_commands"}
- {"dt": "2025-07-09T13:19:51.749461", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_commands"}
- {"dt": "2025-07-09T13:19:51.772888", "lvl": "target", "msg": "+ cat uuid"}
- {"dt": "2025-07-09T13:19:51.839787", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2025-07-09T13:19:51.840239", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-07-09T13:19:51.841914", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_commands 1_1.1.3.1>"}
- {"dt": "2025-07-09T13:19:51.842150", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_commands 1_1.1.3.1"}
- {"dt": "2025-07-09T13:19:51.842203", "lvl": "info", "msg": "Starting test lava.0_commands (1_1.1.3.1)"}
- {"dt": "2025-07-09T13:19:51.842268", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2025-07-09T13:19:51.842485", "lvl": "target", "msg": "+ lava-test-case command --shell ./Image.gz"}
- {"dt": "2025-07-09T13:19:51.905482", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTTC command>"}
- {"dt": "2025-07-09T13:19:51.905809", "lvl": "debug", "msg": "Received signal: <STARTTC> command"}
- {"dt": "2025-07-09T13:19:51.914317", "lvl": "target", "msg": "/lava-1/0/../bin/lava-test-case: 1: eval: ./Image.gz: not found"}
- {"dt": "2025-07-09T13:19:51.919329", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDTC command>"}
- {"dt": "2025-07-09T13:19:51.919583", "lvl": "debug", "msg": "Received signal: <ENDTC> command"}
- {"dt": "2025-07-09T13:19:52.005080", "lvl": "target", "msg": "<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=command RESULT=fail>"}
- {"dt": "2025-07-09T13:19:52.005409", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=command RESULT=fail"}
- {"dt": "2025-07-09T13:19:52.005503", "lvl": "results", "msg": {"definition": "0_commands", "case": "command", "result": "fail", "starttc": ! "944", "endtc": ! "947"}}
- {"dt": "2025-07-09T13:19:52.013927", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-07-09T13:19:52.014463", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_commands 1_1.1.3.1>"}
- {"dt": "2025-07-09T13:19:52.014706", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_commands 1_1.1.3.1"}
- {"dt": "2025-07-09T13:19:52.014780", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2025-07-09T13:19:52.014818", "lvl": "info", "msg": "Ending test lava.0_commands (1_1.1.3.1), duration 0.17"}
- {"dt": "2025-07-09T13:19:52.014882", "lvl": "results", "msg": {"definition": "lava", "case": "0_commands", "uuid": "1_1.1.3.1", "repository": {"metadata": {"format": "Lava-Test Test Definition 1.0", "name": "commands", "description": "Run commands"}, "run": {"steps": ["lava-test-case command --shell ./Image.gz"]}}, "path": "command", "duration": "0.17", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2025-07-09T13:19:52.039050", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2025-07-09T13:19:52.039372", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2025-07-09T13:19:52.039473", "lvl": "debug", "msg": "command: fail\n"}
- {"dt": "2025-07-09T13:19:52.039565", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:10) [common]"}
- {"dt": "2025-07-09T13:19:52.039646", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:10) [common]"}
- {"dt": "2025-07-09T13:19:52.039734", "lvl": "info", "msg": "start: 4 finalize (timeout 00:10:00) [common]"}
- {"dt": "2025-07-09T13:19:52.039803", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:01:00) [common]"}
- {"dt": "2025-07-09T13:19:52.039860", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2025-07-09T13:19:52.039910", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:10:00) [common]"}
- {"dt": "2025-07-09T13:19:52.039973", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-07-09T13:19:52.050313", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-07-09T13:19:52.050836", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.040492", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2025-07-09T13:19:53.040654", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2025-07-09T13:19:53.040745", "lvl": "target", "msg": "[?2004hroot@runner-ns46nmmj-project-40964107-concurrent-0:~# "}
- {"dt": "2025-07-09T13:19:53.141078", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/ArmPlatformPkg/PeilessSec/PeilessSec/DEBUG/PeilessSec.dll 0x88000400", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141230", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Core/Dxe/DxeMain/DEBUG/DxeCore.dll 0xFB5D0000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141303", "lvl": "feedback", "msg": "Loading DxeCore at 0x00FB5CF000 EntryPoint=0x00FB5D8B6C", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141363", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Core/Dxe/DxeMain/DEBUG/DxeCore.dll 0xFB5D0000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141420", "lvl": "feedback", "msg": "HOBLIST address in DXE = 0xF7DBD018", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141475", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFFB000 - 0xFBFFBFFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141529", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0x88000000 - 0x8BFFFFFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141583", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFFA000 - 0xFBFFAFFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141637", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFF9000 - 0xFBFF9FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141689", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFF8000 - 0xFBFF8FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141740", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFF7000 - 0xFBFF7FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141793", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFF6000 - 0xFBFF6FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141846", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFF5000 - 0xFBFF5FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141898", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFF4000 - 0xFBFF4FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141938", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFF3000 - 0xFBFF3FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.141975", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFF2000 - 0xFBFF2FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142010", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFFC000 - 0xFBFFFFFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142045", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBFE2000 - 0xFBFF1FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142080", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFBAFC000 - 0xFBFE1FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142115", "lvl": "feedback", "msg": "Memory Allocation 0x00000004 0xFB616000 - 0xFBAFBFFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142150", "lvl": "feedback", "msg": "Memory Allocation 0x00000003 0xFB5CF000 - 0xFB615FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142185", "lvl": "feedback", "msg": "Memory Allocation 0x00000003 0xFB5CF000 - 0xFB615FFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142220", "lvl": "feedback", "msg": "FV Hob            0x88000000 - 0x8827FFFF", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142255", "lvl": "feedback", "msg": "FV Hob            0xFB616000 - 0xFBAFA37F", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142290", "lvl": "feedback", "msg": "FV2 Hob           0xFB616000 - 0xFBAFA37F", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142326", "lvl": "feedback", "msg": "                  87940482-FC81-41C3-87E6-399CF85AC8A0 - 9E21FD93-9C72-4C15-8C4B-E77F1DB2D792", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142379", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/PCD/Dxe/Pcd/DEBUG/PcdDxe.dll 0xF7F48000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142430", "lvl": "feedback", "msg": "Loading driver at 0x000F7F47000 EntryPoint=0x000F7F4BE64 PcdDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142645", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.142727", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Core/RuntimeDxe/RuntimeDxe/DEBUG/RuntimeDxe.dll 0xF2F40000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142779", "lvl": "feedback", "msg": "Loading driver at 0x000F2F30000 EntryPoint=0x000F2F41B6C RuntimeDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142829", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/SecurityStubDxe/SecurityStubDxe/DEBUG/SecurityStubDxe.dll 0xF7F3E000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142873", "lvl": "feedback", "msg": "Loading driver at 0x000F7F3D000 EntryPoint=0x000F7F41C90 SecurityStubDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142920", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/ResetSystemRuntimeDxe/ResetSystemRuntimeDxe/DEBUG/ResetSystemRuntimeDxe.dll 0xF2EA0000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.142962", "lvl": "feedback", "msg": "Loading driver at 0x000F2E90000 EntryPoint=0x000F2EA1E38 ResetSystemRuntimeDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143009", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/EmbeddedPkg/MetronomeDxe/MetronomeDxe/DEBUG/MetronomeDxe.dll 0xF7F39000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143050", "lvl": "feedback", "msg": "Loading driver at 0x000F7F38000 EntryPoint=0x000F7F3A0F0 MetronomeDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143096", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/HiiDatabaseDxe/HiiDatabaseDxe/DEBUG/HiiDatabase.dll 0xF7F1A000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143139", "lvl": "feedback", "msg": "Loading driver at 0x000F7F19000 EntryPoint=0x000F7F1D7CC HiiDatabase.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143184", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Acpi/AcpiTableDxe/AcpiTableDxe/DEBUG/AcpiTableDxe.dll 0xF7F13000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143227", "lvl": "feedback", "msg": "Loading driver at 0x000F7F12000 EntryPoint=0x000F7F157A4 AcpiTableDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143272", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/Platform/ARM/VExpressPkg/ConfigurationManager/ConfigurationManagerDxe/ConfigurationManagerDxe/DEBUG/ConfigurationManagerDxe.dll 0xF7F0D000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143313", "lvl": "feedback", "msg": "Loading driver at 0x000F7F0C000 EntryPoint=0x000F7F0E788 ConfigurationManagerDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143360", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/DynamicTablesPkg/Drivers/DynamicTableFactoryDxe/DynamicTableFactoryDxe/DEBUG/DynamicTableFactoryDxe.dll 0xF7ED9000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143401", "lvl": "feedback", "msg": "Loading driver at 0x000F7ED8000 EntryPoint=0x000F7ED99CC DynamicTableFactoryDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143608", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.143685", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/SerialDxe/SerialDxe/DEBUG/SerialDxe.dll 0xF7ED3000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143735", "lvl": "feedback", "msg": "Loading driver at 0x000F7ED2000 EntryPoint=0x000F7ED4760 SerialDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143782", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/ArmPkg/Drivers/ArmGic/ArmGicDxe/DEBUG/ArmGicDxe.dll 0xF7ECC000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143827", "lvl": "feedback", "msg": "Loading driver at 0x000F7ECB000 EntryPoint=0x000F7ECE244 ArmGicDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143873", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/EmbeddedPkg/Universal/MmcDxe/MmcDxe/DEBUG/MmcDxe.dll 0xF7EC1000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143914", "lvl": "feedback", "msg": "Loading driver at 0x000F7EC0000 EntryPoint=0x000F7EC5D44 MmcDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.143960", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/SmbiosDxe/SmbiosDxe/DEBUG/SmbiosDxe.dll 0xF7EB9000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144001", "lvl": "feedback", "msg": "Loading driver at 0x000F7EB8000 EntryPoint=0x000F7EBC174 SmbiosDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144047", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/DevicePathDxe/DevicePathDxe/DEBUG/DevicePathDxe.dll 0xF7EAA000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144087", "lvl": "feedback", "msg": "Loading driver at 0x000F7EA9000 EntryPoint=0x000F7EB2070 DevicePathDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144132", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/ArmPkg/Drivers/ArmPciCpuIo2Dxe/ArmPciCpuIo2Dxe/DEBUG/ArmPciCpuIo2Dxe.dll 0xF7EA5000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144173", "lvl": "feedback", "msg": "Loading driver at 0x000F7EA4000 EntryPoint=0x000F7EA6570 ArmPciCpuIo2Dxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144218", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/ArmPkg/Drivers/CpuDxe/CpuDxe/DEBUG/ArmCpuDxe.dll 0xF7E99000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144259", "lvl": "feedback", "msg": "Loading driver at 0x000F7E98000 EntryPoint=0x000F7E9C47C ArmCpuDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144305", "lvl": "feedback", "msg": "ReplaceTableEntry: splitting block entry with MMU disabled", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144346", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/FaultTolerantWriteDxe/FaultTolerantWriteDxe/DEBUG/FaultTolerantWriteDxe.dll 0xF7E91000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144392", "lvl": "feedback", "msg": "Loading driver at 0x000F7E90000 EntryPoint=0x000F7E950DC FaultTolerantWriteDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144611", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.144688", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/DynamicTablesPkg/Drivers/DynamicTableManagerDxe/DynamicTableManagerDxe/DEBUG/DynamicTableManagerDxe.dll 0xF7E8C000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144736", "lvl": "feedback", "msg": "Loading driver at 0x000F7E8B000 EntryPoint=0x000F7E8D524 DynamicTableManagerDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144783", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/ArmPkg/Drivers/TimerDxe/TimerDxe/DEBUG/ArmTimerDxe.dll 0xF7E87000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144826", "lvl": "feedback", "msg": "Loading driver at 0x000F7E86000 EntryPoint=0x000F7E882A8 ArmTimerDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144872", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/ArmPkg/Drivers/GenericWatchdogDxe/GenericWatchdogDxe/DEBUG/GenericWatchdogDxe.dll 0xF7E82000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144912", "lvl": "feedback", "msg": "Loading driver at 0x000F7E81000 EntryPoint=0x000F7E83360 GenericWatchdogDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144958", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/BootManagerPolicyDxe/BootManagerPolicyDxe/DEBUG/BootManagerPolicyDxe.dll 0xF7E7B000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.144999", "lvl": "feedback", "msg": "Loading driver at 0x000F7E7A000 EntryPoint=0x000F7E7D738 BootManagerPolicyDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145046", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/SetupBrowserDxe/SetupBrowserDxe/DEBUG/SetupBrowser.dll 0xF7E60000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145087", "lvl": "feedback", "msg": "Loading driver at 0x000F7E5F000 EntryPoint=0x000F7E60D10 SetupBrowser.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145133", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/BdsDxe/BdsDxe/DEBUG/BdsDxe.dll 0xF7E46000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145173", "lvl": "feedback", "msg": "Loading driver at 0x000F7E45000 EntryPoint=0x000F7E470A4 BdsDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145218", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/Platform/ARM/Drivers/NorFlashDxe/NorFlashDxe/DEBUG/NorFlashDxe.dll 0xF2E00000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145258", "lvl": "feedback", "msg": "Loading driver at 0x000F2DF0000 EntryPoint=0x000F2E033A4 NorFlashDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145304", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/Platform/ARM/VExpressPkg/Drivers/PL180MciDxe/PL180MciDxe/DEBUG/PL180MciDxe.dll 0xF7E3F000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145345", "lvl": "feedback", "msg": "Loading driver at 0x000F7E3E000 EntryPoint=0x000F7E40D98 PL180MciDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145552", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.145626", "lvl": "feedback", "msg": "Probing ID registers at 0x1C050FE0 for a PL180", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145674", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/DisplayEngineDxe/DisplayEngineDxe/DEBUG/DisplayEngine.dll 0xF7E28000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145722", "lvl": "feedback", "msg": "Loading driver at 0x000F7E27000 EntryPoint=0x000F7E28A9C DisplayEngine.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145766", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Bus/Pci/PciHostBridgeDxe/PciHostBridgeDxe/DEBUG/PciHostBridgeDxe.dll 0xF7E19000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145812", "lvl": "feedback", "msg": "Loading driver at 0x000F7E18000 EntryPoint=0x000F7E1FF5C PciHostBridgeDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145852", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Variable/RuntimeDxe/VariableRuntimeDxe/DEBUG/VariableRuntimeDxe.dll 0xF2CC0000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145900", "lvl": "feedback", "msg": "Loading driver at 0x000F2CB0000 EntryPoint=0x000F2CC75E8 VariableRuntimeDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145942", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/CapsuleRuntimeDxe/CapsuleRuntimeDxe/DEBUG/CapsuleRuntimeDxe.dll 0xF7F60000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.145987", "lvl": "feedback", "msg": "Loading driver at 0x000F7F50000 EntryPoint=0x000F7F61510 CapsuleRuntimeDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146027", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/MonotonicCounterRuntimeDxe/MonotonicCounterRuntimeDxe/DEBUG/MonotonicCounterRuntimeDxe.dll 0xF2BF0000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146073", "lvl": "feedback", "msg": "Loading driver at 0x000F2BE0000 EntryPoint=0x000F2BF1328 MonotonicCounterRuntimeDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146115", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/EmbeddedPkg/RealTimeClockRuntimeDxe/RealTimeClockRuntimeDxe/DEBUG/RealTimeClock.dll 0xF2BB0000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146160", "lvl": "feedback", "msg": "Loading driver at 0x000F2BA0000 EntryPoint=0x000F2BB1B70 RealTimeClock.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146199", "lvl": "feedback", "msg": "InitializeRealTimeClock: using default timezone/daylight settings", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146246", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Console/ConPlatformDxe/ConPlatformDxe/DEBUG/ConPlatformDxe.dll 0xF7E12000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146287", "lvl": "feedback", "msg": "Loading driver at 0x000F7E11000 EntryPoint=0x000F7E149C0 ConPlatformDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146493", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.146566", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Console/ConSplitterDxe/ConSplitterDxe/DEBUG/ConSplitterDxe.dll 0xF7E07000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146614", "lvl": "feedback", "msg": "Loading driver at 0x000F7E06000 EntryPoint=0x000F7E0CE7C ConSplitterDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146661", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Console/GraphicsConsoleDxe/GraphicsConsoleDxe/DEBUG/GraphicsConsoleDxe.dll 0xF7DFE000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146707", "lvl": "feedback", "msg": "Loading driver at 0x000F7DFD000 EntryPoint=0x000F7E011A4 GraphicsConsoleDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146749", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Console/TerminalDxe/TerminalDxe/DEBUG/TerminalDxe.dll 0xF7DF3000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146795", "lvl": "feedback", "msg": "Loading driver at 0x000F7DF2000 EntryPoint=0x000F7DF94C4 TerminalDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146834", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/ArmPkg/Filesystem/SemihostFs/SemihostFs/DEBUG/SemihostFs.dll 0xF7DEC000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146881", "lvl": "feedback", "msg": "Loading driver at 0x000F7DEB000 EntryPoint=0x000F7DEEBE8 SemihostFs.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146922", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Disk/DiskIoDxe/DiskIoDxe/DEBUG/DiskIoDxe.dll 0xF7DE4000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.146968", "lvl": "feedback", "msg": "Loading driver at 0x000F7DE3000 EntryPoint=0x000F7DE71A4 DiskIoDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147009", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Disk/PartitionDxe/PartitionDxe/DEBUG/PartitionDxe.dll 0xF7DDA000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147055", "lvl": "feedback", "msg": "Loading driver at 0x000F7DD9000 EntryPoint=0x000F7DDEBE4 PartitionDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147095", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/FatPkg/EnhancedFatDxe/Fat/DEBUG/Fat.dll 0xF7DCD000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147140", "lvl": "feedback", "msg": "Loading driver at 0x000F7DCC000 EntryPoint=0x000F7DD50D4 Fat.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147181", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/Disk/UnicodeCollation/EnglishDxe/EnglishDxe/DEBUG/EnglishDxe.dll 0xF7DC8000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147226", "lvl": "feedback", "msg": "Loading driver at 0x000F7DC7000 EntryPoint=0x000F7DC9388 EnglishDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147427", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.147501", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/Platform/ARM/VExpressPkg/Drivers/ArmVExpressDxe/ArmFvpDxe/DEBUG/ArmFvpDxe.dll 0xF2B96000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147550", "lvl": "feedback", "msg": "Loading driver at 0x000F2B95000 EntryPoint=0x000F2B9BE64 ArmFvpDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147597", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/OvmfPkg/VirtioBlkDxe/VirtioBlk/DEBUG/VirtioBlkDxe.dll 0xF7DC1000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147641", "lvl": "feedback", "msg": "Loading driver at 0x000F7DC0000 EntryPoint=0x000F7DC333C VirtioBlkDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147688", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Bus/Pci/PciBusDxe/PciBusDxe/DEBUG/PciBusDxe.dll 0xF2B81000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147731", "lvl": "feedback", "msg": "Loading driver at 0x000F2B80000 EntryPoint=0x000F2B8941C PciBusDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147777", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Bus/Ata/AtaAtapiPassThru/AtaAtapiPassThru/DEBUG/AtaAtapiPassThruDxe.dll 0xF2B73000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147818", "lvl": "feedback", "msg": "Loading driver at 0x000F2B72000 EntryPoint=0x000F2B7BA2C AtaAtapiPassThruDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147864", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Bus/Ata/AtaBusDxe/AtaBusDxe/DEBUG/AtaBusDxe.dll 0xF2F81000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147905", "lvl": "feedback", "msg": "Loading driver at 0x000F2F80000 EntryPoint=0x000F2F85A20 AtaBusDxe.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147950", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Bus/Pci/SataControllerDxe/SataControllerDxe/DEBUG/SataController.dll 0xF2B6C000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.147992", "lvl": "feedback", "msg": "Loading driver at 0x000F2B6B000 EntryPoint=0x000F2B6E0FC SataController.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148037", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/MdeModulePkg/Universal/FvSimpleFileSystemDxe/FvSimpleFileSystemDxe/DEBUG/FvSimpleFileSystem.dll 0xF2B63000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148080", "lvl": "feedback", "msg": "Loading driver at 0x000F2B62000 EntryPoint=0x000F2B67244 FvSimpleFileSystem.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148125", "lvl": "feedback", "msg": "Process PlatformRecovery0000 (Default PlatformRecovery) ...", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148165", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 0. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148211", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 1. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148420", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.148498", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 2. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148548", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 3. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148595", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 0. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148639", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 1. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148686", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 2. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148728", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 3. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148773", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 0. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148815", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 1. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148860", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 2. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148900", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 3. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148945", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 0. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.148986", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 1. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149032", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 2. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149072", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 3. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149118", "lvl": "feedback", "msg": "[Bds] Unable to boot!", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149157", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 0. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149202", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 1. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149242", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 262144 for FAT filesystem on MediaId 2. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149449", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:53.149516", "lvl": "feedback", "msg": "FatAllocateVolume invalid BlockIo BlockSize 65536 for FAT filesystem on MediaId 3. Must be 512B, 1KB, 2KB, or 4KB", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149564", "lvl": "feedback", "msg": "[Bds]Booting UEFI Shell (default)", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149612", "lvl": "feedback", "msg": "add-symbol-file /home/anders/.shrinkwrap/build/build/ns-edk2/edk2/DEBUG_GCC5/AARCH64/ShellPkg/Application/Shell/Shell/DEBUG/Shell.dll 0xF2A17000", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149653", "lvl": "feedback", "msg": "Loading driver at 0x000F2A16000 EntryPoint=0x000F2A1E240 Shell.efi", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149700", "lvl": "feedback", "msg": "[HiiDatabase]: Memory allocation is required after ReadyToBoot, which may change memory map and cause S4 resume issue.", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149744", "lvl": "feedback", "msg": "[HiiDatabase]: Memory allocation is required after ReadyToBoot, which may change memory map and cause S4 resume issue.", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149789", "lvl": "feedback", "msg": "[HiiDatabase]: Memory allocation is required after ReadyToBoot, which may change memory map and cause S4 resume issue.", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149827", "lvl": "feedback", "msg": "[HiiDatabase]: Memory allocation is required after ReadyToBoot, which may change memory map and cause S4 resume issue.", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149874", "lvl": "feedback", "msg": "[HiiDatabase]: Memory allocation is required after ReadyToBoot, which may change memory map and cause S4 resume issue.", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149913", "lvl": "feedback", "msg": "Loading driver at 0x000F1D70000 EntryPoint=0x000F271FFA4", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.149959", "lvl": "feedback", "msg": "Loading driver at 0x000F1D70000 EntryPoint=0x000F271FFA4 ", "ns": "terminal_1"}
- {"dt": "2025-07-09T13:19:53.150163", "lvl": "debug", "msg": "Listened to connection for namespace 'terminal_1' for up to 1s"}
- {"dt": "2025-07-09T13:19:54.142077", "lvl": "info", "msg": "Finalising connection for namespace 'terminal_1'"}
- {"dt": "2025-07-09T13:19:54.142232", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2025-07-09T13:19:55.243590", "lvl": "info", "msg": "Finalising connection for namespace 'terminal_2'"}
- {"dt": "2025-07-09T13:19:55.243740", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2025-07-09T13:19:56.344723", "lvl": "info", "msg": "Finalising connection for namespace 'terminal_3'"}
- {"dt": "2025-07-09T13:19:56.344879", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2025-07-09T13:19:56.445181", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:04) [common]"}
- {"dt": "2025-07-09T13:19:56.445360", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:04) [common]"}
- {"dt": "2025-07-09T13:19:56.445483", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2025-07-09T13:19:56.445622", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/startup.nsh"}
- {"dt": "2025-07-09T13:19:56.445736", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/FVP_AARCH64_EFI.fd"}
- {"dt": "2025-07-09T13:19:56.445839", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/bl1.bin"}
- {"dt": "2025-07-09T13:19:56.445929", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/fvp-base-revc.dtb"}
- {"dt": "2025-07-09T13:19:56.446019", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/Image"}
- {"dt": "2025-07-09T13:19:56.446107", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/https___storage.tuxboot.com_debian_20250617_trixie_arm64_rootfs.ext4"}
- {"dt": "2025-07-09T13:19:56.446195", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/rootfs.modules/modules.tar"}
- {"dt": "2025-07-09T13:19:56.446259", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1/fvp-deploy-v__n1yed/fip.bin"}
- {"dt": "2025-07-09T13:19:56.446348", "lvl": "debug", "msg": "Stopping container tuxrun-5pnzlwij-lava-1 from action check-fvp-version"}
- {"dt": "2025-07-09T13:19:56.446456", "lvl": "debug", "msg": "Calling: ['docker', 'stop', 'tuxrun-5pnzlwij-lava-1']"}
- {"dt": "2025-07-09T13:20:06.898326", "lvl": "debug", "msg": ">> tuxrun-5pnzlwij-lava-1"}
- {"dt": "2025-07-09T13:20:06.899863", "lvl": "debug", "msg": "Returned 0 in 10 seconds"}
- {"dt": "2025-07-09T13:20:06.899973", "lvl": "debug", "msg": "Stopped container tuxrun-5pnzlwij-lava-1"}
- {"dt": "2025-07-09T13:20:06.900030", "lvl": "debug", "msg": "Listening to feedback from FVP binary for 60s."}
- {"dt": "2025-07-09T13:20:06.900130", "lvl": "feedback", "msg": ""}
- {"dt": "2025-07-09T13:20:06.900183", "lvl": "feedback", "msg": "Info: FVP_Base_RevC_2xAEMvA: FVP_Base_RevC_2xAEMvA.bp.flashloader1: FlashLoader: Loaded 2560 kB from file '/tuxrun-5pnzlwij-lava-1/FVP_AARCH64_EFI.fd'"}
- {"dt": "2025-07-09T13:20:06.900228", "lvl": "feedback", "msg": ""}
- {"dt": "2025-07-09T13:20:06.900268", "lvl": "feedback", "msg": "Info: FVP_Base_RevC_2xAEMvA: FVP_Base_RevC_2xAEMvA.bp.flashloader0: FlashLoader: Loaded 2675 kB from file '/tuxrun-5pnzlwij-lava-1/fip.bin'"}
- {"dt": "2025-07-09T13:20:06.900307", "lvl": "feedback", "msg": ""}
- {"dt": "2025-07-09T13:20:06.900343", "lvl": "feedback", "msg": "Info: FVP_Base_RevC_2xAEMvA: FVP_Base_RevC_2xAEMvA.bp.secureflashloader: FlashLoader: Loaded 31 kB from file '/tuxrun-5pnzlwij-lava-1/bl1.bin'"}
- {"dt": "2025-07-09T13:20:06.903506", "lvl": "debug", "msg": "Stopping container tuxrun-5pnzlwij-lava-1 from action run-fvp"}
- {"dt": "2025-07-09T13:20:06.903573", "lvl": "debug", "msg": "Calling: ['docker', 'stop', 'tuxrun-5pnzlwij-lava-1']"}
- {"dt": "2025-07-09T13:20:06.935477", "lvl": "debug", "msg": ">> Error: no container with name or ID \"tuxrun-5pnzlwij-lava-1\" found: no such container"}
- {"dt": "2025-07-09T13:20:06.936677", "lvl": "debug", "msg": "Returned 125 in 0 seconds"}
- {"dt": "2025-07-09T13:20:06.936780", "lvl": "debug", "msg": "Listening to feedback from FVP binary."}
- {"dt": "2025-07-09T13:20:06.936954", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-5pnzlwij-1"}
- {"dt": "2025-07-09T13:20:06.937422", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2025-07-09T13:20:06.937479", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
