- {"dt": "2024-11-13T12:56:48.347610", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.05.dev0067"}
- {"dt": "2024-11-13T12:56:48.347697", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-11-13T12:56:48.347776", "lvl": "info", "msg": "Start time: 2024-11-13 12:56:48.347768+00:00 (UTC)"}
- {"dt": "2024-11-13T12:56:48.348379", "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:48.348695", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxsuite/plan/62/build_1/zImage exists"}
- {"dt": "2024-11-13T12:56:48.348794", "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:48.348873", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxsuite/plan/62/build_1/modules.tar.xz exists"}
- {"dt": "2024-11-13T12:56:48.357001", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:8.2.4+ds-1, host architecture: amd64"}
- {"dt": "2024-11-13T12:56:48.357188", "lvl": "warning", "msg": "Missing image arg for url_repo"}
- {"dt": "2024-11-13T12:56:48.357291", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2024-11-13T12:56:48.357413", "lvl": "info", "msg": "validate duration: 0.01"}
- {"dt": "2024-11-13T12:56:48.357479", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2024-11-13T12:56:48.357598", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.357701", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.357888", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv"}
- {"dt": "2024-11-13T12:56:48.358086", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin"}
- {"dt": "2024-11-13T12:56:48.358225", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/tests"}
- {"dt": "2024-11-13T12:56:48.358338", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/results"}
- {"dt": "2024-11-13T12:56:48.358416", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-add-keys"}
- {"dt": "2024-11-13T12:56:48.358547", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-add-sources"}
- {"dt": "2024-11-13T12:56:48.358673", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-background-process-start"}
- {"dt": "2024-11-13T12:56:48.358791", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-background-process-stop"}
- {"dt": "2024-11-13T12:56:48.358911", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-common-functions"}
- {"dt": "2024-11-13T12:56:48.359031", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2024-11-13T12:56:48.359147", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-install-packages"}
- {"dt": "2024-11-13T12:56:48.359267", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-installed-packages"}
- {"dt": "2024-11-13T12:56:48.359381", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-os-build"}
- {"dt": "2024-11-13T12:56:48.359499", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-probe-channel"}
- {"dt": "2024-11-13T12:56:48.359611", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-probe-ip"}
- {"dt": "2024-11-13T12:56:48.359724", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-target-ip"}
- {"dt": "2024-11-13T12:56:48.359836", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-target-mac"}
- {"dt": "2024-11-13T12:56:48.359948", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-target-storage"}
- {"dt": "2024-11-13T12:56:48.360064", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-test-case"}
- {"dt": "2024-11-13T12:56:48.360177", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-test-event"}
- {"dt": "2024-11-13T12:56:48.360290", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-test-feedback"}
- {"dt": "2024-11-13T12:56:48.360403", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-test-raise"}
- {"dt": "2024-11-13T12:56:48.360517", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-test-reference"}
- {"dt": "2024-11-13T12:56:48.360628", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-test-runner"}
- {"dt": "2024-11-13T12:56:48.360742", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-test-set"}
- {"dt": "2024-11-13T12:56:48.360854", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-test-shell"}
- {"dt": "2024-11-13T12:56:48.360971", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2024-11-13T12:56:48.361127", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2024-11-13T12:56:48.361241", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/environment"}
- {"dt": "2024-11-13T12:56:48.361328", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-11-13T12:56:48.361376", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2024-11-13T12:56:48.361419", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2024-11-13T12:56:48.361491", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.361535", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-11-13T12:56:48.361586", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.361640", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.361680", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-11-13T12:56:48.361726", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.361776", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.361823", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-11-13T12:56:48.361878", "lvl": "debug", "msg": "start: 1.1.3.1 url-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.361930", "lvl": "debug", "msg": "start: 1.1.3.1.1 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.361985", "lvl": "debug", "msg": "start: 1.1.3.1.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.362111", "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:48.362162", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/url-repo-action-udwmhli2/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar"}
- {"dt": "2024-11-13T12:56:48.362202", "lvl": "debug", "msg": "total size: 13692979 (13\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.362243", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-11-13T12:56:48.362554", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.364753", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.366608", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.368429", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.370338", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.372165", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.373990", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.375880", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.377714", "lvl": "debug", "msg": "progress  40\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.379545", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.381311", "lvl": "debug", "msg": "progress  50\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.383160", "lvl": "debug", "msg": "progress  55\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.384978", "lvl": "debug", "msg": "progress  60\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.386838", "lvl": "debug", "msg": "progress  65\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.389603", "lvl": "debug", "msg": "progress  70\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.392153", "lvl": "debug", "msg": "progress  75\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.394876", "lvl": "debug", "msg": "progress  80\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.398388", "lvl": "debug", "msg": "progress  85\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.400769", "lvl": "debug", "msg": "progress  90\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.405105", "lvl": "debug", "msg": "progress  95\u202F% (12\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.409074", "lvl": "debug", "msg": "progress 100\u202F% (13\u202FMB)"}
- {"dt": "2024-11-13T12:56:48.416879", "lvl": "info", "msg": "13\u202FMB downloaded in 0.05\u202Fs (238.85\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:48.416986", "lvl": "debug", "msg": "end: 1.1.3.1.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.417044", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.1.3.1.1.1", "duration": "0.06", "result": "pass", "extra": {"label": "url_repo", "size": ! "13692979", "md5sum": "30eb6f40044a706e0edae9b32f16a662", "sha256sum": "c850bed1a93201581f64754245d951f99a4942fe707b841bfe6ea3c9cd7624b0", "sha512sum": "3435daea585c61fec8b4c72eeb412c9d550e229d1220195bd592f268fe6baad5d210433f9802e5f9931fb5b8b17a10a4c5c569f16a3c286247222b96634c6310"}}}
- {"dt": "2024-11-13T12:56:48.417155", "lvl": "debug", "msg": "end: 1.1.3.1.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.417215", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2024-11-13T12:56:48.417285", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/0/tests/0_ltp-nptl"}
- {"dt": "2024-11-13T12:56:48.417350", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/url-repo-action-udwmhli2/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/0/tests/0_ltp-nptl"}
- {"dt": "2024-11-13T12:56:48.613689", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/0/tests/0_ltp-nptl/automated/linux/ltp/ltp.yaml"}
- {"dt": "2024-11-13T12:56:48.613980", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2024-11-13T12:56:48.614052", "lvl": "debug", "msg": "end: 1.1.3.1 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.614113", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.614693", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.614746", "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-nptl", "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:48.614858", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.615636", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.615691", "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-nptl", "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:48.615800", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:48.624003", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/0/tests/0_ltp-nptl test_uuid 1_1.1.3.1"}
- {"dt": "2024-11-13T12:56:48.624083", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2024-11-13T12:56:48.624137", "lvl": "debug", "msg": "TST_CMDFILES='nptl'"}
- {"dt": "2024-11-13T12:56:48.624188", "lvl": "debug", "msg": "SKIPFILE='/tuxtest/skipfiles/armv7/ltp-nptl'"}
- {"dt": "2024-11-13T12:56:48.624235", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2024-11-13T12:56:48.624281", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2024-11-13T12:56:48.624325", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2024-11-13T12:56:48.624371", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2024-11-13T12:56:48.624416", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2024-11-13T12:56:48.624461", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2024-11-13T12:56:48.624616", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.624686", "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-nptl", "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-y146hlow-1/lava-overlay-vofinsuv/lava-1/0/tests/0_ltp-nptl/run.sh"}}}
- {"dt": "2024-11-13T12:56:48.624788", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-11-13T12:56:48.624834", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/lava-overlay-vofinsuv/lava-1/0 for stage 0"}
- {"dt": "2024-11-13T12:56:48.624905", "lvl": "debug", "msg": "- 0_ltp-nptl"}
- {"dt": "2024-11-13T12:56:48.624981", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:48.625040", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:54.500349", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:06) [common]"}
- {"dt": "2024-11-13T12:56:54.500479", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:54.500540", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:54.500606", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:06) [common]"}
- {"dt": "2024-11-13T12:56:54.500671", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:54.500716", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2024-11-13T12:56:54.500766", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:54.500818", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:54.500868", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:54.500920", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:54.500974", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:54.501144", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxsuite/plan/62/build_1/zImage"}
- {"dt": "2024-11-13T12:56:54.501214", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/deployimages-f5t70g6r/kernel/zImage"}
- {"dt": "2024-11-13T12:56:54.501259", "lvl": "debug", "msg": "total size: 11485696 (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.501301", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-11-13T12:56:54.501391", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.503119", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.505082", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.506784", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.508568", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.510267", "lvl": "debug", "msg": "progress  25\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.512042", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.513735", "lvl": "debug", "msg": "progress  35\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.515504", "lvl": "debug", "msg": "progress  40\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.517185", "lvl": "debug", "msg": "progress  45\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.518968", "lvl": "debug", "msg": "progress  50\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.520649", "lvl": "debug", "msg": "progress  55\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.522520", "lvl": "debug", "msg": "progress  60\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.524228", "lvl": "debug", "msg": "progress  65\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.526044", "lvl": "debug", "msg": "progress  70\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.527750", "lvl": "debug", "msg": "progress  75\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.529552", "lvl": "debug", "msg": "progress  80\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.531254", "lvl": "debug", "msg": "progress  85\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.533047", "lvl": "debug", "msg": "progress  90\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.534980", "lvl": "debug", "msg": "progress  95\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.536770", "lvl": "debug", "msg": "progress 100\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.536881", "lvl": "info", "msg": "10\u202FMB downloaded in 0.04\u202Fs (307.52\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:54.536972", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:54.537021", "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:54.537150", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:54.537212", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:54.537339", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:04:54) [common]"}
- {"dt": "2024-11-13T12:56:54.537536", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4.zst"}
- {"dt": "2024-11-13T12:56:54.537611", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/deployimages-f5t70g6r/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4"}
- {"dt": "2024-11-13T12:56:54.537672", "lvl": "debug", "msg": "total size: 73194706 (69\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.537730", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-11-13T12:56:54.538108", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.554266", "lvl": "debug", "msg": "progress   5\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.564777", "lvl": "debug", "msg": "progress  10\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.574580", "lvl": "debug", "msg": "progress  15\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.584819", "lvl": "debug", "msg": "progress  20\u202F% (13\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.606069", "lvl": "debug", "msg": "progress  25\u202F% (17\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.631973", "lvl": "debug", "msg": "progress  30\u202F% (20\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.653241", "lvl": "debug", "msg": "progress  35\u202F% (24\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.674723", "lvl": "debug", "msg": "progress  40\u202F% (27\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.695883", "lvl": "debug", "msg": "progress  45\u202F% (31\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.718475", "lvl": "debug", "msg": "progress  50\u202F% (34\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.739626", "lvl": "debug", "msg": "progress  55\u202F% (38\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.759987", "lvl": "debug", "msg": "progress  60\u202F% (41\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.781643", "lvl": "debug", "msg": "progress  65\u202F% (45\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.799401", "lvl": "debug", "msg": "progress  70\u202F% (48\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.809155", "lvl": "debug", "msg": "progress  75\u202F% (52\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.818843", "lvl": "debug", "msg": "progress  80\u202F% (55\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.829013", "lvl": "debug", "msg": "progress  85\u202F% (59\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.842067", "lvl": "debug", "msg": "progress  90\u202F% (62\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.852457", "lvl": "debug", "msg": "progress  95\u202F% (66\u202FMB)"}
- {"dt": "2024-11-13T12:56:54.862168", "lvl": "debug", "msg": "progress 100\u202F% (69\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.127725", "lvl": "info", "msg": "69\u202FMB downloaded in 0.59\u202Fs (118.30\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:55.127945", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:01) [common]"}
- {"dt": "2024-11-13T12:56:55.128000", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "0.59", "result": "pass", "extra": {"label": "rootfs", "size": ! "73194706", "md5sum": "d8011d3000995663f497569fe7ae9ba7", "sha256sum": "2ff192e8a743a11de63c31a6361d545e2aa6d9a7d3dd3d872e9b107e06b1954e", "sha512sum": "16b75b03a9a0f1a4b356ca0cfcae27a003be26bfdfa8916ba12d314cc77658dfb9888d43b1479b0ef8157385c30b455aaac3abb75cb19d6e47f9587a7570a549"}}}
- {"dt": "2024-11-13T12:56:55.128118", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:04:53) [common]"}
- {"dt": "2024-11-13T12:56:55.128179", "lvl": "debug", "msg": "start: 1.5.2.1 file-download (timeout 00:04:53) [common]"}
- {"dt": "2024-11-13T12:56:55.128370", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxsuite/plan/62/build_1/modules.tar.xz"}
- {"dt": "2024-11-13T12:56:55.128426", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/deployimages-f5t70g6r/rootfs.modules/modules.tar"}
- {"dt": "2024-11-13T12:56:55.128467", "lvl": "debug", "msg": "total size: 6635564 (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.128507", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-11-13T12:56:55.128890", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.130557", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.131470", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.132371", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.133281", "lvl": "debug", "msg": "progress  20\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.134185", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.135083", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.135987", "lvl": "debug", "msg": "progress  35\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.136971", "lvl": "debug", "msg": "progress  40\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.137877", "lvl": "debug", "msg": "progress  45\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.138774", "lvl": "debug", "msg": "progress  50\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.139671", "lvl": "debug", "msg": "progress  55\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.140571", "lvl": "debug", "msg": "progress  60\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.141480", "lvl": "debug", "msg": "progress  65\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.142384", "lvl": "debug", "msg": "progress  70\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.143287", "lvl": "debug", "msg": "progress  75\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.144271", "lvl": "debug", "msg": "progress  80\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.145168", "lvl": "debug", "msg": "progress  85\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.146253", "lvl": "debug", "msg": "progress  90\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.147155", "lvl": "debug", "msg": "progress  95\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.148055", "lvl": "debug", "msg": "progress 100\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:55.289127", "lvl": "info", "msg": "6\u202FMB downloaded in 0.16\u202Fs (39.39\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:55.289256", "lvl": "debug", "msg": "end: 1.5.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:55.289314", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.2.1", "duration": "0.16", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "6635564", "md5sum": "b603fa1cf36ddee27ca7b529aa460c29", "sha256sum": "0b026d9f0f1cd0e4f47860a9ef6517c7297cec7b62b287601ae43cc5b7c9a67b", "sha512sum": "ccb7aac1885840e2dcddd189030ab5cf0fe3f7d0fc408209dec1028d79c862a23288ee25c8318b919f4379ae30a0625e2b832a0e390735ab653aeecc9d821c1a"}}}
- {"dt": "2024-11-13T12:56:55.289423", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:55.289485", "lvl": "debug", "msg": "start: 1.5.3 append-overlays (timeout 00:04:53) [common]"}
- {"dt": "2024-11-13T12:56:55.289534", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/deployimages-f5t70g6r/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4'"}
- {"dt": "2024-11-13T12:56:56.950856", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2024-11-13T12:56:56.951585", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/compress-overlay-l1do01g5/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2024-11-13T12:56:57.299824", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/deployimages-f5t70g6r/rootfs.modules/modules.tar' to '/'"}
- {"dt": "2024-11-13T12:56:57.632757", "lvl": "debug", "msg": "end: 1.5.3 append-overlays (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:57.633118", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:03) [common]"}
- {"dt": "2024-11-13T12:56:57.633240", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:09) [common]"}
- {"dt": "2024-11-13T12:56:57.633345", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:57.633445", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:57.633528", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:57.633819", "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-y146hlow-1/deployimages-f5t70g6r/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-y146hlow-1/deployimages-f5t70g6r/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4,if=virtio,format=raw,id=hd0"}
- {"dt": "2024-11-13T12:56:57.637150", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2024-11-13T12:56:57.637506", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:57.637591", "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:57.637749", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:57.637817", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:57.637896", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-11-13T12:56:57.637949", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:15:00)"}
- {"dt": "2024-11-13T12:56:57.977176", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0"}
- {"dt": "2024-11-13T12:56:57.977397", "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:57.977614", "lvl": "target", "msg": "[    0.000000] CPU: ARMv7 Processor [414fc0f0] revision 0 (ARMv7), cr=30c5387d"}
- {"dt": "2024-11-13T12:56:57.977762", "lvl": "target", "msg": "[    0.000000] CPU: div instructions available: patching division code"}
- {"dt": "2024-11-13T12:56:57.977931", "lvl": "target", "msg": "[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache"}
- {"dt": "2024-11-13T12:56:57.978054", "lvl": "target", "msg": "[    0.000000] OF: fdt: Machine model: linux,dummy-virt"}
- {"dt": "2024-11-13T12:56:57.978157", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-11-13T12:56:57.978311", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-11-13T12:56:57.978439", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-11-13T12:56:57.978699", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:57.978763", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-11-13T12:56:57.978829", "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:57.978889", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-11-13T12:56:57.978935", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-11-13T12:56:57.978977", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-11-13T12:56:57.979013", "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:57.979085", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:15:00)"}
- {"dt": "2024-11-13T12:56:57.979132", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2024-11-13T12:56:57.979489", "lvl": "target", "msg": "[    0.000000] Memory policy: Data cache writealloc"}
- {"dt": "2024-11-13T12:56:57.979540", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-11-13T12:56:57.981192", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fb800000 on node -1"}
- {"dt": "2024-11-13T12:56:57.983009", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-11-13T12:56:57.983404", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x000000006fffffff]"}
- {"dt": "2024-11-13T12:56:57.983514", "lvl": "target", "msg": "[    0.000000]   Normal   empty"}
- {"dt": "2024-11-13T12:56:57.983690", "lvl": "target", "msg": "[    0.000000]   HighMem  [mem 0x0000000070000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:57.983811", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-11-13T12:56:57.983934", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-11-13T12:56:57.984154", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:57.984456", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:58.092635", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-11-13T12:56:58.092995", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-11-13T12:56:58.093074", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-11-13T12:56:58.093145", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-11-13T12:56:58.093302", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-11-13T12:56:58.094934", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 17 pages/cpu s40844 r8192 d20596 u69632"}
- {"dt": "2024-11-13T12:56:58.095508", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s40844 r8192 d20596 u69632 alloc=17*4096"}
- {"dt": "2024-11-13T12:56:58.095765", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-11-13T12:56:58.096921", "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:58.097802", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2024-11-13T12:56:58.098520", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.098868", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.099356", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2024-11-13T12:56:58.099675", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-11-13T12:56:58.099908", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-11-13T12:56:58.152919", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x0000000069f0f000-0x000000006df0f000] (64MB)"}
- {"dt": "2024-11-13T12:56:58.672117", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-11-13T12:56:58.674776", "lvl": "target", "msg": "<6>[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-11-13T12:56:58.678640", "lvl": "target", "msg": "<6>[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2024-11-13T12:56:58.678728", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-11-13T12:56:58.678794", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=2."}
- {"dt": "2024-11-13T12:56:58.679066", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies."}
- {"dt": "2024-11-13T12:56:58.679206", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-11-13T12:56:58.687351", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16"}
- {"dt": "2024-11-13T12:56:58.693581", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-11-13T12:56:58.693669", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-11-13T12:56:58.695143", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-11-13T12:56:58.695239", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2024-11-13T12:56:58.695743", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-11-13T12:56:58.698951", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-11-13T12:56:58.699626", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @428b0000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-11-13T12:56:58.699962", "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:58.700575", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x00000000428d0000"}
- {"dt": "2024-11-13T12:56:58.701249", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000000428e0000"}
- {"dt": "2024-11-13T12:56:58.702452", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-11-13T12:56:58.713364", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt)."}
- {"dt": "2024-11-13T12:56:58.713844", "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:58.714391", "lvl": "target", "msg": "<6>[    0.000092] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-11-13T12:56:58.714608", "lvl": "target", "msg": "<6>[    0.000406] Switching to timer-based delay loop, resolution 16ns"}
- {"dt": "2024-11-13T12:56:58.718151", "lvl": "target", "msg": "<6>[    0.003826] Console: colour dummy device 80x30"}
- {"dt": "2024-11-13T12:56:58.719279", "lvl": "target", "msg": "<6>[    0.004970] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=625000)"}
- {"dt": "2024-11-13T12:56:58.719867", "lvl": "target", "msg": "<6>[    0.005283] CPU: Testing write buffer coherency: ok"}
- {"dt": "2024-11-13T12:56:58.720044", "lvl": "target", "msg": "<3>[    0.005787] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2024-11-13T12:56:58.720284", "lvl": "target", "msg": "<6>[    0.006098] CPU0: Spectre BHB: enabling loop workaround for all CPUs"}
- {"dt": "2024-11-13T12:56:58.720524", "lvl": "target", "msg": "<6>[    0.006315] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-11-13T12:56:58.721917", "lvl": "target", "msg": "<6>[    0.007640] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.722071", "lvl": "target", "msg": "<6>[    0.007851] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.738729", "lvl": "target", "msg": "<3>[    0.024289] /cpus/cpu@0 missing clock-frequency property"}
- {"dt": "2024-11-13T12:56:58.738824", "lvl": "target", "msg": "<3>[    0.024608] /cpus/cpu@1 missing clock-frequency property"}
- {"dt": "2024-11-13T12:56:58.739131", "lvl": "target", "msg": "<6>[    0.024858] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000"}
- {"dt": "2024-11-13T12:56:58.787189", "lvl": "target", "msg": "<6>[    0.072634] Setting up static identity map for 0x40400000 - 0x40400184"}
- {"dt": "2024-11-13T12:56:58.788509", "lvl": "target", "msg": "<6>[    0.074223] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-11-13T12:56:58.788574", "lvl": "target", "msg": "<6>[    0.074380] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-11-13T12:56:58.789970", "lvl": "target", "msg": "<6>[    0.075665] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2024-11-13T12:56:58.792128", "lvl": "target", "msg": "<6>[    0.077830] EFI services will not be available."}
- {"dt": "2024-11-13T12:56:58.793005", "lvl": "target", "msg": "<6>[    0.078748] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-11-13T12:56:58.796157", "lvl": "target", "msg": "<6>[    0.081159] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-11-13T12:56:58.796353", "lvl": "target", "msg": "<6>[    0.081376] GICv3: CPU1: using allocated LPI pending table @0x00000000428f0000"}
- {"dt": "2024-11-13T12:56:58.796515", "lvl": "target", "msg": "<6>[    0.081829] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001"}
- {"dt": "2024-11-13T12:56:58.796902", "lvl": "target", "msg": "<3>[    0.081873] CPU1: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2024-11-13T12:56:58.799250", "lvl": "target", "msg": "<6>[    0.084941] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-11-13T12:56:58.799472", "lvl": "target", "msg": "<6>[    0.085199] SMP: Total of 2 processors activated (250.00 BogoMIPS)."}
- {"dt": "2024-11-13T12:56:58.799708", "lvl": "target", "msg": "<6>[    0.085492] CPU: All CPU(s) started in SVC mode."}
- {"dt": "2024-11-13T12:56:58.804647", "lvl": "target", "msg": "<6>[    0.090102] 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:58.810730", "lvl": "target", "msg": "<6>[    0.096438] devtmpfs: initialized"}
- {"dt": "2024-11-13T12:56:58.820635", "lvl": "target", "msg": "<6>[    0.103449] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0"}
- {"dt": "2024-11-13T12:56:58.828854", "lvl": "target", "msg": "<6>[    0.114381] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns"}
- {"dt": "2024-11-13T12:56:58.829504", "lvl": "target", "msg": "<6>[    0.115133] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.834875", "lvl": "target", "msg": "<6>[    0.120585] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-11-13T12:56:58.842612", "lvl": "target", "msg": "<6>[    0.128316] DMI not present or invalid."}
- {"dt": "2024-11-13T12:56:58.848154", "lvl": "target", "msg": "<6>[    0.133861] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-11-13T12:56:58.856942", "lvl": "target", "msg": "<6>[    0.142634] DMA: preallocated 256 KiB pool for atomic coherent allocations"}
- {"dt": "2024-11-13T12:56:58.860943", "lvl": "target", "msg": "<6>[    0.146416] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-11-13T12:56:58.861019", "lvl": "target", "msg": "<6>[    0.146658] cpuidle: using governor menu"}
- {"dt": "2024-11-13T12:56:58.861700", "lvl": "target", "msg": "<6>[    0.147420] No ATAGs?"}
- {"dt": "2024-11-13T12:56:58.862504", "lvl": "target", "msg": "<6>[    0.148242] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers."}
- {"dt": "2024-11-13T12:56:58.862667", "lvl": "target", "msg": "<6>[    0.148488] hw-breakpoint: maximum watchpoint size is 8 bytes."}
- {"dt": "2024-11-13T12:56:58.865837", "lvl": "target", "msg": "<6>[    0.151559] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-11-13T12:56:58.893271", "lvl": "target", "msg": "<6>[    0.178839] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 28, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-11-13T12:56:58.894497", "lvl": "target", "msg": "<6>[    0.180132] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-11-13T12:56:58.894573", "lvl": "target", "msg": "<6>[    0.180132] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-11-13T12:56:58.894832", "lvl": "target", "msg": "<6>[    0.180596] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-11-13T12:56:58.894902", "lvl": "target", "msg": "<6>[    0.180596] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-11-13T12:56:58.915662", "lvl": "target", "msg": "<6>[    0.201247] iommu: Default domain type: Translated"}
- {"dt": "2024-11-13T12:56:58.915748", "lvl": "target", "msg": "<6>[    0.201372] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-11-13T12:56:58.917843", "lvl": "target", "msg": "<5>[    0.203527] SCSI subsystem initialized"}
- {"dt": "2024-11-13T12:56:58.918591", "lvl": "target", "msg": "<7>[    0.204282] libata version 3.00 loaded."}
- {"dt": "2024-11-13T12:56:58.919353", "lvl": "target", "msg": "<6>[    0.205071] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-11-13T12:56:58.919636", "lvl": "target", "msg": "<6>[    0.205321] usbcore: registered new interface driver hub"}
- {"dt": "2024-11-13T12:56:58.919748", "lvl": "target", "msg": "<6>[    0.205592] usbcore: registered new device driver usb"}
- {"dt": "2024-11-13T12:56:58.921267", "lvl": "target", "msg": "<6>[    0.206983] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-11-13T12:56:58.921372", "lvl": "target", "msg": "<6>[    0.207119] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-11-13T12:56:58.921524", "lvl": "target", "msg": "<6>[    0.207386] PTP clock support registered"}
- {"dt": "2024-11-13T12:56:58.921811", "lvl": "target", "msg": "<6>[    0.207694] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-11-13T12:56:58.922812", "lvl": "target", "msg": "<6>[    0.208555] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-11-13T12:56:58.931341", "lvl": "target", "msg": "<6>[    0.217053] vgaarb: loaded"}
- {"dt": "2024-11-13T12:56:58.934557", "lvl": "target", "msg": "<6>[    0.220264] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-11-13T12:56:58.956632", "lvl": "target", "msg": "<6>[    0.242303] NET: Registered PF_INET protocol family"}
- {"dt": "2024-11-13T12:56:58.957554", "lvl": "target", "msg": "<6>[    0.243291] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.961390", "lvl": "target", "msg": "<6>[    0.247085] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.961577", "lvl": "target", "msg": "<6>[    0.247354] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.961803", "lvl": "target", "msg": "<6>[    0.247585] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.962166", "lvl": "target", "msg": "<6>[    0.247956] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.962482", "lvl": "target", "msg": "<6>[    0.248299] TCP: Hash tables configured (established 8192 bind 8192)"}
- {"dt": "2024-11-13T12:56:58.963241", "lvl": "target", "msg": "<6>[    0.248986] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.963576", "lvl": "target", "msg": "<6>[    0.249365] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)"}
- {"dt": "2024-11-13T12:56:58.964450", "lvl": "target", "msg": "<6>[    0.250199] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-11-13T12:56:58.969056", "lvl": "target", "msg": "<6>[    0.254766] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-11-13T12:56:58.969127", "lvl": "target", "msg": "<6>[    0.254944] RPC: Registered udp transport module."}
- {"dt": "2024-11-13T12:56:58.969230", "lvl": "target", "msg": "<6>[    0.255073] RPC: Registered tcp transport module."}
- {"dt": "2024-11-13T12:56:58.969363", "lvl": "target", "msg": "<6>[    0.255206] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-11-13T12:56:58.969525", "lvl": "target", "msg": "<6>[    0.255340] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-11-13T12:56:58.969813", "lvl": "target", "msg": "<6>[    0.255544] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-11-13T12:56:58.971527", "lvl": "target", "msg": "<5>[    0.257246] Initialise system trusted keyrings"}
- {"dt": "2024-11-13T12:56:58.972996", "lvl": "target", "msg": "<6>[    0.258734] workingset: timestamp_bits=30 max_order=20 bucket_order=0"}
- {"dt": "2024-11-13T12:56:58.974476", "lvl": "target", "msg": "<6>[    0.260214] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-11-13T12:56:58.976062", "lvl": "target", "msg": "<5>[    0.261800] NFS: Registering the id_resolver key type"}
- {"dt": "2024-11-13T12:56:58.976331", "lvl": "target", "msg": "<5>[    0.262135] Key type id_resolver registered"}
- {"dt": "2024-11-13T12:56:58.976452", "lvl": "target", "msg": "<5>[    0.262282] Key type id_legacy registered"}
- {"dt": "2024-11-13T12:56:58.976777", "lvl": "target", "msg": "<6>[    0.262577] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-11-13T12:56:58.977044", "lvl": "target", "msg": "<6>[    0.262799] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-11-13T12:56:58.978010", "lvl": "target", "msg": "<5>[    0.263752] Key type asymmetric registered"}
- {"dt": "2024-11-13T12:56:58.978065", "lvl": "target", "msg": "<5>[    0.263900] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-11-13T12:56:58.978308", "lvl": "target", "msg": "<6>[    0.264153] bounce: pool size: 64 pages"}
- {"dt": "2024-11-13T12:56:58.978554", "lvl": "target", "msg": "<6>[    0.264353] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)"}
- {"dt": "2024-11-13T12:56:58.978796", "lvl": "target", "msg": "<6>[    0.264600] io scheduler mq-deadline registered"}
- {"dt": "2024-11-13T12:56:58.978880", "lvl": "target", "msg": "<6>[    0.264751] io scheduler kyber registered"}
- {"dt": "2024-11-13T12:56:58.979121", "lvl": "target", "msg": "<6>[    0.264919] io scheduler bfq registered"}
- {"dt": "2024-11-13T12:56:58.997447", "lvl": "target", "msg": "<6>[    0.283064] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-11-13T12:56:59.004984", "lvl": "target", "msg": "<6>[    0.290693] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-11-13T12:56:59.007339", "lvl": "target", "msg": "<6>[    0.293066] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-11-13T12:56:59.007986", "lvl": "target", "msg": "<6>[    0.293662] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-11-13T12:56:59.008484", "lvl": "target", "msg": "<6>[    0.294226] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-11-13T12:56:59.008695", "lvl": "target", "msg": "<6>[    0.294461] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-11-13T12:56:59.009254", "lvl": "target", "msg": "<4>[    0.295003] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-11-13T12:56:59.009920", "lvl": "target", "msg": "<6>[    0.295490] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-11-13T12:56:59.010923", "lvl": "target", "msg": "<6>[    0.296668] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-11-13T12:56:59.011178", "lvl": "target", "msg": "<6>[    0.297003] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-11-13T12:56:59.011419", "lvl": "target", "msg": "<6>[    0.297181] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-11-13T12:56:59.011544", "lvl": "target", "msg": "<6>[    0.297358] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-11-13T12:56:59.011711", "lvl": "target", "msg": "<6>[    0.297516] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-11-13T12:56:59.012746", "lvl": "target", "msg": "<6>[    0.298490] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-11-13T12:56:59.015211", "lvl": "target", "msg": "<6>[    0.300921] pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint"}
- {"dt": "2024-11-13T12:56:59.015495", "lvl": "target", "msg": "<6>[    0.301318] pci 0000:00:01.0: BAR 0 [io  0x0000-0x007f]"}
- {"dt": "2024-11-13T12:56:59.015658", "lvl": "target", "msg": "<6>[    0.301494] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]"}
- {"dt": "2024-11-13T12:56:59.015939", "lvl": "target", "msg": "<6>[    0.301668] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2024-11-13T12:56:59.016977", "lvl": "target", "msg": "<6>[    0.302723] PCI: bus0: Fast back to back transfers disabled"}
- {"dt": "2024-11-13T12:56:59.018079", "lvl": "target", "msg": "<6>[    0.303821] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned"}
- {"dt": "2024-11-13T12:56:59.018396", "lvl": "target", "msg": "<6>[    0.304193] pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned"}
- {"dt": "2024-11-13T12:56:59.018558", "lvl": "target", "msg": "<6>[    0.304377] pci 0000:00:01.0: BAR 0 [io  0x1000-0x107f]: assigned"}
- {"dt": "2024-11-13T12:56:59.018853", "lvl": "target", "msg": "<6>[    0.304683] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2024-11-13T12:56:59.019022", "lvl": "target", "msg": "<6>[    0.304836] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-11-13T12:56:59.019173", "lvl": "target", "msg": "<6>[    0.304990] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-11-13T12:56:59.036239", "lvl": "target", "msg": "<6>[    0.321922] virtio-pci 0000:00:01.0: enabling device (0100 -> 0103)"}
- {"dt": "2024-11-13T12:56:59.090196", "lvl": "target", "msg": "<6>[    0.375888] Serial: 8250/16550 driver, 5 ports, IRQ sharing enabled"}
- {"dt": "2024-11-13T12:56:59.095975", "lvl": "target", "msg": "<6>[    0.381685] msm_serial: driver initialized"}
- {"dt": "2024-11-13T12:56:59.096333", "lvl": "target", "msg": "<6>[    0.382162] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-11-13T12:56:59.096834", "lvl": "target", "msg": "<6>[    0.382583] STMicroelectronics ASC driver initialized"}
- {"dt": "2024-11-13T12:56:59.097076", "lvl": "target", "msg": "<6>[    0.382847] STM32 USART driver initialized"}
- {"dt": "2024-11-13T12:56:59.123131", "lvl": "target", "msg": "<6>[    0.408809] brd: module loaded"}
- {"dt": "2024-11-13T12:56:59.129593", "lvl": "target", "msg": "<6>[    0.415269] loop: module loaded"}
- {"dt": "2024-11-13T12:56:59.130271", "lvl": "target", "msg": "<6>[    0.415963] virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2024-11-13T12:56:59.137628", "lvl": "target", "msg": "<5>[    0.423308] virtio_blk virtio0: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)"}
- {"dt": "2024-11-13T12:56:59.147170", "lvl": "target", "msg": "<5>[    0.432846] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-11-13T12:56:59.148225", "lvl": "target", "msg": "<6>[    0.433954] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-11-13T12:56:59.148581", "lvl": "target", "msg": "<6>[    0.434416] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-11-13T12:56:59.149329", "lvl": "target", "msg": "<6>[    0.435069] Using buffer write method"}
- {"dt": "2024-11-13T12:56:59.149574", "lvl": "target", "msg": "<7>[    0.435389] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-11-13T12:56:59.151028", "lvl": "target", "msg": "<5>[    0.436772] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-11-13T12:56:59.151631", "lvl": "target", "msg": "<6>[    0.437355] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-11-13T12:56:59.151752", "lvl": "target", "msg": "<6>[    0.437601] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-11-13T12:56:59.152412", "lvl": "target", "msg": "<6>[    0.438129] Using buffer write method"}
- {"dt": "2024-11-13T12:56:59.152473", "lvl": "target", "msg": "<7>[    0.438241] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-11-13T12:56:59.152546", "lvl": "target", "msg": "<5>[    0.438418] Concatenating MTD devices:"}
- {"dt": "2024-11-13T12:56:59.152632", "lvl": "target", "msg": "<5>[    0.438526] (0): \"0.flash\""}
- {"dt": "2024-11-13T12:56:59.152712", "lvl": "target", "msg": "<5>[    0.438606] (1): \"0.flash\""}
- {"dt": "2024-11-13T12:56:59.152803", "lvl": "target", "msg": "<5>[    0.438687] into device \"0.flash\""}
- {"dt": "2024-11-13T12:56:59.169848", "lvl": "target", "msg": "<6>[    0.455547] CAN device driver interface"}
- {"dt": "2024-11-13T12:56:59.170727", "lvl": "target", "msg": "<6>[    0.456468] bgmac_bcma: Broadcom 47xx GBit MAC driver loaded"}
- {"dt": "2024-11-13T12:56:59.171709", "lvl": "target", "msg": "<6>[    0.457455] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-11-13T12:56:59.171773", "lvl": "target", "msg": "<6>[    0.457589] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-11-13T12:56:59.172026", "lvl": "target", "msg": "<6>[    0.457794] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-11-13T12:56:59.172099", "lvl": "target", "msg": "<6>[    0.457939] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-11-13T12:56:59.176015", "lvl": "target", "msg": "<6>[    0.461724] pegasus: Pegasus/Pegasus II USB Ethernet driver"}
- {"dt": "2024-11-13T12:56:59.176109", "lvl": "target", "msg": "<6>[    0.461932] usbcore: registered new interface driver pegasus"}
- {"dt": "2024-11-13T12:56:59.176300", "lvl": "target", "msg": "<6>[    0.462131] usbcore: registered new interface driver asix"}
- {"dt": "2024-11-13T12:56:59.176479", "lvl": "target", "msg": "<6>[    0.462304] usbcore: registered new interface driver ax88179_178a"}
- {"dt": "2024-11-13T12:56:59.176652", "lvl": "target", "msg": "<6>[    0.462483] usbcore: registered new interface driver cdc_ether"}
- {"dt": "2024-11-13T12:56:59.176896", "lvl": "target", "msg": "<6>[    0.462658] usbcore: registered new interface driver smsc75xx"}
- {"dt": "2024-11-13T12:56:59.177005", "lvl": "target", "msg": "<6>[    0.462837] usbcore: registered new interface driver smsc95xx"}
- {"dt": "2024-11-13T12:56:59.177263", "lvl": "target", "msg": "<6>[    0.463011] usbcore: registered new interface driver net1080"}
- {"dt": "2024-11-13T12:56:59.177366", "lvl": "target", "msg": "<6>[    0.463189] usbcore: registered new interface driver cdc_subset"}
- {"dt": "2024-11-13T12:56:59.177607", "lvl": "target", "msg": "<6>[    0.463370] usbcore: registered new interface driver zaurus"}
- {"dt": "2024-11-13T12:56:59.177719", "lvl": "target", "msg": "<6>[    0.463550] usbcore: registered new interface driver cdc_ncm"}
- {"dt": "2024-11-13T12:56:59.181733", "lvl": "target", "msg": "<6>[    0.467449] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-11-13T12:56:59.188292", "lvl": "target", "msg": "<6>[    0.474003] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-11-13T12:56:59.189149", "lvl": "target", "msg": "<6>[    0.474708] rtc-pl031 9010000.pl031: setting system clock to 2024-11-13T12:56:59 UTC (1731502619)"}
- {"dt": "2024-11-13T12:56:59.190660", "lvl": "target", "msg": "<6>[    0.476392] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-11-13T12:56:59.202817", "lvl": "target", "msg": "<6>[    0.488524] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-11-13T12:56:59.202901", "lvl": "target", "msg": "<6>[    0.488684] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-11-13T12:56:59.204944", "lvl": "target", "msg": "<6>[    0.490656] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-11-13T12:56:59.206312", "lvl": "target", "msg": "<6>[    0.492058] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-11-13T12:56:59.210130", "lvl": "target", "msg": "<6>[    0.495846] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-11-13T12:56:59.210195", "lvl": "target", "msg": "<6>[    0.495999] usbhid: USB HID core driver"}
- {"dt": "2024-11-13T12:56:59.216250", "lvl": "target", "msg": "<6>[    0.501960] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-11-13T12:56:59.220549", "lvl": "target", "msg": "<6>[    0.506262] Segment Routing with IPv6"}
- {"dt": "2024-11-13T12:56:59.220622", "lvl": "target", "msg": "<6>[    0.506476] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-11-13T12:56:59.221037", "lvl": "target", "msg": "<6>[    0.506837] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2024-11-13T12:56:59.222962", "lvl": "target", "msg": "<6>[    0.508682] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-11-13T12:56:59.223036", "lvl": "target", "msg": "<6>[    0.508839] can: controller area network core"}
- {"dt": "2024-11-13T12:56:59.223748", "lvl": "target", "msg": "<6>[    0.509467] NET: Registered PF_CAN protocol family"}
- {"dt": "2024-11-13T12:56:59.223797", "lvl": "target", "msg": "<6>[    0.509642] can: raw protocol"}
- {"dt": "2024-11-13T12:56:59.224039", "lvl": "target", "msg": "<6>[    0.509811] can: broadcast manager protocol"}
- {"dt": "2024-11-13T12:56:59.224145", "lvl": "target", "msg": "<6>[    0.510004] can: netlink gateway - max_hops=1"}
- {"dt": "2024-11-13T12:56:59.225095", "lvl": "target", "msg": "<5>[    0.510844] Key type dns_resolver registered"}
- {"dt": "2024-11-13T12:56:59.225430", "lvl": "target", "msg": "<6>[    0.511284] ThumbEE CPU extension supported."}
- {"dt": "2024-11-13T12:56:59.225682", "lvl": "target", "msg": "<5>[    0.511447] Registering SWP/SWPB emulation handler"}
- {"dt": "2024-11-13T12:56:59.235029", "lvl": "target", "msg": "<5>[    0.520740] Loading compiled-in X.509 certificates"}
- {"dt": "2024-11-13T12:56:59.249694", "lvl": "target", "msg": "<6>[    0.535395] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-11-13T12:56:59.580123", "lvl": "target", "msg": "<6>[    0.865728] clk: Disabling unused clocks"}
- {"dt": "2024-11-13T12:56:59.580527", "lvl": "target", "msg": "<6>[    0.866253] PM: genpd: Disabling unused power domains"}
- {"dt": "2024-11-13T12:56:59.620214", "lvl": "target", "msg": "<6>[    0.905878] EXT4-fs (vda): orphan cleanup on readonly fs"}
- {"dt": "2024-11-13T12:56:59.621055", "lvl": "target", "msg": "<6>[    0.906584] EXT4-fs (vda): mounted filesystem 31d198d0-69d2-49e7-a44f-8143470115fd ro with ordered data mode. Quota mode: disabled."}
- {"dt": "2024-11-13T12:56:59.622137", "lvl": "target", "msg": "<6>[    0.907863] VFS: Mounted root (ext4 filesystem) readonly on device 254:0."}
- {"dt": "2024-11-13T12:56:59.623934", "lvl": "target", "msg": "<6>[    0.909623] devtmpfs: mounted"}
- {"dt": "2024-11-13T12:56:59.705502", "lvl": "target", "msg": "<6>[    0.991064] Freeing unused kernel image (initmem) memory: 2048K"}
- {"dt": "2024-11-13T12:56:59.729837", "lvl": "target", "msg": "<6>[    1.015508] Run /sbin/init as init process"}
- {"dt": "2024-11-13T12:56:59.729921", "lvl": "target", "msg": "<7>[    1.015631]   with arguments:"}
- {"dt": "2024-11-13T12:56:59.729986", "lvl": "target", "msg": "<7>[    1.015716]     /sbin/init"}
- {"dt": "2024-11-13T12:56:59.730052", "lvl": "target", "msg": "<7>[    1.015798]     verbose"}
- {"dt": "2024-11-13T12:56:59.730112", "lvl": "target", "msg": "<7>[    1.015873]   with environment:"}
- {"dt": "2024-11-13T12:56:59.730172", "lvl": "target", "msg": "<7>[    1.015965]     HOME=/"}
- {"dt": "2024-11-13T12:56:59.730233", "lvl": "target", "msg": "<7>[    1.016057]     TERM=linux"}
- {"dt": "2024-11-13T12:56:59.746152", "lvl": "target", "msg": "<1>[    1.031835] 8<--- cut here ---"}
- {"dt": "2024-11-13T12:56:59.746247", "lvl": "target", "msg": "<1>[    1.031946] Unhandled fault: synchronous abort (translation table walk) (0xa17) at 0x0054813d"}
- {"dt": "2024-11-13T12:56:59.746445", "lvl": "target", "msg": "<1>[    1.032178] [0054813d] *pgd=43851003, *pmd=13f8cb003"}
- {"dt": "2024-11-13T12:56:59.746746", "lvl": "debug", "msg": "Matched prompt #1: Unhandled fault"}
- {"dt": "2024-11-13T12:56:59.746797", "lvl": "debug", "msg": "Setting prompt string to ['\\\\r', 'root@(.*):[/~]#', 'login:', 'Login incorrect']"}
- {"dt": "2024-11-13T12:56:59.746902", "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:59.746954", "lvl": "warning", "msg": "login-action: kernel 'fault'"}
- {"dt": "2024-11-13T12:56:59.746999", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:14:58)"}
- {"dt": "2024-11-13T12:56:59.747043", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:29)"}
- {"dt": "2024-11-13T12:56:59.747101", "lvl": "target", "msg": "<0>[    1.032751] Internal error: : a17 [#1] SMP ARM"}
- {"dt": "2024-11-13T12:56:59.747213", "lvl": "target", "msg": "<4>[    1.033026] Modules linked in:"}
- {"dt": "2024-11-13T12:56:59.747699", "lvl": "target", "msg": "<4>[    1.033417] CPU: 0 UID: 0 PID: 1 Comm: init Not tainted 6.12.0-rc7 #1"}
- {"dt": "2024-11-13T12:56:59.747782", "lvl": "target", "msg": "<4>[    1.033634] Hardware name: Generic DT based system"}
- {"dt": "2024-11-13T12:56:59.748497", "lvl": "target", "msg": "<4>[    1.033942] PC is at __clear_user_std+0x1c/0x68"}
- {"dt": "2024-11-13T12:56:59.748564", "lvl": "target", "msg": "<4>[    1.034381] LR is at elf_load+0x1b0/0x214"}
- {"dt": "2024-11-13T12:56:59.748805", "lvl": "target", "msg": "<4>[    1.034539] pc : [<c1317cdc>]    lr : [<c0657724>]    psr: 80000013"}
- {"dt": "2024-11-13T12:56:59.748929", "lvl": "target", "msg": "<4>[    1.034756] sp : f0825e78  ip : 00000001  fp : 00000000"}
- {"dt": "2024-11-13T12:56:59.749100", "lvl": "target", "msg": "<4>[    1.034914] r10: 00000000  r9 : b5003500  r8 : 00546000"}
- {"dt": "2024-11-13T12:56:59.749431", "lvl": "target", "msg": "<4>[    1.035084] r7 : 00000003  r6 : c2807c80  r5 : 005486f0  r4 : 0054813d"}
- {"dt": "2024-11-13T12:56:59.749529", "lvl": "target", "msg": "<4>[    1.035311] r3 : b5003500  r2 : 00000000  r1 : 00000ec3  r0 : 0054813d"}
- {"dt": "2024-11-13T12:56:59.749702", "lvl": "target", "msg": "<4>[    1.035504] Flags: Nzcv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user"}
- {"dt": "2024-11-13T12:56:59.749859", "lvl": "target", "msg": "<4>[    1.035692] Control: 30c5387d  Table: 43822640  DAC: fffffffd"}
- {"dt": "2024-11-13T12:56:59.750128", "lvl": "target", "msg": "<1>[    1.035854] Register r0 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.750276", "lvl": "target", "msg": "<1>[    1.036112] Register r1 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.750402", "lvl": "target", "msg": "<1>[    1.036247] Register r2 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:59.750533", "lvl": "target", "msg": "<1>[    1.036374] Register r3 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.750662", "lvl": "target", "msg": "<1>[    1.036504] Register r4 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.750784", "lvl": "target", "msg": "<1>[    1.036630] Register r5 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.751179", "lvl": "target", "msg": "<1>[    1.036754] Register r6 information: slab kmalloc-512 start c2807c00 pointer offset 128 size 512"}
- {"dt": "2024-11-13T12:56:59.751334", "lvl": "target", "msg": "<1>[    1.037173] Register r7 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.751461", "lvl": "target", "msg": "<1>[    1.037304] Register r8 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.751589", "lvl": "target", "msg": "<1>[    1.037432] Register r9 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.751709", "lvl": "target", "msg": "<1>[    1.037559] Register r10 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:59.751831", "lvl": "target", "msg": "<1>[    1.037681] Register r11 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:59.751963", "lvl": "target", "msg": "<1>[    1.037803] Register r12 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:59.752288", "lvl": "target", "msg": "<0>[    1.037943] Process init (pid: 1, stack limit = 0x5c368568)"}
- {"dt": "2024-11-13T12:56:59.752429", "lvl": "target", "msg": "<0>[    1.038288] Stack: (0xf0825e78 to 0xf0826000)"}
- {"dt": "2024-11-13T12:56:59.752669", "lvl": "target", "msg": "<0>[    1.038452] 5e60:                                                       00000ec3 c0657724"}
- {"dt": "2024-11-13T12:56:59.752867", "lvl": "target", "msg": "<0>[    1.038647] 5e80: 00000012 000000d1 000d1878 c2807a00 c2807c80 000d36f0 00000004 000d0e7c"}
- {"dt": "2024-11-13T12:56:59.753060", "lvl": "target", "msg": "<0>[    1.038842] 5ea0: 00475000 c0657b78 00000012 00000000 c2807c00 c3829080 c2940000 00000003"}
- {"dt": "2024-11-13T12:56:59.753331", "lvl": "target", "msg": "<0>[    1.039032] 5ec0: 000d0e7c 00000000 000d0e7c c2804a80 c3822680 00001000 00000034 00000001"}
- {"dt": "2024-11-13T12:56:59.753474", "lvl": "target", "msg": "<0>[    1.039249] 5ee0: c3829000 00000000 00000034 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:59.753676", "lvl": "target", "msg": "<0>[    1.039453] 5f00: 00000000 aed47863 00000000 fffffff8 c2940000 c2807a00 c1eadd60 00000006"}
- {"dt": "2024-11-13T12:56:59.753872", "lvl": "target", "msg": "<0>[    1.039651] 5f20: c1eb25e4 c2807a5c c20b1c08 c05fb5d4 00000004 00000002 c1eadd40 00000000"}
- {"dt": "2024-11-13T12:56:59.754072", "lvl": "target", "msg": "<0>[    1.039849] 5f40: 00000001 00000001 00000100 00000000 c2940000 aed47863 00000000 00000000"}
- {"dt": "2024-11-13T12:56:59.754274", "lvl": "target", "msg": "<0>[    1.040050] 5f60: c2807a00 c2950000 c1e09254 c1e092e0 00000000 00000000 00000000 c05fbfdc"}
- {"dt": "2024-11-13T12:56:59.754474", "lvl": "target", "msg": "<0>[    1.040251] 5f80: c207d000 c189197c 00000000 00000000 00000000 c13453d0 c207d000 c1360048"}
- {"dt": "2024-11-13T12:56:59.754673", "lvl": "target", "msg": "<0>[    1.040452] 5fa0: 00000000 c1360124 00000000 c040029c 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:59.754868", "lvl": "target", "msg": "<0>[    1.040648] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:59.755067", "lvl": "target", "msg": "<0>[    1.040844] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:59.755667", "lvl": "target", "msg": "<0>[    1.041073] Call trace: "}
- {"dt": "2024-11-13T12:56:59.755710", "lvl": "target", "msg": "<0>[    1.041396]  __clear_user_std from elf_load+0x1b0/0x214"}
- {"dt": "2024-11-13T12:56:59.755866", "lvl": "target", "msg": "<0>[    1.041688]  elf_load from load_elf_binary+0x3f0/0x1310"}
- {"dt": "2024-11-13T12:56:59.756006", "lvl": "target", "msg": "<0>[    1.041837]  load_elf_binary from bprm_execve+0x1dc/0x51c"}
- {"dt": "2024-11-13T12:56:59.756146", "lvl": "target", "msg": "<0>[    1.041982]  bprm_execve from kernel_execve+0xec/0x1a4"}
- {"dt": "2024-11-13T12:56:59.756309", "lvl": "target", "msg": "<0>[    1.042119]  kernel_execve from try_to_run_init_process+0xc/0x38"}
- {"dt": "2024-11-13T12:56:59.756461", "lvl": "target", "msg": "<0>[    1.042278]  try_to_run_init_process from kernel_init+0xdc/0x12c"}
- {"dt": "2024-11-13T12:56:59.756605", "lvl": "target", "msg": "<0>[    1.042436]  kernel_init from ret_from_fork+0x14/0x38"}
- {"dt": "2024-11-13T12:56:59.756749", "lvl": "target", "msg": "<0>[    1.042593] Exception stack(0xf0825fb0 to 0xf0825ff8)"}
- {"dt": "2024-11-13T12:56:59.756940", "lvl": "target", "msg": "<0>[    1.042726] 5fa0:                                     00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:59.757138", "lvl": "target", "msg": "<0>[    1.042918] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:59.757299", "lvl": "target", "msg": "<0>[    1.043110] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000"}
- {"dt": "2024-11-13T12:56:59.757571", "lvl": "target", "msg": "<0>[    1.043393] Code: ba00000d e210c003 0a000005 e35c0002 (e4c02001) "}
- {"dt": "2024-11-13T12:56:59.757902", "lvl": "target", "msg": "<4>[    1.043751] ---[ end trace 0000000000000000 ]---"}
- {"dt": "2024-11-13T12:56:59.758142", "lvl": "target", "msg": "<6>[    1.043946] note: init[1] exited with irqs disabled"}
- {"dt": "2024-11-13T12:56:59.758656", "lvl": "target", "msg": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b"}
- {"dt": "2024-11-13T12:56:59.758897", "lvl": "debug", "msg": "Matched prompt #9: Kernel panic - not syncing"}
- {"dt": "2024-11-13T12:56:59.758943", "lvl": "debug", "msg": "Setting prompt string to ['end Kernel panic[^\\\\r]*\\\\r', 'root@(.*):[/~]#', 'login:', 'Login incorrect']"}
- {"dt": "2024-11-13T12:56:59.759480", "lvl": "target", "msg": "<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:59.759694", "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:59.759743", "lvl": "error", "msg": "login-action kernel 'panic'"}
- {"dt": "2024-11-13T12:56:59.759788", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "1.78", "result": "fail", "extra": {"extra": [{"kind": "fault", "message": "<1>[    1.031946] Unhandled fault: synchronous abort (translation table walk) (0xa17) at 0x0054813d"}, {"kind": "panic", "message": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}]}}}
- {"dt": "2024-11-13T12:56:59.759900", "lvl": "exception", "msg": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:59.759950", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:59.759991", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "1.78", "result": "fail", "extra": {"fail": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:59.760063", "lvl": "error", "msg": "auto-login-action failed: 1 of 1 attempts. '<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---'"}
- {"dt": "2024-11-13T12:56:59.760119", "lvl": "exception", "msg": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:59.760163", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:59.760208", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "auto-login-action", "level": "2.2", "duration": "2.12", "result": "fail", "extra": {"fail": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:59.760276", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. '<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---'"}
- {"dt": "2024-11-13T12:56:59.760332", "lvl": "exception", "msg": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:59.760376", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:59.760419", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "boot-image-retry", "level": "2", "duration": "2.13", "result": "fail", "extra": {"fail": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:59.760524", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-11-13T12:56:59.760607", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/url-repo-action-udwmhli2/url_repo"}
- {"dt": "2024-11-13T12:56:59.768475", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/deployimages-f5t70g6r/kernel"}
- {"dt": "2024-11-13T12:56:59.770347", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/deployimages-f5t70g6r/rootfs"}
- {"dt": "2024-11-13T12:56:59.770454", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1/deployimages-f5t70g6r/rootfs.modules"}
- {"dt": "2024-11-13T12:56:59.775328", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-11-13T12:56:59.775397", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:59.775454", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:59.775546", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-11-13T12:57:00.776562", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-11-13T12:57:00.776695", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2024-11-13T12:57:00.876997", "lvl": "input", "msg": "poweroff"}
- {"dt": "2024-11-13T12:57:05.882289", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-11-13T12:57:06.083206", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:06) [common]"}
- {"dt": "2024-11-13T12:57:06.083317", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-y146hlow-1"}
- {"dt": "2024-11-13T12:57:06.117158", "lvl": "error", "msg": "JobError: Your job cannot terminate cleanly."}
- {"dt": "2024-11-13T12:57:06.117229", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "fail", "error_msg": "<0>[    1.044380] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.045252] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---", "error_type": "Job"}}
