- {"dt": "2024-11-13T12:56:28.290227", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.05.dev0067"}
- {"dt": "2024-11-13T12:56:28.290310", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-11-13T12:56:28.290379", "lvl": "info", "msg": "Start time: 2024-11-13 12:56:28.290373+00:00 (UTC)"}
- {"dt": "2024-11-13T12:56:28.290938", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst exists"}
- {"dt": "2024-11-13T12:56:28.291219", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxsuite/plan/62/build_1/zImage exists"}
- {"dt": "2024-11-13T12:56:28.291307", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4.zst exists"}
- {"dt": "2024-11-13T12:56:28.291389", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxsuite/plan/62/build_1/modules.tar.xz exists"}
- {"dt": "2024-11-13T12:56:28.299036", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:8.2.4+ds-1, host architecture: amd64"}
- {"dt": "2024-11-13T12:56:28.299303", "lvl": "warning", "msg": "Missing image arg for url_repo"}
- {"dt": "2024-11-13T12:56:28.299402", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2024-11-13T12:56:28.299574", "lvl": "info", "msg": "validate duration: 0.01"}
- {"dt": "2024-11-13T12:56:28.299653", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2024-11-13T12:56:28.299801", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.299900", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.300117", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y"}
- {"dt": "2024-11-13T12:56:28.300287", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin"}
- {"dt": "2024-11-13T12:56:28.300395", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/tests"}
- {"dt": "2024-11-13T12:56:28.300497", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/results"}
- {"dt": "2024-11-13T12:56:28.300578", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-add-keys"}
- {"dt": "2024-11-13T12:56:28.300713", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-add-sources"}
- {"dt": "2024-11-13T12:56:28.300842", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-background-process-start"}
- {"dt": "2024-11-13T12:56:28.300971", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-background-process-stop"}
- {"dt": "2024-11-13T12:56:28.301101", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-common-functions"}
- {"dt": "2024-11-13T12:56:28.301240", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2024-11-13T12:56:28.301366", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-install-packages"}
- {"dt": "2024-11-13T12:56:28.301491", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-installed-packages"}
- {"dt": "2024-11-13T12:56:28.301616", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-os-build"}
- {"dt": "2024-11-13T12:56:28.301740", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-probe-channel"}
- {"dt": "2024-11-13T12:56:28.301863", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-probe-ip"}
- {"dt": "2024-11-13T12:56:28.301984", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-target-ip"}
- {"dt": "2024-11-13T12:56:28.302107", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-target-mac"}
- {"dt": "2024-11-13T12:56:28.302230", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-target-storage"}
- {"dt": "2024-11-13T12:56:28.302354", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-test-case"}
- {"dt": "2024-11-13T12:56:28.302480", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-test-event"}
- {"dt": "2024-11-13T12:56:28.302602", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-test-feedback"}
- {"dt": "2024-11-13T12:56:28.302725", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-test-raise"}
- {"dt": "2024-11-13T12:56:28.302853", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-test-reference"}
- {"dt": "2024-11-13T12:56:28.302977", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-test-runner"}
- {"dt": "2024-11-13T12:56:28.303103", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-test-set"}
- {"dt": "2024-11-13T12:56:28.303227", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-test-shell"}
- {"dt": "2024-11-13T12:56:28.303352", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2024-11-13T12:56:28.303515", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2024-11-13T12:56:28.303637", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/environment"}
- {"dt": "2024-11-13T12:56:28.303729", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-11-13T12:56:28.303782", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2024-11-13T12:56:28.303834", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2024-11-13T12:56:28.303919", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.303968", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-11-13T12:56:28.304032", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.304098", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.304143", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-11-13T12:56:28.304204", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.304265", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.304319", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-11-13T12:56:28.304386", "lvl": "debug", "msg": "start: 1.1.3.1 url-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.304449", "lvl": "debug", "msg": "start: 1.1.3.1.1 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.304512", "lvl": "debug", "msg": "start: 1.1.3.1.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.304650", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst"}
- {"dt": "2024-11-13T12:56:28.304703", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/url-repo-action-26iifmow/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar"}
- {"dt": "2024-11-13T12:56:28.304752", "lvl": "debug", "msg": "total size: 13692979 (13\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.304801", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-11-13T12:56:28.305135", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.307297", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.309125", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.310951", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.312785", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.314624", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.316464", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.318287", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.320143", "lvl": "debug", "msg": "progress  40\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.322295", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.324039", "lvl": "debug", "msg": "progress  50\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.326193", "lvl": "debug", "msg": "progress  55\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.328024", "lvl": "debug", "msg": "progress  60\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.329863", "lvl": "debug", "msg": "progress  65\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.331707", "lvl": "debug", "msg": "progress  70\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.334047", "lvl": "debug", "msg": "progress  75\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.336662", "lvl": "debug", "msg": "progress  80\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.340019", "lvl": "debug", "msg": "progress  85\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.342359", "lvl": "debug", "msg": "progress  90\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.346506", "lvl": "debug", "msg": "progress  95\u202F% (12\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.350396", "lvl": "debug", "msg": "progress 100\u202F% (13\u202FMB)"}
- {"dt": "2024-11-13T12:56:28.357622", "lvl": "info", "msg": "13\u202FMB downloaded in 0.05\u202Fs (247.01\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:28.357742", "lvl": "debug", "msg": "end: 1.1.3.1.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.357813", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.1.3.1.1.1", "duration": "0.05", "result": "pass", "extra": {"label": "url_repo", "size": ! "13692979", "md5sum": "30eb6f40044a706e0edae9b32f16a662", "sha256sum": "c850bed1a93201581f64754245d951f99a4942fe707b841bfe6ea3c9cd7624b0", "sha512sum": "3435daea585c61fec8b4c72eeb412c9d550e229d1220195bd592f268fe6baad5d210433f9802e5f9931fb5b8b17a10a4c5c569f16a3c286247222b96634c6310"}}}
- {"dt": "2024-11-13T12:56:28.357958", "lvl": "debug", "msg": "end: 1.1.3.1.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.358018", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2024-11-13T12:56:28.358096", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/0/tests/0_ltp-fs_bind"}
- {"dt": "2024-11-13T12:56:28.358172", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/url-repo-action-26iifmow/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/0/tests/0_ltp-fs_bind"}
- {"dt": "2024-11-13T12:56:28.556831", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/0/tests/0_ltp-fs_bind/automated/linux/ltp/ltp.yaml"}
- {"dt": "2024-11-13T12:56:28.557174", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2024-11-13T12:56:28.557278", "lvl": "debug", "msg": "end: 1.1.3.1 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.557361", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.557952", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.558006", "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": "ltp-fs_bind", "path": "automated/linux/ltp/ltp.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst"}}}
- {"dt": "2024-11-13T12:56:28.558109", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.558929", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.558985", "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": "ltp-fs_bind", "path": "automated/linux/ltp/ltp.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst", "skipped test-install-overlay": "1_1.1.3.1"}}}
- {"dt": "2024-11-13T12:56:28.559104", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:28.567320", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/0/tests/0_ltp-fs_bind test_uuid 1_1.1.3.1"}
- {"dt": "2024-11-13T12:56:28.567402", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2024-11-13T12:56:28.567458", "lvl": "debug", "msg": "TST_CMDFILES='fs_bind'"}
- {"dt": "2024-11-13T12:56:28.567518", "lvl": "debug", "msg": "SKIPFILE='/tuxtest/skipfiles/armv7/ltp-fs_bind'"}
- {"dt": "2024-11-13T12:56:28.567571", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2024-11-13T12:56:28.567624", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2024-11-13T12:56:28.567675", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2024-11-13T12:56:28.567727", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2024-11-13T12:56:28.567770", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2024-11-13T12:56:28.567808", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2024-11-13T12:56:28.567939", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.567996", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-runscript-overlay", "level": "1.1.3.4", "duration": "0.01", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "ltp-fs_bind", "path": "automated/linux/ltp/ltp.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/0/tests/0_ltp-fs_bind/run.sh"}}}
- {"dt": "2024-11-13T12:56:28.568094", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-11-13T12:56:28.568136", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/lava-overlay-gv67q41y/lava-1/0 for stage 0"}
- {"dt": "2024-11-13T12:56:28.568210", "lvl": "debug", "msg": "- 0_ltp-fs_bind"}
- {"dt": "2024-11-13T12:56:28.568293", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:28.568353", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:34.466674", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:06) [common]"}
- {"dt": "2024-11-13T12:56:34.466803", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:34.466866", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:34.466935", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:06) [common]"}
- {"dt": "2024-11-13T12:56:34.466992", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:34.467038", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2024-11-13T12:56:34.467088", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:34.467140", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:34.467190", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:34.467242", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:34.467294", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:34.467455", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxsuite/plan/62/build_1/zImage"}
- {"dt": "2024-11-13T12:56:34.467512", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/kernel/zImage"}
- {"dt": "2024-11-13T12:56:34.467555", "lvl": "debug", "msg": "total size: 11485696 (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.467599", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-11-13T12:56:34.467690", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.469441", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.471263", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.472983", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.474808", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.476523", "lvl": "debug", "msg": "progress  25\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.478348", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.480065", "lvl": "debug", "msg": "progress  35\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.481886", "lvl": "debug", "msg": "progress  40\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.483604", "lvl": "debug", "msg": "progress  45\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.485425", "lvl": "debug", "msg": "progress  50\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.487143", "lvl": "debug", "msg": "progress  55\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.488957", "lvl": "debug", "msg": "progress  60\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.490679", "lvl": "debug", "msg": "progress  65\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.492495", "lvl": "debug", "msg": "progress  70\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.494217", "lvl": "debug", "msg": "progress  75\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.496033", "lvl": "debug", "msg": "progress  80\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.497758", "lvl": "debug", "msg": "progress  85\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.499574", "lvl": "debug", "msg": "progress  90\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.501304", "lvl": "debug", "msg": "progress  95\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.503126", "lvl": "debug", "msg": "progress 100\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.503231", "lvl": "info", "msg": "10\u202FMB downloaded in 0.04\u202Fs (307.05\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:34.503323", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:34.503371", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.1", "duration": "0.04", "result": "pass", "extra": {"label": "kernel", "size": ! "11485696", "md5sum": "db32d76905c12e987d0675573dd654a8", "sha256sum": "d19166ca9e4c40f83e4324023741e3b99ef29e13cc588908643a7cdf27d19df6", "sha512sum": "2c59c327a3f41f55cc9062da0135a71d040c5cc89db66e109f62d70b980e86fb26ec8e1fd5e6c81903bbdc74cb03790f27d00d8085d463879916d6f80ae2ba6f"}}}
- {"dt": "2024-11-13T12:56:34.503501", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:34.503557", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:34.503611", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:34.503738", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4.zst"}
- {"dt": "2024-11-13T12:56:34.503790", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4"}
- {"dt": "2024-11-13T12:56:34.503832", "lvl": "debug", "msg": "total size: 73194706 (69\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.503872", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-11-13T12:56:34.504228", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.520182", "lvl": "debug", "msg": "progress   5\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.530092", "lvl": "debug", "msg": "progress  10\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.540165", "lvl": "debug", "msg": "progress  15\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.549898", "lvl": "debug", "msg": "progress  20\u202F% (13\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.567268", "lvl": "debug", "msg": "progress  25\u202F% (17\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.590677", "lvl": "debug", "msg": "progress  30\u202F% (20\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.609486", "lvl": "debug", "msg": "progress  35\u202F% (24\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.629137", "lvl": "debug", "msg": "progress  40\u202F% (27\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.648290", "lvl": "debug", "msg": "progress  45\u202F% (31\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.668794", "lvl": "debug", "msg": "progress  50\u202F% (34\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.688698", "lvl": "debug", "msg": "progress  55\u202F% (38\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.707187", "lvl": "debug", "msg": "progress  60\u202F% (41\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.727173", "lvl": "debug", "msg": "progress  65\u202F% (45\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.743845", "lvl": "debug", "msg": "progress  70\u202F% (48\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.753625", "lvl": "debug", "msg": "progress  75\u202F% (52\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.763276", "lvl": "debug", "msg": "progress  80\u202F% (55\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.773185", "lvl": "debug", "msg": "progress  85\u202F% (59\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.785537", "lvl": "debug", "msg": "progress  90\u202F% (62\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.795545", "lvl": "debug", "msg": "progress  95\u202F% (66\u202FMB)"}
- {"dt": "2024-11-13T12:56:34.805354", "lvl": "debug", "msg": "progress 100\u202F% (69\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.046925", "lvl": "info", "msg": "69\u202FMB downloaded in 0.54\u202Fs (128.53\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:35.047144", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:01) [common]"}
- {"dt": "2024-11-13T12:56:35.047203", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "0.54", "result": "pass", "extra": {"label": "rootfs", "size": ! "73194706", "md5sum": "d8011d3000995663f497569fe7ae9ba7", "sha256sum": "2ff192e8a743a11de63c31a6361d545e2aa6d9a7d3dd3d872e9b107e06b1954e", "sha512sum": "16b75b03a9a0f1a4b356ca0cfcae27a003be26bfdfa8916ba12d314cc77658dfb9888d43b1479b0ef8157385c30b455aaac3abb75cb19d6e47f9587a7570a549"}}}
- {"dt": "2024-11-13T12:56:35.047324", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:04:53) [common]"}
- {"dt": "2024-11-13T12:56:35.047388", "lvl": "debug", "msg": "start: 1.5.2.1 file-download (timeout 00:04:53) [common]"}
- {"dt": "2024-11-13T12:56:35.047578", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxsuite/plan/62/build_1/modules.tar.xz"}
- {"dt": "2024-11-13T12:56:35.047634", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/rootfs.modules/modules.tar"}
- {"dt": "2024-11-13T12:56:35.047679", "lvl": "debug", "msg": "total size: 6635564 (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.047722", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-11-13T12:56:35.048135", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.049864", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.050790", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.051708", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.052622", "lvl": "debug", "msg": "progress  20\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.053543", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.054459", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.055374", "lvl": "debug", "msg": "progress  35\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.056372", "lvl": "debug", "msg": "progress  40\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.057290", "lvl": "debug", "msg": "progress  45\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.058206", "lvl": "debug", "msg": "progress  50\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.059117", "lvl": "debug", "msg": "progress  55\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.060034", "lvl": "debug", "msg": "progress  60\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.060946", "lvl": "debug", "msg": "progress  65\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.061868", "lvl": "debug", "msg": "progress  70\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.062785", "lvl": "debug", "msg": "progress  75\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.063782", "lvl": "debug", "msg": "progress  80\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.064691", "lvl": "debug", "msg": "progress  85\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.065763", "lvl": "debug", "msg": "progress  90\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.066677", "lvl": "debug", "msg": "progress  95\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.067589", "lvl": "debug", "msg": "progress 100\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:35.214173", "lvl": "info", "msg": "6\u202FMB downloaded in 0.17\u202Fs (38.01\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:35.214413", "lvl": "debug", "msg": "end: 1.5.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:35.214471", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.2.1", "duration": "0.17", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "6635564", "md5sum": "b603fa1cf36ddee27ca7b529aa460c29", "sha256sum": "0b026d9f0f1cd0e4f47860a9ef6517c7297cec7b62b287601ae43cc5b7c9a67b", "sha512sum": "ccb7aac1885840e2dcddd189030ab5cf0fe3f7d0fc408209dec1028d79c862a23288ee25c8318b919f4379ae30a0625e2b832a0e390735ab653aeecc9d821c1a"}}}
- {"dt": "2024-11-13T12:56:35.214606", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:35.214673", "lvl": "debug", "msg": "start: 1.5.3 append-overlays (timeout 00:04:53) [common]"}
- {"dt": "2024-11-13T12:56:35.214732", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4'"}
- {"dt": "2024-11-13T12:56:36.943845", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2024-11-13T12:56:36.944501", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/compress-overlay-kxjq_os8/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2024-11-13T12:56:37.318611", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/rootfs.modules/modules.tar' to '/'"}
- {"dt": "2024-11-13T12:56:37.601322", "lvl": "debug", "msg": "end: 1.5.3 append-overlays (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:37.601738", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-11-13T12:56:37.601852", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:09) [common]"}
- {"dt": "2024-11-13T12:56:37.601972", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:37.602105", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:37.602210", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:37.602546", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-arm -cpu cortex-a15 -machine virt,gic-version=3 -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/kernel/zImage -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4,if=virtio,format=raw,id=hd0"}
- {"dt": "2024-11-13T12:56:37.606195", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2024-11-13T12:56:37.606617", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:37.606742", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.00", "result": "pass", "extra": {"qemu_version": "1:8.2.4+ds-1", "host_arch": "amd64", "job_arch": "arm"}}}
- {"dt": "2024-11-13T12:56:37.606934", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:37.607047", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:37.607155", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-11-13T12:56:37.607243", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:15:00)"}
- {"dt": "2024-11-13T12:56:37.960119", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0"}
- {"dt": "2024-11-13T12:56:37.960556", "lvl": "target", "msg": "[    0.000000] Linux version 6.12.0-rc7 (tuxmake@tuxmake) (arm-linux-gnueabihf-gcc (Debian 13.3.0-5) 13.3.0, GNU ld (GNU Binutils for Debian) 2.43.1) #1 SMP @1731277175"}
- {"dt": "2024-11-13T12:56:37.960847", "lvl": "target", "msg": "[    0.000000] CPU: ARMv7 Processor [414fc0f0] revision 0 (ARMv7), cr=30c5387d"}
- {"dt": "2024-11-13T12:56:37.961080", "lvl": "target", "msg": "[    0.000000] CPU: div instructions available: patching division code"}
- {"dt": "2024-11-13T12:56:37.961336", "lvl": "target", "msg": "[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache"}
- {"dt": "2024-11-13T12:56:37.961521", "lvl": "target", "msg": "[    0.000000] OF: fdt: Machine model: linux,dummy-virt"}
- {"dt": "2024-11-13T12:56:37.961631", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-11-13T12:56:37.961857", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-11-13T12:56:37.962037", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-11-13T12:56:37.962330", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:37.962402", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-11-13T12:56:37.962504", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2024-11-13T12:56:37.962607", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-11-13T12:56:37.962686", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-11-13T12:56:37.962766", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-11-13T12:56:37.962843", "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": "2024-11-13T12:56:37.962986", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:15:00)"}
- {"dt": "2024-11-13T12:56:37.963072", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2024-11-13T12:56:37.963585", "lvl": "target", "msg": "[    0.000000] Memory policy: Data cache writealloc"}
- {"dt": "2024-11-13T12:56:37.963644", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-11-13T12:56:37.964987", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fb800000 on node -1"}
- {"dt": "2024-11-13T12:56:37.967083", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-11-13T12:56:37.967703", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x000000006fffffff]"}
- {"dt": "2024-11-13T12:56:37.967797", "lvl": "target", "msg": "[    0.000000]   Normal   empty"}
- {"dt": "2024-11-13T12:56:37.967957", "lvl": "target", "msg": "[    0.000000]   HighMem  [mem 0x0000000070000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:37.968120", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-11-13T12:56:37.968279", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-11-13T12:56:37.968544", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:37.968966", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:38.082124", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-11-13T12:56:38.082289", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-11-13T12:56:38.082606", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-11-13T12:56:38.082698", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-11-13T12:56:38.082786", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-11-13T12:56:38.084348", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 17 pages/cpu s40844 r8192 d20596 u69632"}
- {"dt": "2024-11-13T12:56:38.085080", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s40844 r8192 d20596 u69632 alloc=17*4096"}
- {"dt": "2024-11-13T12:56:38.085374", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-11-13T12:56:38.086833", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning earlycon"}
- {"dt": "2024-11-13T12:56:38.087761", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2024-11-13T12:56:38.088725", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-11-13T12:56:38.089237", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-11-13T12:56:38.089741", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2024-11-13T12:56:38.090191", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-11-13T12:56:38.090405", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-11-13T12:56:38.146249", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x0000000069f0f000-0x000000006df0f000] (64MB)"}
- {"dt": "2024-11-13T12:56:38.687500", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-11-13T12:56:38.690240", "lvl": "target", "msg": "<6>[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-11-13T12:56:38.694078", "lvl": "target", "msg": "<6>[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2024-11-13T12:56:38.694190", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-11-13T12:56:38.694374", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=2."}
- {"dt": "2024-11-13T12:56:38.694761", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies."}
- {"dt": "2024-11-13T12:56:38.694984", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-11-13T12:56:38.703393", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16"}
- {"dt": "2024-11-13T12:56:38.709826", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-11-13T12:56:38.709917", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-11-13T12:56:38.711043", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-11-13T12:56:38.711121", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2024-11-13T12:56:38.711700", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-11-13T12:56:38.715063", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-11-13T12:56:38.715819", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @428b0000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-11-13T12:56:38.716225", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @428c0000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-11-13T12:56:38.716851", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x00000000428d0000"}
- {"dt": "2024-11-13T12:56:38.717595", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000000428e0000"}
- {"dt": "2024-11-13T12:56:38.718850", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-11-13T12:56:38.730225", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt)."}
- {"dt": "2024-11-13T12:56:38.730757", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2024-11-13T12:56:38.731299", "lvl": "target", "msg": "<6>[    0.000111] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-11-13T12:56:38.731564", "lvl": "target", "msg": "<6>[    0.000422] Switching to timer-based delay loop, resolution 16ns"}
- {"dt": "2024-11-13T12:56:38.735253", "lvl": "target", "msg": "<6>[    0.004011] Console: colour dummy device 80x30"}
- {"dt": "2024-11-13T12:56:38.736498", "lvl": "target", "msg": "<6>[    0.005201] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=625000)"}
- {"dt": "2024-11-13T12:56:38.737110", "lvl": "target", "msg": "<6>[    0.005591] CPU: Testing write buffer coherency: ok"}
- {"dt": "2024-11-13T12:56:38.737326", "lvl": "target", "msg": "<3>[    0.006123] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2024-11-13T12:56:38.737601", "lvl": "target", "msg": "<6>[    0.006486] CPU0: Spectre BHB: enabling loop workaround for all CPUs"}
- {"dt": "2024-11-13T12:56:38.737774", "lvl": "target", "msg": "<6>[    0.006705] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-11-13T12:56:38.739251", "lvl": "target", "msg": "<6>[    0.008080] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2024-11-13T12:56:38.739491", "lvl": "target", "msg": "<6>[    0.008305] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2024-11-13T12:56:38.756908", "lvl": "target", "msg": "<3>[    0.025568] /cpus/cpu@0 missing clock-frequency property"}
- {"dt": "2024-11-13T12:56:38.757024", "lvl": "target", "msg": "<3>[    0.025903] /cpus/cpu@1 missing clock-frequency property"}
- {"dt": "2024-11-13T12:56:38.757294", "lvl": "target", "msg": "<6>[    0.026169] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000"}
- {"dt": "2024-11-13T12:56:38.825207", "lvl": "target", "msg": "<6>[    0.093889] Setting up static identity map for 0x40400000 - 0x40400184"}
- {"dt": "2024-11-13T12:56:38.826844", "lvl": "target", "msg": "<6>[    0.095663] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-11-13T12:56:38.826933", "lvl": "target", "msg": "<6>[    0.095825] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-11-13T12:56:38.828319", "lvl": "target", "msg": "<6>[    0.097148] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2024-11-13T12:56:38.830665", "lvl": "target", "msg": "<6>[    0.099482] EFI services will not be available."}
- {"dt": "2024-11-13T12:56:38.831677", "lvl": "target", "msg": "<6>[    0.100497] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-11-13T12:56:38.835048", "lvl": "target", "msg": "<6>[    0.103225] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-11-13T12:56:38.835161", "lvl": "target", "msg": "<6>[    0.103414] GICv3: CPU1: using allocated LPI pending table @0x00000000428f0000"}
- {"dt": "2024-11-13T12:56:38.835431", "lvl": "target", "msg": "<6>[    0.103746] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001"}
- {"dt": "2024-11-13T12:56:38.835521", "lvl": "target", "msg": "<3>[    0.103781] CPU1: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2024-11-13T12:56:38.838337", "lvl": "target", "msg": "<6>[    0.107108] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-11-13T12:56:38.838556", "lvl": "target", "msg": "<6>[    0.107354] SMP: Total of 2 processors activated (250.00 BogoMIPS)."}
- {"dt": "2024-11-13T12:56:38.838877", "lvl": "target", "msg": "<6>[    0.107682] CPU: All CPU(s) started in SVC mode."}
- {"dt": "2024-11-13T12:56:38.844178", "lvl": "target", "msg": "<6>[    0.112699] Memory: 3996228K/4194304K available (16384K kernel code, 2544K rwdata, 6788K rodata, 2048K init, 439K bss, 130428K reserved, 65536K cma-reserved, 3342336K highmem)"}
- {"dt": "2024-11-13T12:56:38.850031", "lvl": "target", "msg": "<6>[    0.118828] devtmpfs: initialized"}
- {"dt": "2024-11-13T12:56:38.860725", "lvl": "target", "msg": "<6>[    0.126289] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0"}
- {"dt": "2024-11-13T12:56:38.873704", "lvl": "target", "msg": "<6>[    0.142411] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns"}
- {"dt": "2024-11-13T12:56:38.874439", "lvl": "target", "msg": "<6>[    0.143235] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-11-13T12:56:38.878835", "lvl": "target", "msg": "<6>[    0.147635] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-11-13T12:56:38.889191", "lvl": "target", "msg": "<6>[    0.157977] DMI not present or invalid."}
- {"dt": "2024-11-13T12:56:38.902393", "lvl": "target", "msg": "<6>[    0.171158] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-11-13T12:56:38.910993", "lvl": "target", "msg": "<6>[    0.179763] DMA: preallocated 256 KiB pool for atomic coherent allocations"}
- {"dt": "2024-11-13T12:56:38.916135", "lvl": "target", "msg": "<6>[    0.184470] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-11-13T12:56:38.916236", "lvl": "target", "msg": "<6>[    0.184924] cpuidle: using governor menu"}
- {"dt": "2024-11-13T12:56:38.916957", "lvl": "target", "msg": "<6>[    0.185765] No ATAGs?"}
- {"dt": "2024-11-13T12:56:38.917623", "lvl": "target", "msg": "<6>[    0.186448] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers."}
- {"dt": "2024-11-13T12:56:38.917944", "lvl": "target", "msg": "<6>[    0.186707] hw-breakpoint: maximum watchpoint size is 8 bytes."}
- {"dt": "2024-11-13T12:56:38.921278", "lvl": "target", "msg": "<6>[    0.190107] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-11-13T12:56:38.951065", "lvl": "target", "msg": "<6>[    0.219768] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 28, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-11-13T12:56:38.952763", "lvl": "target", "msg": "<6>[    0.221444] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-11-13T12:56:38.952932", "lvl": "target", "msg": "<6>[    0.221444] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-11-13T12:56:38.953246", "lvl": "target", "msg": "<6>[    0.222063] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-11-13T12:56:38.953322", "lvl": "target", "msg": "<6>[    0.222063] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-11-13T12:56:38.978785", "lvl": "target", "msg": "<6>[    0.247361] iommu: Default domain type: Translated"}
- {"dt": "2024-11-13T12:56:38.978888", "lvl": "target", "msg": "<6>[    0.247721] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-11-13T12:56:38.980835", "lvl": "target", "msg": "<5>[    0.249626] SCSI subsystem initialized"}
- {"dt": "2024-11-13T12:56:38.981570", "lvl": "target", "msg": "<7>[    0.250393] libata version 3.00 loaded."}
- {"dt": "2024-11-13T12:56:38.982372", "lvl": "target", "msg": "<6>[    0.251202] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-11-13T12:56:38.982553", "lvl": "target", "msg": "<6>[    0.251468] usbcore: registered new interface driver hub"}
- {"dt": "2024-11-13T12:56:38.982811", "lvl": "target", "msg": "<6>[    0.251712] usbcore: registered new device driver usb"}
- {"dt": "2024-11-13T12:56:38.984344", "lvl": "target", "msg": "<6>[    0.253176] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-11-13T12:56:38.984487", "lvl": "target", "msg": "<6>[    0.253321] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-11-13T12:56:38.984653", "lvl": "target", "msg": "<6>[    0.253590] PTP clock support registered"}
- {"dt": "2024-11-13T12:56:38.984959", "lvl": "target", "msg": "<6>[    0.253920] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-11-13T12:56:38.986048", "lvl": "target", "msg": "<6>[    0.254841] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-11-13T12:56:38.995033", "lvl": "target", "msg": "<6>[    0.263802] vgaarb: loaded"}
- {"dt": "2024-11-13T12:56:38.997893", "lvl": "target", "msg": "<6>[    0.266681] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-11-13T12:56:39.013924", "lvl": "target", "msg": "<6>[    0.282702] NET: Registered PF_INET protocol family"}
- {"dt": "2024-11-13T12:56:39.014808", "lvl": "target", "msg": "<6>[    0.283599] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-13T12:56:39.019086", "lvl": "target", "msg": "<6>[    0.287838] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)"}
- {"dt": "2024-11-13T12:56:39.019265", "lvl": "target", "msg": "<6>[    0.288123] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-11-13T12:56:39.019526", "lvl": "target", "msg": "<6>[    0.288367] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-11-13T12:56:39.019843", "lvl": "target", "msg": "<6>[    0.288718] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-13T12:56:39.020195", "lvl": "target", "msg": "<6>[    0.289087] TCP: Hash tables configured (established 8192 bind 8192)"}
- {"dt": "2024-11-13T12:56:39.021077", "lvl": "target", "msg": "<6>[    0.289880] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)"}
- {"dt": "2024-11-13T12:56:39.021320", "lvl": "target", "msg": "<6>[    0.290177] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)"}
- {"dt": "2024-11-13T12:56:39.022335", "lvl": "target", "msg": "<6>[    0.291138] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-11-13T12:56:39.026366", "lvl": "target", "msg": "<6>[    0.295150] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-11-13T12:56:39.026446", "lvl": "target", "msg": "<6>[    0.295340] RPC: Registered udp transport module."}
- {"dt": "2024-11-13T12:56:39.026541", "lvl": "target", "msg": "<6>[    0.295473] RPC: Registered tcp transport module."}
- {"dt": "2024-11-13T12:56:39.026689", "lvl": "target", "msg": "<6>[    0.295606] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-11-13T12:56:39.026860", "lvl": "target", "msg": "<6>[    0.295753] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-11-13T12:56:39.027148", "lvl": "target", "msg": "<6>[    0.295977] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-11-13T12:56:39.029480", "lvl": "target", "msg": "<5>[    0.298248] Initialise system trusted keyrings"}
- {"dt": "2024-11-13T12:56:39.031676", "lvl": "target", "msg": "<6>[    0.300420] workingset: timestamp_bits=30 max_order=20 bucket_order=0"}
- {"dt": "2024-11-13T12:56:39.033805", "lvl": "target", "msg": "<6>[    0.302619] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-11-13T12:56:39.037025", "lvl": "target", "msg": "<5>[    0.305788] NFS: Registering the id_resolver key type"}
- {"dt": "2024-11-13T12:56:39.037360", "lvl": "target", "msg": "<5>[    0.306282] Key type id_resolver registered"}
- {"dt": "2024-11-13T12:56:39.037504", "lvl": "target", "msg": "<5>[    0.306446] Key type id_legacy registered"}
- {"dt": "2024-11-13T12:56:39.037910", "lvl": "target", "msg": "<6>[    0.306791] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-11-13T12:56:39.038163", "lvl": "target", "msg": "<6>[    0.307034] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-11-13T12:56:39.039368", "lvl": "target", "msg": "<5>[    0.308198] Key type asymmetric registered"}
- {"dt": "2024-11-13T12:56:39.039442", "lvl": "target", "msg": "<5>[    0.308365] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-11-13T12:56:39.039701", "lvl": "target", "msg": "<6>[    0.308652] bounce: pool size: 64 pages"}
- {"dt": "2024-11-13T12:56:39.040023", "lvl": "target", "msg": "<6>[    0.308895] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)"}
- {"dt": "2024-11-13T12:56:39.040274", "lvl": "target", "msg": "<6>[    0.309167] io scheduler mq-deadline registered"}
- {"dt": "2024-11-13T12:56:39.040384", "lvl": "target", "msg": "<6>[    0.309330] io scheduler kyber registered"}
- {"dt": "2024-11-13T12:56:39.040636", "lvl": "target", "msg": "<6>[    0.309517] io scheduler bfq registered"}
- {"dt": "2024-11-13T12:56:39.065368", "lvl": "target", "msg": "<6>[    0.333923] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-11-13T12:56:39.080169", "lvl": "target", "msg": "<6>[    0.348895] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-11-13T12:56:39.083847", "lvl": "target", "msg": "<6>[    0.352616] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-11-13T12:56:39.084529", "lvl": "target", "msg": "<6>[    0.353296] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-11-13T12:56:39.085074", "lvl": "target", "msg": "<6>[    0.353896] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-11-13T12:56:39.085326", "lvl": "target", "msg": "<6>[    0.354150] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-11-13T12:56:39.085928", "lvl": "target", "msg": "<4>[    0.354748] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-11-13T12:56:39.086604", "lvl": "target", "msg": "<6>[    0.355248] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-11-13T12:56:39.087958", "lvl": "target", "msg": "<6>[    0.356775] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-11-13T12:56:39.088259", "lvl": "target", "msg": "<6>[    0.357164] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-11-13T12:56:39.088507", "lvl": "target", "msg": "<6>[    0.357355] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-11-13T12:56:39.088663", "lvl": "target", "msg": "<6>[    0.357549] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-11-13T12:56:39.088843", "lvl": "target", "msg": "<6>[    0.357724] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-11-13T12:56:39.089943", "lvl": "target", "msg": "<6>[    0.358771] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-11-13T12:56:39.092446", "lvl": "target", "msg": "<6>[    0.361245] pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint"}
- {"dt": "2024-11-13T12:56:39.092760", "lvl": "target", "msg": "<6>[    0.361673] pci 0000:00:01.0: BAR 0 [io  0x0000-0x007f]"}
- {"dt": "2024-11-13T12:56:39.092957", "lvl": "target", "msg": "<6>[    0.361866] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]"}
- {"dt": "2024-11-13T12:56:39.093259", "lvl": "target", "msg": "<6>[    0.362056] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2024-11-13T12:56:39.094353", "lvl": "target", "msg": "<6>[    0.363179] PCI: bus0: Fast back to back transfers disabled"}
- {"dt": "2024-11-13T12:56:39.095514", "lvl": "target", "msg": "<6>[    0.364344] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned"}
- {"dt": "2024-11-13T12:56:39.095849", "lvl": "target", "msg": "<6>[    0.364737] pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned"}
- {"dt": "2024-11-13T12:56:39.096170", "lvl": "target", "msg": "<6>[    0.364923] pci 0000:00:01.0: BAR 0 [io  0x1000-0x107f]: assigned"}
- {"dt": "2024-11-13T12:56:39.096334", "lvl": "target", "msg": "<6>[    0.365237] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2024-11-13T12:56:39.096506", "lvl": "target", "msg": "<6>[    0.365398] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-11-13T12:56:39.096668", "lvl": "target", "msg": "<6>[    0.365563] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-11-13T12:56:39.114627", "lvl": "target", "msg": "<6>[    0.383371] virtio-pci 0000:00:01.0: enabling device (0100 -> 0103)"}
- {"dt": "2024-11-13T12:56:39.172163", "lvl": "target", "msg": "<6>[    0.440850] Serial: 8250/16550 driver, 5 ports, IRQ sharing enabled"}
- {"dt": "2024-11-13T12:56:39.177828", "lvl": "target", "msg": "<6>[    0.446607] msm_serial: driver initialized"}
- {"dt": "2024-11-13T12:56:39.178459", "lvl": "target", "msg": "<6>[    0.447260] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-11-13T12:56:39.178821", "lvl": "target", "msg": "<6>[    0.447689] STMicroelectronics ASC driver initialized"}
- {"dt": "2024-11-13T12:56:39.179141", "lvl": "target", "msg": "<6>[    0.448039] STM32 USART driver initialized"}
- {"dt": "2024-11-13T12:56:39.203534", "lvl": "target", "msg": "<6>[    0.472287] brd: module loaded"}
- {"dt": "2024-11-13T12:56:39.211294", "lvl": "target", "msg": "<6>[    0.480069] loop: module loaded"}
- {"dt": "2024-11-13T12:56:39.212042", "lvl": "target", "msg": "<6>[    0.480855] virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2024-11-13T12:56:39.219942", "lvl": "target", "msg": "<5>[    0.488714] virtio_blk virtio0: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)"}
- {"dt": "2024-11-13T12:56:39.229818", "lvl": "target", "msg": "<5>[    0.498559] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-11-13T12:56:39.231026", "lvl": "target", "msg": "<6>[    0.499792] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-11-13T12:56:39.231449", "lvl": "target", "msg": "<6>[    0.500317] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-11-13T12:56:39.232286", "lvl": "target", "msg": "<6>[    0.501102] Using buffer write method"}
- {"dt": "2024-11-13T12:56:39.232561", "lvl": "target", "msg": "<7>[    0.501446] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-11-13T12:56:39.234093", "lvl": "target", "msg": "<5>[    0.502914] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-11-13T12:56:39.234747", "lvl": "target", "msg": "<6>[    0.503559] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-11-13T12:56:39.234894", "lvl": "target", "msg": "<6>[    0.503819] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-11-13T12:56:39.235546", "lvl": "target", "msg": "<6>[    0.504370] Using buffer write method"}
- {"dt": "2024-11-13T12:56:39.235602", "lvl": "target", "msg": "<7>[    0.504495] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-11-13T12:56:39.235912", "lvl": "target", "msg": "<5>[    0.504686] Concatenating MTD devices:"}
- {"dt": "2024-11-13T12:56:39.235957", "lvl": "target", "msg": "<5>[    0.504846] (0): \"0.flash\""}
- {"dt": "2024-11-13T12:56:39.236004", "lvl": "target", "msg": "<5>[    0.504941] (1): \"0.flash\""}
- {"dt": "2024-11-13T12:56:39.236072", "lvl": "target", "msg": "<5>[    0.505024] into device \"0.flash\""}
- {"dt": "2024-11-13T12:56:39.253412", "lvl": "target", "msg": "<6>[    0.522200] CAN device driver interface"}
- {"dt": "2024-11-13T12:56:39.254386", "lvl": "target", "msg": "<6>[    0.523218] bgmac_bcma: Broadcom 47xx GBit MAC driver loaded"}
- {"dt": "2024-11-13T12:56:39.255450", "lvl": "target", "msg": "<6>[    0.524285] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-11-13T12:56:39.255521", "lvl": "target", "msg": "<6>[    0.524425] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-11-13T12:56:39.255767", "lvl": "target", "msg": "<6>[    0.524652] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-11-13T12:56:39.255939", "lvl": "target", "msg": "<6>[    0.524838] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-11-13T12:56:39.260020", "lvl": "target", "msg": "<6>[    0.528816] pegasus: Pegasus/Pegasus II USB Ethernet driver"}
- {"dt": "2024-11-13T12:56:39.260124", "lvl": "target", "msg": "<6>[    0.529033] usbcore: registered new interface driver pegasus"}
- {"dt": "2024-11-13T12:56:39.260412", "lvl": "target", "msg": "<6>[    0.529228] usbcore: registered new interface driver asix"}
- {"dt": "2024-11-13T12:56:39.260568", "lvl": "target", "msg": "<6>[    0.529461] usbcore: registered new interface driver ax88179_178a"}
- {"dt": "2024-11-13T12:56:39.260855", "lvl": "target", "msg": "<6>[    0.529692] usbcore: registered new interface driver cdc_ether"}
- {"dt": "2024-11-13T12:56:39.261020", "lvl": "target", "msg": "<6>[    0.529913] usbcore: registered new interface driver smsc75xx"}
- {"dt": "2024-11-13T12:56:39.261255", "lvl": "target", "msg": "<6>[    0.530131] usbcore: registered new interface driver smsc95xx"}
- {"dt": "2024-11-13T12:56:39.261456", "lvl": "target", "msg": "<6>[    0.530353] usbcore: registered new interface driver net1080"}
- {"dt": "2024-11-13T12:56:39.261731", "lvl": "target", "msg": "<6>[    0.530575] usbcore: registered new interface driver cdc_subset"}
- {"dt": "2024-11-13T12:56:39.261896", "lvl": "target", "msg": "<6>[    0.530794] usbcore: registered new interface driver zaurus"}
- {"dt": "2024-11-13T12:56:39.262150", "lvl": "target", "msg": "<6>[    0.531015] usbcore: registered new interface driver cdc_ncm"}
- {"dt": "2024-11-13T12:56:39.266527", "lvl": "target", "msg": "<6>[    0.535323] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-11-13T12:56:39.273790", "lvl": "target", "msg": "<6>[    0.542581] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-11-13T12:56:39.274699", "lvl": "target", "msg": "<6>[    0.543331] rtc-pl031 9010000.pl031: setting system clock to 2024-11-13T12:56:39 UTC (1731502599)"}
- {"dt": "2024-11-13T12:56:39.276317", "lvl": "target", "msg": "<6>[    0.545152] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-11-13T12:56:39.289511", "lvl": "target", "msg": "<6>[    0.558303] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-11-13T12:56:39.289594", "lvl": "target", "msg": "<6>[    0.558487] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-11-13T12:56:39.291270", "lvl": "target", "msg": "<6>[    0.560078] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-11-13T12:56:39.292695", "lvl": "target", "msg": "<6>[    0.561531] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-11-13T12:56:39.296737", "lvl": "target", "msg": "<6>[    0.565536] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-11-13T12:56:39.296818", "lvl": "target", "msg": "<6>[    0.565704] usbhid: USB HID core driver"}
- {"dt": "2024-11-13T12:56:39.302910", "lvl": "target", "msg": "<6>[    0.571704] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-11-13T12:56:39.307830", "lvl": "target", "msg": "<6>[    0.576623] Segment Routing with IPv6"}
- {"dt": "2024-11-13T12:56:39.307910", "lvl": "target", "msg": "<6>[    0.576865] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-11-13T12:56:39.308508", "lvl": "target", "msg": "<6>[    0.577316] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2024-11-13T12:56:39.310512", "lvl": "target", "msg": "<6>[    0.579312] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-11-13T12:56:39.310590", "lvl": "target", "msg": "<6>[    0.579475] can: controller area network core"}
- {"dt": "2024-11-13T12:56:39.311116", "lvl": "target", "msg": "<6>[    0.579873] NET: Registered PF_CAN protocol family"}
- {"dt": "2024-11-13T12:56:39.311160", "lvl": "target", "msg": "<6>[    0.580052] can: raw protocol"}
- {"dt": "2024-11-13T12:56:39.311641", "lvl": "target", "msg": "<6>[    0.580477] can: broadcast manager protocol"}
- {"dt": "2024-11-13T12:56:39.311743", "lvl": "target", "msg": "<6>[    0.580688] can: netlink gateway - max_hops=1"}
- {"dt": "2024-11-13T12:56:39.312795", "lvl": "target", "msg": "<5>[    0.581628] Key type dns_resolver registered"}
- {"dt": "2024-11-13T12:56:39.313254", "lvl": "target", "msg": "<6>[    0.582124] ThumbEE CPU extension supported."}
- {"dt": "2024-11-13T12:56:39.313370", "lvl": "target", "msg": "<5>[    0.582303] Registering SWP/SWPB emulation handler"}
- {"dt": "2024-11-13T12:56:39.324402", "lvl": "target", "msg": "<5>[    0.593195] Loading compiled-in X.509 certificates"}
- {"dt": "2024-11-13T12:56:39.340470", "lvl": "target", "msg": "<6>[    0.609264] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-11-13T12:56:39.657078", "lvl": "target", "msg": "<6>[    0.925503] clk: Disabling unused clocks"}
- {"dt": "2024-11-13T12:56:39.657277", "lvl": "target", "msg": "<6>[    0.925781] PM: genpd: Disabling unused power domains"}
- {"dt": "2024-11-13T12:56:39.695804", "lvl": "target", "msg": "<6>[    0.964513] EXT4-fs (vda): orphan cleanup on readonly fs"}
- {"dt": "2024-11-13T12:56:39.696669", "lvl": "target", "msg": "<6>[    0.965309] EXT4-fs (vda): mounted filesystem 31d198d0-69d2-49e7-a44f-8143470115fd ro with ordered data mode. Quota mode: disabled."}
- {"dt": "2024-11-13T12:56:39.698317", "lvl": "target", "msg": "<6>[    0.967134] VFS: Mounted root (ext4 filesystem) readonly on device 254:0."}
- {"dt": "2024-11-13T12:56:39.700070", "lvl": "target", "msg": "<6>[    0.968867] devtmpfs: mounted"}
- {"dt": "2024-11-13T12:56:39.792930", "lvl": "target", "msg": "<6>[    1.061592] Freeing unused kernel image (initmem) memory: 2048K"}
- {"dt": "2024-11-13T12:56:39.831535", "lvl": "target", "msg": "<6>[    1.100290] Run /sbin/init as init process"}
- {"dt": "2024-11-13T12:56:39.831646", "lvl": "target", "msg": "<7>[    1.100430]   with arguments:"}
- {"dt": "2024-11-13T12:56:39.831729", "lvl": "target", "msg": "<7>[    1.100526]     /sbin/init"}
- {"dt": "2024-11-13T12:56:39.831791", "lvl": "target", "msg": "<7>[    1.100611]     verbose"}
- {"dt": "2024-11-13T12:56:39.831845", "lvl": "target", "msg": "<7>[    1.100696]   with environment:"}
- {"dt": "2024-11-13T12:56:39.831910", "lvl": "target", "msg": "<7>[    1.100794]     HOME=/"}
- {"dt": "2024-11-13T12:56:39.831963", "lvl": "target", "msg": "<7>[    1.100865]     TERM=linux"}
- {"dt": "2024-11-13T12:56:39.848487", "lvl": "target", "msg": "<1>[    1.117250] 8<--- cut here ---"}
- {"dt": "2024-11-13T12:56:39.848592", "lvl": "target", "msg": "<1>[    1.117372] Unhandled fault: synchronous abort (translation table walk) (0xa17) at 0x0055913d"}
- {"dt": "2024-11-13T12:56:39.848776", "lvl": "target", "msg": "<1>[    1.117624] [0055913d] *pgd=439e5003, *pmd=13f841003"}
- {"dt": "2024-11-13T12:56:39.849084", "lvl": "debug", "msg": "Matched prompt #1: Unhandled fault"}
- {"dt": "2024-11-13T12:56:39.849140", "lvl": "debug", "msg": "Setting prompt string to ['\\\\r', 'root@(.*):[/~]#', 'login:', 'Login incorrect']"}
- {"dt": "2024-11-13T12:56:39.849264", "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', 'root@(.*):[/~]#', 'login:', 'Login incorrect']"}
- {"dt": "2024-11-13T12:56:39.849325", "lvl": "warning", "msg": "login-action: kernel 'fault'"}
- {"dt": "2024-11-13T12:56:39.849373", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:14:58)"}
- {"dt": "2024-11-13T12:56:39.849416", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:29)"}
- {"dt": "2024-11-13T12:56:39.849478", "lvl": "target", "msg": "<0>[    1.118110] Internal error: : a17 [#1] SMP ARM"}
- {"dt": "2024-11-13T12:56:39.849520", "lvl": "target", "msg": "<4>[    1.118347] Modules linked in:"}
- {"dt": "2024-11-13T12:56:39.849751", "lvl": "target", "msg": "<4>[    1.118609] CPU: 0 UID: 0 PID: 1 Comm: init Not tainted 6.12.0-rc7 #1"}
- {"dt": "2024-11-13T12:56:39.849892", "lvl": "target", "msg": "<4>[    1.118826] Hardware name: Generic DT based system"}
- {"dt": "2024-11-13T12:56:39.850488", "lvl": "target", "msg": "<4>[    1.119005] PC is at __clear_user_std+0x1c/0x68"}
- {"dt": "2024-11-13T12:56:39.850560", "lvl": "target", "msg": "<4>[    1.119461] LR is at elf_load+0x1b0/0x214"}
- {"dt": "2024-11-13T12:56:39.850685", "lvl": "target", "msg": "<4>[    1.119587] pc : [<c1317cdc>]    lr : [<c0657724>]    psr: 80000013"}
- {"dt": "2024-11-13T12:56:39.850828", "lvl": "target", "msg": "<4>[    1.119752] sp : f0825e78  ip : 00000001  fp : 00000000"}
- {"dt": "2024-11-13T12:56:39.850972", "lvl": "target", "msg": "<4>[    1.119896] r10: 00000000  r9 : b5003500  r8 : 00557000"}
- {"dt": "2024-11-13T12:56:39.851142", "lvl": "target", "msg": "<4>[    1.120042] r7 : 00000003  r6 : c2933280  r5 : 005596f0  r4 : 0055913d"}
- {"dt": "2024-11-13T12:56:39.851313", "lvl": "target", "msg": "<4>[    1.120213] r3 : b5003500  r2 : 00000000  r1 : 00000ec3  r0 : 0055913d"}
- {"dt": "2024-11-13T12:56:39.851530", "lvl": "target", "msg": "<4>[    1.120411] Flags: Nzcv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user"}
- {"dt": "2024-11-13T12:56:39.851697", "lvl": "target", "msg": "<4>[    1.120607] Control: 30c5387d  Table: 429e5480  DAC: fffffffd"}
- {"dt": "2024-11-13T12:56:39.851966", "lvl": "target", "msg": "<1>[    1.120776] Register r0 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.852111", "lvl": "target", "msg": "<1>[    1.121037] Register r1 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.852245", "lvl": "target", "msg": "<1>[    1.121174] Register r2 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:39.852377", "lvl": "target", "msg": "<1>[    1.121307] Register r3 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.852504", "lvl": "target", "msg": "<1>[    1.121440] Register r4 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.852635", "lvl": "target", "msg": "<1>[    1.121566] Register r5 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.853045", "lvl": "target", "msg": "<1>[    1.121698] Register r6 information: slab kmalloc-512 start c2933200 pointer offset 128 size 512"}
- {"dt": "2024-11-13T12:56:39.853249", "lvl": "target", "msg": "<1>[    1.122130] Register r7 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.853513", "lvl": "target", "msg": "<1>[    1.122317] Register r8 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.853584", "lvl": "target", "msg": "<1>[    1.122502] Register r9 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.853712", "lvl": "target", "msg": "<1>[    1.122646] Register r10 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:39.853840", "lvl": "target", "msg": "<1>[    1.122775] Register r11 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:39.853975", "lvl": "target", "msg": "<1>[    1.122903] Register r12 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:39.854394", "lvl": "target", "msg": "<0>[    1.123052] Process init (pid: 1, stack limit = 0x5e52dc5c)"}
- {"dt": "2024-11-13T12:56:39.854481", "lvl": "target", "msg": "<0>[    1.123423] Stack: (0xf0825e78 to 0xf0826000)"}
- {"dt": "2024-11-13T12:56:39.854736", "lvl": "target", "msg": "<0>[    1.123596] 5e60:                                                       00000ec3 c0657724"}
- {"dt": "2024-11-13T12:56:39.854934", "lvl": "target", "msg": "<0>[    1.123803] 5e80: 00000012 000000d1 000d1878 c2933000 c2933280 000d36f0 00000004 000d0e7c"}
- {"dt": "2024-11-13T12:56:39.855139", "lvl": "target", "msg": "<0>[    1.124000] 5ea0: 00486000 c0657b78 00000012 00000000 c2933200 c29e7080 c2940000 00000003"}
- {"dt": "2024-11-13T12:56:39.855336", "lvl": "target", "msg": "<0>[    1.124207] 5ec0: 000d0e7c 00000000 000d0e7c c2804e40 c29e54c0 00001000 00000034 00000001"}
- {"dt": "2024-11-13T12:56:39.855532", "lvl": "target", "msg": "<0>[    1.124402] 5ee0: c29e7000 00000000 00000034 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:39.855722", "lvl": "target", "msg": "<0>[    1.124598] 5f00: 00000000 0f89da2a 00000000 fffffff8 c2940000 c2933000 c1eadd60 00000006"}
- {"dt": "2024-11-13T12:56:39.855928", "lvl": "target", "msg": "<0>[    1.124790] 5f20: c1eb25e4 c293305c c20b1c08 c05fb5d4 c05fa7c8 00000002 c1eadd40 00000000"}
- {"dt": "2024-11-13T12:56:39.856124", "lvl": "target", "msg": "<0>[    1.124990] 5f40: 00000001 00000001 00000100 00000000 c2940000 0f89da2a 00000000 00000000"}
- {"dt": "2024-11-13T12:56:39.856327", "lvl": "target", "msg": "<0>[    1.125191] 5f60: c2933000 c2952000 c1e09254 c1e092e0 00000000 00000000 00000000 c05fbfdc"}
- {"dt": "2024-11-13T12:56:39.856530", "lvl": "target", "msg": "<0>[    1.125394] 5f80: c207d000 c189197c 00000000 00000000 00000000 c13453d0 c207d000 c1360048"}
- {"dt": "2024-11-13T12:56:39.856735", "lvl": "target", "msg": "<0>[    1.125597] 5fa0: 00000000 c1360124 00000000 c040029c 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:39.856939", "lvl": "target", "msg": "<0>[    1.125797] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:39.857258", "lvl": "target", "msg": "<0>[    1.125999] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:39.857797", "lvl": "target", "msg": "<0>[    1.126249] Call trace: "}
- {"dt": "2024-11-13T12:56:39.857843", "lvl": "target", "msg": "<0>[    1.126598]  __clear_user_std from elf_load+0x1b0/0x214"}
- {"dt": "2024-11-13T12:56:39.858006", "lvl": "target", "msg": "<0>[    1.126907]  elf_load from load_elf_binary+0x3f0/0x1310"}
- {"dt": "2024-11-13T12:56:39.858154", "lvl": "target", "msg": "<0>[    1.127069]  load_elf_binary from bprm_execve+0x1dc/0x51c"}
- {"dt": "2024-11-13T12:56:39.858301", "lvl": "target", "msg": "<0>[    1.127221]  bprm_execve from kernel_execve+0xec/0x1a4"}
- {"dt": "2024-11-13T12:56:39.858464", "lvl": "target", "msg": "<0>[    1.127363]  kernel_execve from try_to_run_init_process+0xc/0x38"}
- {"dt": "2024-11-13T12:56:39.858632", "lvl": "target", "msg": "<0>[    1.127527]  try_to_run_init_process from kernel_init+0xdc/0x12c"}
- {"dt": "2024-11-13T12:56:39.858776", "lvl": "target", "msg": "<0>[    1.127694]  kernel_init from ret_from_fork+0x14/0x38"}
- {"dt": "2024-11-13T12:56:39.858929", "lvl": "target", "msg": "<0>[    1.127856] Exception stack(0xf0825fb0 to 0xf0825ff8)"}
- {"dt": "2024-11-13T12:56:39.859130", "lvl": "target", "msg": "<0>[    1.127996] 5fa0:                                     00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:39.859336", "lvl": "target", "msg": "<0>[    1.128198] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:39.859503", "lvl": "target", "msg": "<0>[    1.128401] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000"}
- {"dt": "2024-11-13T12:56:39.859788", "lvl": "target", "msg": "<0>[    1.128689] Code: ba00000d e210c003 0a000005 e35c0002 (e4c02001) "}
- {"dt": "2024-11-13T12:56:39.860128", "lvl": "target", "msg": "<4>[    1.129062] ---[ end trace 0000000000000000 ]---"}
- {"dt": "2024-11-13T12:56:39.860375", "lvl": "target", "msg": "<6>[    1.129266] note: init[1] exited with irqs disabled"}
- {"dt": "2024-11-13T12:56:39.860871", "lvl": "target", "msg": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b"}
- {"dt": "2024-11-13T12:56:39.861113", "lvl": "debug", "msg": "Matched prompt #9: Kernel panic - not syncing"}
- {"dt": "2024-11-13T12:56:39.861161", "lvl": "debug", "msg": "Setting prompt string to ['end Kernel panic[^\\\\r]*\\\\r', 'root@(.*):[/~]#', 'login:', 'Login incorrect']"}
- {"dt": "2024-11-13T12:56:39.862044", "lvl": "target", "msg": "<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:39.862263", "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', 'root@(.*):[/~]#', 'login:', 'Login incorrect']"}
- {"dt": "2024-11-13T12:56:39.862313", "lvl": "error", "msg": "login-action kernel 'panic'"}
- {"dt": "2024-11-13T12:56:39.862366", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "1.90", "result": "fail", "extra": {"extra": [{"kind": "fault", "message": "<1>[    1.117372] Unhandled fault: synchronous abort (translation table walk) (0xa17) at 0x0055913d"}, {"kind": "panic", "message": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}]}}}
- {"dt": "2024-11-13T12:56:39.862488", "lvl": "exception", "msg": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:39.862543", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:39.862591", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "1.90", "result": "fail", "extra": {"fail": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:39.862669", "lvl": "error", "msg": "auto-login-action failed: 1 of 1 attempts. '<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---'"}
- {"dt": "2024-11-13T12:56:39.862728", "lvl": "exception", "msg": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:39.862779", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:39.862829", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "auto-login-action", "level": "2.2", "duration": "2.26", "result": "fail", "extra": {"fail": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:39.862908", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. '<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---'"}
- {"dt": "2024-11-13T12:56:39.862974", "lvl": "exception", "msg": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:39.863022", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:39.863071", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "boot-image-retry", "level": "2", "duration": "2.26", "result": "fail", "extra": {"fail": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:39.863184", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-11-13T12:56:39.863275", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/url-repo-action-26iifmow/url_repo"}
- {"dt": "2024-11-13T12:56:39.871443", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/kernel"}
- {"dt": "2024-11-13T12:56:39.873431", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/rootfs"}
- {"dt": "2024-11-13T12:56:39.873543", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1/deployimages-rz960_mo/rootfs.modules"}
- {"dt": "2024-11-13T12:56:39.878517", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-11-13T12:56:39.878588", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:39.878651", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:39.878758", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-11-13T12:56:40.879808", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-11-13T12:56:40.879990", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2024-11-13T12:56:40.980226", "lvl": "input", "msg": "poweroff"}
- {"dt": "2024-11-13T12:56:45.985529", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-11-13T12:56:46.185958", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:06) [common]"}
- {"dt": "2024-11-13T12:56:46.186130", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-oq_jp8v8-1"}
- {"dt": "2024-11-13T12:56:46.222493", "lvl": "error", "msg": "JobError: Your job cannot terminate cleanly."}
- {"dt": "2024-11-13T12:56:46.222566", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "fail", "error_msg": "<0>[    1.129680] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.130891] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---", "error_type": "Job"}}
