- {"dt": "2024-11-13T12:55:56.102359", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.05.dev0067"}
- {"dt": "2024-11-13T12:55:56.102447", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-11-13T12:55:56.102522", "lvl": "info", "msg": "Start time: 2024-11-13 12:55:56.102515+00:00 (UTC)"}
- {"dt": "2024-11-13T12:55:56.103120", "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:55:56.103240", "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:55:56.103349", "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:55:56.103649", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxsuite/plan/62/build_1/zImage exists"}
- {"dt": "2024-11-13T12:55:56.103737", "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:55:56.103827", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxsuite/plan/62/build_1/modules.tar.xz exists"}
- {"dt": "2024-11-13T12:55:56.112259", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:8.2.4+ds-1, host architecture: amd64"}
- {"dt": "2024-11-13T12:55:56.112486", "lvl": "warning", "msg": "Missing image arg for url_repo"}
- {"dt": "2024-11-13T12:55:56.112564", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2024-11-13T12:55:56.112728", "lvl": "info", "msg": "validate duration: 0.01"}
- {"dt": "2024-11-13T12:55:56.112795", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2024-11-13T12:55:56.112921", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.113005", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.113215", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol"}
- {"dt": "2024-11-13T12:55:56.113361", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin"}
- {"dt": "2024-11-13T12:55:56.113508", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/tests"}
- {"dt": "2024-11-13T12:55:56.113621", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/results"}
- {"dt": "2024-11-13T12:55:56.113702", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-add-keys"}
- {"dt": "2024-11-13T12:55:56.113835", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-add-sources"}
- {"dt": "2024-11-13T12:55:56.113958", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-background-process-start"}
- {"dt": "2024-11-13T12:55:56.114075", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-background-process-stop"}
- {"dt": "2024-11-13T12:55:56.114193", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-common-functions"}
- {"dt": "2024-11-13T12:55:56.114308", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2024-11-13T12:55:56.114421", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-install-packages"}
- {"dt": "2024-11-13T12:55:56.114530", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-installed-packages"}
- {"dt": "2024-11-13T12:55:56.114640", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-os-build"}
- {"dt": "2024-11-13T12:55:56.114751", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-probe-channel"}
- {"dt": "2024-11-13T12:55:56.114864", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-probe-ip"}
- {"dt": "2024-11-13T12:55:56.114976", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-target-ip"}
- {"dt": "2024-11-13T12:55:56.115087", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-target-mac"}
- {"dt": "2024-11-13T12:55:56.115197", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-target-storage"}
- {"dt": "2024-11-13T12:55:56.115310", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-test-case"}
- {"dt": "2024-11-13T12:55:56.115421", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-test-event"}
- {"dt": "2024-11-13T12:55:56.115530", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-test-feedback"}
- {"dt": "2024-11-13T12:55:56.115639", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-test-raise"}
- {"dt": "2024-11-13T12:55:56.115749", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-test-reference"}
- {"dt": "2024-11-13T12:55:56.115859", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-test-runner"}
- {"dt": "2024-11-13T12:55:56.115968", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-test-set"}
- {"dt": "2024-11-13T12:55:56.116078", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-test-shell"}
- {"dt": "2024-11-13T12:55:56.116193", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2024-11-13T12:55:56.116346", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2024-11-13T12:55:56.116448", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/environment"}
- {"dt": "2024-11-13T12:55:56.116527", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-11-13T12:55:56.116574", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2024-11-13T12:55:56.116615", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2024-11-13T12:55:56.116684", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.116728", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-11-13T12:55:56.116777", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.116831", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.116870", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-11-13T12:55:56.116914", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.116963", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.117008", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-11-13T12:55:56.117063", "lvl": "debug", "msg": "start: 1.1.3.1 url-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.117115", "lvl": "debug", "msg": "start: 1.1.3.1.1 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.117168", "lvl": "debug", "msg": "start: 1.1.3.1.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.117311", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst"}
- {"dt": "2024-11-13T12:55:56.117360", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-hjru_so3/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar"}
- {"dt": "2024-11-13T12:55:56.117398", "lvl": "debug", "msg": "total size: 13692979 (13\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.117437", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-11-13T12:55:56.117713", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.120054", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.121936", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.123768", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.125592", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.127410", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.129222", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.131061", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.132907", "lvl": "debug", "msg": "progress  40\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.134835", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.136584", "lvl": "debug", "msg": "progress  50\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.138428", "lvl": "debug", "msg": "progress  55\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.140269", "lvl": "debug", "msg": "progress  60\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.142135", "lvl": "debug", "msg": "progress  65\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.144526", "lvl": "debug", "msg": "progress  70\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.147112", "lvl": "debug", "msg": "progress  75\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.149849", "lvl": "debug", "msg": "progress  80\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.153399", "lvl": "debug", "msg": "progress  85\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.155779", "lvl": "debug", "msg": "progress  90\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.160080", "lvl": "debug", "msg": "progress  95\u202F% (12\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.164099", "lvl": "debug", "msg": "progress 100\u202F% (13\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.171984", "lvl": "info", "msg": "13\u202FMB downloaded in 0.05\u202Fs (239.26\u202FMB/s)"}
- {"dt": "2024-11-13T12:55:56.172145", "lvl": "debug", "msg": "end: 1.1.3.1.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.172227", "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:55:56.172389", "lvl": "debug", "msg": "end: 1.1.3.1.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.172458", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2024-11-13T12:55:56.172560", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/0/tests/0_ltp-fcntl-locktests"}
- {"dt": "2024-11-13T12:55:56.172645", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-hjru_so3/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/0/tests/0_ltp-fcntl-locktests"}
- {"dt": "2024-11-13T12:55:56.362535", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/0/tests/0_ltp-fcntl-locktests/automated/linux/ltp/ltp.yaml"}
- {"dt": "2024-11-13T12:55:56.362835", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2024-11-13T12:55:56.362913", "lvl": "debug", "msg": "end: 1.1.3.1 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.362973", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.363541", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.363594", "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-fcntl-locktests", "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:55:56.363709", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.364484", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.364539", "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-fcntl-locktests", "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:55:56.364651", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.372813", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/0/tests/0_ltp-fcntl-locktests test_uuid 1_1.1.3.1"}
- {"dt": "2024-11-13T12:55:56.372872", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2024-11-13T12:55:56.372913", "lvl": "debug", "msg": "TST_CMDFILES='fcntl-locktests'"}
- {"dt": "2024-11-13T12:55:56.372950", "lvl": "debug", "msg": "SKIPFILE='/tuxtest/skipfiles/armv7/ltp-fcntl-locktests'"}
- {"dt": "2024-11-13T12:55:56.372985", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2024-11-13T12:55:56.373018", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2024-11-13T12:55:56.373050", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2024-11-13T12:55:56.373083", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2024-11-13T12:55:56.373116", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2024-11-13T12:55:56.373148", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2024-11-13T12:55:56.373268", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.373321", "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-fcntl-locktests", "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-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/0/tests/0_ltp-fcntl-locktests/run.sh"}}}
- {"dt": "2024-11-13T12:55:56.373424", "lvl": "debug", "msg": "start: 1.1.3.5 url-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.373477", "lvl": "debug", "msg": "start: 1.1.3.5.1 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.373534", "lvl": "debug", "msg": "start: 1.1.3.5.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:55:56.373646", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst"}
- {"dt": "2024-11-13T12:55:56.373693", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-5npvfaj3/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar"}
- {"dt": "2024-11-13T12:55:56.373732", "lvl": "debug", "msg": "total size: 13692979 (13\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.373771", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-11-13T12:55:56.374053", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.376016", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.377835", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.379705", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.381641", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.383466", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.385291", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.387099", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.388901", "lvl": "debug", "msg": "progress  40\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.390756", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.392480", "lvl": "debug", "msg": "progress  50\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.394293", "lvl": "debug", "msg": "progress  55\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.396115", "lvl": "debug", "msg": "progress  60\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.397992", "lvl": "debug", "msg": "progress  65\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.400269", "lvl": "debug", "msg": "progress  70\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.402779", "lvl": "debug", "msg": "progress  75\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.405445", "lvl": "debug", "msg": "progress  80\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.408917", "lvl": "debug", "msg": "progress  85\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.411298", "lvl": "debug", "msg": "progress  90\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.415512", "lvl": "debug", "msg": "progress  95\u202F% (12\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.419434", "lvl": "debug", "msg": "progress 100\u202F% (13\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.427148", "lvl": "info", "msg": "13\u202FMB downloaded in 0.05\u202Fs (244.49\u202FMB/s)"}
- {"dt": "2024-11-13T12:55:56.427266", "lvl": "debug", "msg": "end: 1.1.3.5.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.427328", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.1.3.5.1.1", "duration": "0.05", "result": "pass", "extra": {"label": "url_repo", "size": ! "13692979", "md5sum": "30eb6f40044a706e0edae9b32f16a662", "sha256sum": "c850bed1a93201581f64754245d951f99a4942fe707b841bfe6ea3c9cd7624b0", "sha512sum": "3435daea585c61fec8b4c72eeb412c9d550e229d1220195bd592f268fe6baad5d210433f9802e5f9931fb5b8b17a10a4c5c569f16a3c286247222b96634c6310"}}}
- {"dt": "2024-11-13T12:55:56.427460", "lvl": "debug", "msg": "end: 1.1.3.5.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.427520", "lvl": "debug", "msg": "Using /lava-1 at stage 1"}
- {"dt": "2024-11-13T12:55:56.427599", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/1/tests/1_ltp-fs_perms_simple"}
- {"dt": "2024-11-13T12:55:56.427667", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-5npvfaj3/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/1/tests/1_ltp-fs_perms_simple"}
- {"dt": "2024-11-13T12:55:56.619559", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/1/tests/1_ltp-fs_perms_simple/automated/linux/ltp/ltp.yaml"}
- {"dt": "2024-11-13T12:55:56.619906", "lvl": "debug", "msg": "uuid=1_1.1.3.5 testdef=None"}
- {"dt": "2024-11-13T12:55:56.619996", "lvl": "debug", "msg": "end: 1.1.3.5 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.620066", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.620619", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.620672", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-overlay", "level": "1.1.3.6", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.5", "name": "ltp-fs_perms_simple", "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:55:56.620773", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.621540", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.621595", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-install-overlay", "level": "1.1.3.7", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.5", "name": "ltp-fs_perms_simple", "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.5"}}}
- {"dt": "2024-11-13T12:55:56.621699", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.629999", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/1/tests/1_ltp-fs_perms_simple test_uuid 1_1.1.3.5"}
- {"dt": "2024-11-13T12:55:56.630080", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2024-11-13T12:55:56.630137", "lvl": "debug", "msg": "TST_CMDFILES='fs_perms_simple'"}
- {"dt": "2024-11-13T12:55:56.630188", "lvl": "debug", "msg": "SKIPFILE='/tuxtest/skipfiles/armv7/ltp-fs_perms_simple'"}
- {"dt": "2024-11-13T12:55:56.630222", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2024-11-13T12:55:56.630255", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2024-11-13T12:55:56.630288", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2024-11-13T12:55:56.630320", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2024-11-13T12:55:56.630352", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2024-11-13T12:55:56.630385", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2024-11-13T12:55:56.630498", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.630548", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-runscript-overlay", "level": "1.1.3.8", "duration": "0.01", "result": "pass", "extra": {"uuid": "1_1.1.3.5", "name": "ltp-fs_perms_simple", "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-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/1/tests/1_ltp-fs_perms_simple/run.sh"}}}
- {"dt": "2024-11-13T12:55:56.630648", "lvl": "debug", "msg": "start: 1.1.3.9 url-repo-action (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.630702", "lvl": "debug", "msg": "start: 1.1.3.9.1 download-retry (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.630753", "lvl": "debug", "msg": "start: 1.1.3.9.1.1 file-download (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.630869", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst"}
- {"dt": "2024-11-13T12:55:56.630916", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-bvn1ovby/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar"}
- {"dt": "2024-11-13T12:55:56.630952", "lvl": "debug", "msg": "total size: 13692979 (13\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.630990", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-11-13T12:55:56.631322", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.633641", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.635641", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.637489", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.639307", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.641137", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.642978", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.644803", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.646661", "lvl": "debug", "msg": "progress  40\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.648494", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.650240", "lvl": "debug", "msg": "progress  50\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.652061", "lvl": "debug", "msg": "progress  55\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.653895", "lvl": "debug", "msg": "progress  60\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.655727", "lvl": "debug", "msg": "progress  65\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.658351", "lvl": "debug", "msg": "progress  70\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.660974", "lvl": "debug", "msg": "progress  75\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.664057", "lvl": "debug", "msg": "progress  80\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.667608", "lvl": "debug", "msg": "progress  85\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.670178", "lvl": "debug", "msg": "progress  90\u202F% (11\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.674405", "lvl": "debug", "msg": "progress  95\u202F% (12\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.678962", "lvl": "debug", "msg": "progress 100\u202F% (13\u202FMB)"}
- {"dt": "2024-11-13T12:55:56.686906", "lvl": "info", "msg": "13\u202FMB downloaded in 0.06\u202Fs (233.41\u202FMB/s)"}
- {"dt": "2024-11-13T12:55:56.687088", "lvl": "debug", "msg": "end: 1.1.3.9.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.687167", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.1.3.9.1.1", "duration": "0.06", "result": "pass", "extra": {"label": "url_repo", "size": ! "13692979", "md5sum": "30eb6f40044a706e0edae9b32f16a662", "sha256sum": "c850bed1a93201581f64754245d951f99a4942fe707b841bfe6ea3c9cd7624b0", "sha512sum": "3435daea585c61fec8b4c72eeb412c9d550e229d1220195bd592f268fe6baad5d210433f9802e5f9931fb5b8b17a10a4c5c569f16a3c286247222b96634c6310"}}}
- {"dt": "2024-11-13T12:55:56.687326", "lvl": "debug", "msg": "end: 1.1.3.9.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.687395", "lvl": "debug", "msg": "Using /lava-1 at stage 2"}
- {"dt": "2024-11-13T12:55:56.687491", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/2/tests/2_ltp-smoke"}
- {"dt": "2024-11-13T12:55:56.687574", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-bvn1ovby/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/2/tests/2_ltp-smoke"}
- {"dt": "2024-11-13T12:55:56.883679", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/2/tests/2_ltp-smoke/automated/linux/ltp/ltp.yaml"}
- {"dt": "2024-11-13T12:55:56.884012", "lvl": "debug", "msg": "uuid=1_1.1.3.9 testdef=None"}
- {"dt": "2024-11-13T12:55:56.884100", "lvl": "debug", "msg": "end: 1.1.3.9 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.884164", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.884757", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.884810", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-overlay", "level": "1.1.3.10", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.9", "name": "ltp-smoke", "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:55:56.884918", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.885737", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.885790", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-install-overlay", "level": "1.1.3.11", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.9", "name": "ltp-smoke", "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.9"}}}
- {"dt": "2024-11-13T12:55:56.885911", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:55:56.886631", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/2/tests/2_ltp-smoke test_uuid 1_1.1.3.9"}
- {"dt": "2024-11-13T12:55:56.886687", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2024-11-13T12:55:56.886728", "lvl": "debug", "msg": "TST_CMDFILES='smoketest'"}
- {"dt": "2024-11-13T12:55:56.886765", "lvl": "debug", "msg": "SKIPFILE='/tuxtest/skipfiles/armv7/ltp-smoke'"}
- {"dt": "2024-11-13T12:55:56.886801", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2024-11-13T12:55:56.886836", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2024-11-13T12:55:56.886870", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2024-11-13T12:55:56.886905", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2024-11-13T12:55:56.886939", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2024-11-13T12:55:56.886971", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2024-11-13T12:55:56.887083", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:55:56.887132", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-runscript-overlay", "level": "1.1.3.12", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.9", "name": "ltp-smoke", "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-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/2/tests/2_ltp-smoke/run.sh"}}}
- {"dt": "2024-11-13T12:55:56.887219", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-11-13T12:55:56.887256", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/0 for stage 0"}
- {"dt": "2024-11-13T12:55:56.887327", "lvl": "debug", "msg": "- 0_ltp-fcntl-locktests"}
- {"dt": "2024-11-13T12:55:56.887388", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/1 for stage 1"}
- {"dt": "2024-11-13T12:55:56.887463", "lvl": "debug", "msg": "- 1_ltp-fs_perms_simple"}
- {"dt": "2024-11-13T12:55:56.887518", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/lava-overlay-fm5947ol/lava-1/2 for stage 2"}
- {"dt": "2024-11-13T12:55:56.887589", "lvl": "debug", "msg": "- 2_ltp-smoke"}
- {"dt": "2024-11-13T12:55:56.887658", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:01) [common]"}
- {"dt": "2024-11-13T12:55:56.887713", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:04:59) [common]"}
- {"dt": "2024-11-13T12:56:14.029773", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:17) [common]"}
- {"dt": "2024-11-13T12:56:14.029900", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 00:04:42) [common]"}
- {"dt": "2024-11-13T12:56:14.029960", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:14.030028", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:18) [common]"}
- {"dt": "2024-11-13T12:56:14.030085", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:04:42) [common]"}
- {"dt": "2024-11-13T12:56:14.030136", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2024-11-13T12:56:14.030186", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:14.030237", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:04:42) [common]"}
- {"dt": "2024-11-13T12:56:14.030286", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:14.030336", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:04:42) [common]"}
- {"dt": "2024-11-13T12:56:14.030386", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:04:42) [common]"}
- {"dt": "2024-11-13T12:56:14.030533", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxsuite/plan/62/build_1/zImage"}
- {"dt": "2024-11-13T12:56:14.030586", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/deployimages-ldllkn_8/kernel/zImage"}
- {"dt": "2024-11-13T12:56:14.030627", "lvl": "debug", "msg": "total size: 11485696 (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.030669", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-11-13T12:56:14.030759", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.032480", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.034284", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.036141", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.037935", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.039626", "lvl": "debug", "msg": "progress  25\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.041423", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.043124", "lvl": "debug", "msg": "progress  35\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.044919", "lvl": "debug", "msg": "progress  40\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.046618", "lvl": "debug", "msg": "progress  45\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.048407", "lvl": "debug", "msg": "progress  50\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.050113", "lvl": "debug", "msg": "progress  55\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.051903", "lvl": "debug", "msg": "progress  60\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.053611", "lvl": "debug", "msg": "progress  65\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.055398", "lvl": "debug", "msg": "progress  70\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.057094", "lvl": "debug", "msg": "progress  75\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.058882", "lvl": "debug", "msg": "progress  80\u202F% (8\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.060580", "lvl": "debug", "msg": "progress  85\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.062532", "lvl": "debug", "msg": "progress  90\u202F% (9\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.064218", "lvl": "debug", "msg": "progress  95\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.066018", "lvl": "debug", "msg": "progress 100\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.066131", "lvl": "info", "msg": "10\u202FMB downloaded in 0.04\u202Fs (308.56\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:14.066231", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:14.066279", "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:14.066389", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:14.066445", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:04:42) [common]"}
- {"dt": "2024-11-13T12:56:14.066499", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:04:42) [common]"}
- {"dt": "2024-11-13T12:56:14.066651", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4.zst"}
- {"dt": "2024-11-13T12:56:14.066702", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/deployimages-ldllkn_8/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4"}
- {"dt": "2024-11-13T12:56:14.066744", "lvl": "debug", "msg": "total size: 73194706 (69\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.066785", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-11-13T12:56:14.067152", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.082280", "lvl": "debug", "msg": "progress   5\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.092092", "lvl": "debug", "msg": "progress  10\u202F% (7\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.101987", "lvl": "debug", "msg": "progress  15\u202F% (10\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.112758", "lvl": "debug", "msg": "progress  20\u202F% (13\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.131742", "lvl": "debug", "msg": "progress  25\u202F% (17\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.155768", "lvl": "debug", "msg": "progress  30\u202F% (20\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.174860", "lvl": "debug", "msg": "progress  35\u202F% (24\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.194368", "lvl": "debug", "msg": "progress  40\u202F% (27\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.212499", "lvl": "debug", "msg": "progress  45\u202F% (31\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.232176", "lvl": "debug", "msg": "progress  50\u202F% (34\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.251135", "lvl": "debug", "msg": "progress  55\u202F% (38\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.269192", "lvl": "debug", "msg": "progress  60\u202F% (41\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.288565", "lvl": "debug", "msg": "progress  65\u202F% (45\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.304858", "lvl": "debug", "msg": "progress  70\u202F% (48\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.315321", "lvl": "debug", "msg": "progress  75\u202F% (52\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.325189", "lvl": "debug", "msg": "progress  80\u202F% (55\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.335137", "lvl": "debug", "msg": "progress  85\u202F% (59\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.347629", "lvl": "debug", "msg": "progress  90\u202F% (62\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.357827", "lvl": "debug", "msg": "progress  95\u202F% (66\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.367460", "lvl": "debug", "msg": "progress 100\u202F% (69\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.612765", "lvl": "info", "msg": "69\u202FMB downloaded in 0.55\u202Fs (127.84\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:14.612981", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:01) [common]"}
- {"dt": "2024-11-13T12:56:14.613054", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "0.55", "result": "pass", "extra": {"label": "rootfs", "size": ! "73194706", "md5sum": "d8011d3000995663f497569fe7ae9ba7", "sha256sum": "2ff192e8a743a11de63c31a6361d545e2aa6d9a7d3dd3d872e9b107e06b1954e", "sha512sum": "16b75b03a9a0f1a4b356ca0cfcae27a003be26bfdfa8916ba12d314cc77658dfb9888d43b1479b0ef8157385c30b455aaac3abb75cb19d6e47f9587a7570a549"}}}
- {"dt": "2024-11-13T12:56:14.613176", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:04:41) [common]"}
- {"dt": "2024-11-13T12:56:14.613252", "lvl": "debug", "msg": "start: 1.5.2.1 file-download (timeout 00:04:41) [common]"}
- {"dt": "2024-11-13T12:56:14.613433", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxsuite/plan/62/build_1/modules.tar.xz"}
- {"dt": "2024-11-13T12:56:14.613487", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/deployimages-ldllkn_8/rootfs.modules/modules.tar"}
- {"dt": "2024-11-13T12:56:14.613529", "lvl": "debug", "msg": "total size: 6635564 (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.613572", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-11-13T12:56:14.613956", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.615660", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.616566", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.617471", "lvl": "debug", "msg": "progress  15\u202F% (0\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.618381", "lvl": "debug", "msg": "progress  20\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.619359", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.620262", "lvl": "debug", "msg": "progress  30\u202F% (1\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.621164", "lvl": "debug", "msg": "progress  35\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.622156", "lvl": "debug", "msg": "progress  40\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.623054", "lvl": "debug", "msg": "progress  45\u202F% (2\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.623951", "lvl": "debug", "msg": "progress  50\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.624851", "lvl": "debug", "msg": "progress  55\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.625753", "lvl": "debug", "msg": "progress  60\u202F% (3\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.626650", "lvl": "debug", "msg": "progress  65\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.627543", "lvl": "debug", "msg": "progress  70\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.628437", "lvl": "debug", "msg": "progress  75\u202F% (4\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.629427", "lvl": "debug", "msg": "progress  80\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.630324", "lvl": "debug", "msg": "progress  85\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.631387", "lvl": "debug", "msg": "progress  90\u202F% (5\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.632286", "lvl": "debug", "msg": "progress  95\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.633182", "lvl": "debug", "msg": "progress 100\u202F% (6\u202FMB)"}
- {"dt": "2024-11-13T12:56:14.774506", "lvl": "info", "msg": "6\u202FMB downloaded in 0.16\u202Fs (39.31\u202FMB/s)"}
- {"dt": "2024-11-13T12:56:14.774617", "lvl": "debug", "msg": "end: 1.5.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:14.774670", "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:14.774779", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:14.774837", "lvl": "debug", "msg": "start: 1.5.3 append-overlays (timeout 00:04:41) [common]"}
- {"dt": "2024-11-13T12:56:14.774891", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/deployimages-ldllkn_8/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4'"}
- {"dt": "2024-11-13T12:56:16.386359", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2024-11-13T12:56:16.387131", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/compress-overlay-i31jukk1/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2024-11-13T12:56:17.478420", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/deployimages-ldllkn_8/rootfs.modules/modules.tar' to '/'"}
- {"dt": "2024-11-13T12:56:17.802369", "lvl": "debug", "msg": "end: 1.5.3 append-overlays (duration 00:00:03) [common]"}
- {"dt": "2024-11-13T12:56:17.802734", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:04) [common]"}
- {"dt": "2024-11-13T12:56:17.802843", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:22) [common]"}
- {"dt": "2024-11-13T12:56:17.802939", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:17.803033", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:17.803113", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:17.803390", "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-6kkqnpls-1/deployimages-ldllkn_8/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-6kkqnpls-1/deployimages-ldllkn_8/rootfs/https___storage.tuxboot.com_buildroot_armv7_rootfs.ext4,if=virtio,format=raw,id=hd0"}
- {"dt": "2024-11-13T12:56:17.807327", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2024-11-13T12:56:17.807710", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:17.807803", "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:17.807964", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:17.808061", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:17.808159", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-11-13T12:56:17.808223", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:15:00)"}
- {"dt": "2024-11-13T12:56:18.165731", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0"}
- {"dt": "2024-11-13T12:56:18.165988", "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:18.166229", "lvl": "target", "msg": "[    0.000000] CPU: ARMv7 Processor [414fc0f0] revision 0 (ARMv7), cr=30c5387d"}
- {"dt": "2024-11-13T12:56:18.166396", "lvl": "target", "msg": "[    0.000000] CPU: div instructions available: patching division code"}
- {"dt": "2024-11-13T12:56:18.166568", "lvl": "target", "msg": "[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache"}
- {"dt": "2024-11-13T12:56:18.166686", "lvl": "target", "msg": "[    0.000000] OF: fdt: Machine model: linux,dummy-virt"}
- {"dt": "2024-11-13T12:56:18.166781", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-11-13T12:56:18.166928", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-11-13T12:56:18.167054", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-11-13T12:56:18.167341", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:15:00) [common]"}
- {"dt": "2024-11-13T12:56:18.167412", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-11-13T12:56:18.167480", "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:18.167542", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-11-13T12:56:18.167587", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-11-13T12:56:18.167632", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-11-13T12:56:18.167674", "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:18.167750", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:15:00)"}
- {"dt": "2024-11-13T12:56:18.167796", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2024-11-13T12:56:18.168180", "lvl": "target", "msg": "[    0.000000] Memory policy: Data cache writealloc"}
- {"dt": "2024-11-13T12:56:18.168234", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-11-13T12:56:18.169754", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fb800000 on node -1"}
- {"dt": "2024-11-13T12:56:18.171593", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-11-13T12:56:18.172006", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x000000006fffffff]"}
- {"dt": "2024-11-13T12:56:18.172105", "lvl": "target", "msg": "[    0.000000]   Normal   empty"}
- {"dt": "2024-11-13T12:56:18.172281", "lvl": "target", "msg": "[    0.000000]   HighMem  [mem 0x0000000070000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:18.172397", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-11-13T12:56:18.172515", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-11-13T12:56:18.172703", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:18.172969", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-11-13T12:56:18.286344", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-11-13T12:56:18.286493", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-11-13T12:56:18.286645", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-11-13T12:56:18.286907", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-11-13T12:56:18.287002", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-11-13T12:56:18.288633", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 17 pages/cpu s40844 r8192 d20596 u69632"}
- {"dt": "2024-11-13T12:56:18.289214", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s40844 r8192 d20596 u69632 alloc=17*4096"}
- {"dt": "2024-11-13T12:56:18.289499", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-11-13T12:56:18.290667", "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:18.291547", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2024-11-13T12:56:18.292274", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-11-13T12:56:18.292647", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-11-13T12:56:18.293098", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2024-11-13T12:56:18.293467", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-11-13T12:56:18.293780", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-11-13T12:56:18.349114", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x0000000069f0f000-0x000000006df0f000] (64MB)"}
- {"dt": "2024-11-13T12:56:18.890699", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-11-13T12:56:18.893429", "lvl": "target", "msg": "<6>[    0.000000] trace event string verifier disabled"}
- {"dt": "2024-11-13T12:56:18.897261", "lvl": "target", "msg": "<6>[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2024-11-13T12:56:18.897354", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-11-13T12:56:18.897546", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=2."}
- {"dt": "2024-11-13T12:56:18.897941", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies."}
- {"dt": "2024-11-13T12:56:18.898165", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-11-13T12:56:18.906557", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16"}
- {"dt": "2024-11-13T12:56:18.913069", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-11-13T12:56:18.913148", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-11-13T12:56:18.914307", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-11-13T12:56:18.914392", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2024-11-13T12:56:18.914967", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-11-13T12:56:18.918349", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-11-13T12:56:18.919071", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @428b0000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-11-13T12:56:18.919451", "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:18.920069", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x00000000428d0000"}
- {"dt": "2024-11-13T12:56:18.920786", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000000428e0000"}
- {"dt": "2024-11-13T12:56:18.922020", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-11-13T12:56:18.933419", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt)."}
- {"dt": "2024-11-13T12:56:18.933992", "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:18.934537", "lvl": "target", "msg": "<6>[    0.000097] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-11-13T12:56:18.934790", "lvl": "target", "msg": "<6>[    0.000413] Switching to timer-based delay loop, resolution 16ns"}
- {"dt": "2024-11-13T12:56:18.938495", "lvl": "target", "msg": "<6>[    0.004007] Console: colour dummy device 80x30"}
- {"dt": "2024-11-13T12:56:18.939735", "lvl": "target", "msg": "<6>[    0.005202] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=625000)"}
- {"dt": "2024-11-13T12:56:18.940346", "lvl": "target", "msg": "<6>[    0.005584] CPU: Testing write buffer coherency: ok"}
- {"dt": "2024-11-13T12:56:18.940582", "lvl": "target", "msg": "<3>[    0.006128] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2024-11-13T12:56:18.940846", "lvl": "target", "msg": "<6>[    0.006485] CPU0: Spectre BHB: enabling loop workaround for all CPUs"}
- {"dt": "2024-11-13T12:56:18.941109", "lvl": "target", "msg": "<6>[    0.006718] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-11-13T12:56:18.942526", "lvl": "target", "msg": "<6>[    0.008109] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2024-11-13T12:56:18.942731", "lvl": "target", "msg": "<6>[    0.008325] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2024-11-13T12:56:18.960253", "lvl": "target", "msg": "<3>[    0.025649] /cpus/cpu@0 missing clock-frequency property"}
- {"dt": "2024-11-13T12:56:18.960378", "lvl": "target", "msg": "<3>[    0.025999] /cpus/cpu@1 missing clock-frequency property"}
- {"dt": "2024-11-13T12:56:18.960672", "lvl": "target", "msg": "<6>[    0.026268] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000"}
- {"dt": "2024-11-13T12:56:19.018298", "lvl": "target", "msg": "<6>[    0.083763] Setting up static identity map for 0x40400000 - 0x40400184"}
- {"dt": "2024-11-13T12:56:19.020106", "lvl": "target", "msg": "<6>[    0.085662] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-11-13T12:56:19.020187", "lvl": "target", "msg": "<6>[    0.085831] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-11-13T12:56:19.021621", "lvl": "target", "msg": "<6>[    0.087175] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2024-11-13T12:56:19.023903", "lvl": "target", "msg": "<6>[    0.089448] EFI services will not be available."}
- {"dt": "2024-11-13T12:56:19.024839", "lvl": "target", "msg": "<6>[    0.090425] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-11-13T12:56:19.027793", "lvl": "target", "msg": "<6>[    0.092836] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-11-13T12:56:19.027993", "lvl": "target", "msg": "<6>[    0.093001] GICv3: CPU1: using allocated LPI pending table @0x00000000428f0000"}
- {"dt": "2024-11-13T12:56:19.028171", "lvl": "target", "msg": "<6>[    0.093317] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001"}
- {"dt": "2024-11-13T12:56:19.028461", "lvl": "target", "msg": "<3>[    0.093350] CPU1: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2024-11-13T12:56:19.031031", "lvl": "target", "msg": "<6>[    0.096577] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-11-13T12:56:19.031146", "lvl": "target", "msg": "<6>[    0.096759] SMP: Total of 2 processors activated (250.00 BogoMIPS)."}
- {"dt": "2024-11-13T12:56:19.031324", "lvl": "target", "msg": "<6>[    0.096998] CPU: All CPU(s) started in SVC mode."}
- {"dt": "2024-11-13T12:56:19.036684", "lvl": "target", "msg": "<6>[    0.101998] 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:19.042595", "lvl": "target", "msg": "<6>[    0.108139] devtmpfs: initialized"}
- {"dt": "2024-11-13T12:56:19.052909", "lvl": "target", "msg": "<6>[    0.115258] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0"}
- {"dt": "2024-11-13T12:56:19.066617", "lvl": "target", "msg": "<6>[    0.132079] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns"}
- {"dt": "2024-11-13T12:56:19.067362", "lvl": "target", "msg": "<6>[    0.132881] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-11-13T12:56:19.071655", "lvl": "target", "msg": "<6>[    0.137205] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-11-13T12:56:19.080843", "lvl": "target", "msg": "<6>[    0.146388] DMI not present or invalid."}
- {"dt": "2024-11-13T12:56:19.087498", "lvl": "target", "msg": "<6>[    0.152991] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-11-13T12:56:19.096198", "lvl": "target", "msg": "<6>[    0.161728] DMA: preallocated 256 KiB pool for atomic coherent allocations"}
- {"dt": "2024-11-13T12:56:19.100571", "lvl": "target", "msg": "<6>[    0.165749] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-11-13T12:56:19.100695", "lvl": "target", "msg": "<6>[    0.166113] cpuidle: using governor menu"}
- {"dt": "2024-11-13T12:56:19.101525", "lvl": "target", "msg": "<6>[    0.167061] No ATAGs?"}
- {"dt": "2024-11-13T12:56:19.102619", "lvl": "target", "msg": "<6>[    0.168105] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers."}
- {"dt": "2024-11-13T12:56:19.102913", "lvl": "target", "msg": "<6>[    0.168485] hw-breakpoint: maximum watchpoint size is 8 bytes."}
- {"dt": "2024-11-13T12:56:19.106402", "lvl": "target", "msg": "<6>[    0.171961] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-11-13T12:56:19.135385", "lvl": "target", "msg": "<6>[    0.200881] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 28, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-11-13T12:56:19.136865", "lvl": "target", "msg": "<6>[    0.202297] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-11-13T12:56:19.136964", "lvl": "target", "msg": "<6>[    0.202297] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-11-13T12:56:19.137100", "lvl": "target", "msg": "<6>[    0.202780] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-11-13T12:56:19.137233", "lvl": "target", "msg": "<6>[    0.202780] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-11-13T12:56:19.160381", "lvl": "target", "msg": "<6>[    0.225903] iommu: Default domain type: Translated"}
- {"dt": "2024-11-13T12:56:19.160483", "lvl": "target", "msg": "<6>[    0.226055] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-11-13T12:56:19.174182", "lvl": "target", "msg": "<5>[    0.239678] SCSI subsystem initialized"}
- {"dt": "2024-11-13T12:56:19.183671", "lvl": "target", "msg": "<7>[    0.249197] libata version 3.00 loaded."}
- {"dt": "2024-11-13T12:56:19.184612", "lvl": "target", "msg": "<6>[    0.250165] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-11-13T12:56:19.184763", "lvl": "target", "msg": "<6>[    0.250430] usbcore: registered new interface driver hub"}
- {"dt": "2024-11-13T12:56:19.185029", "lvl": "target", "msg": "<6>[    0.250670] usbcore: registered new device driver usb"}
- {"dt": "2024-11-13T12:56:19.186588", "lvl": "target", "msg": "<6>[    0.252175] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-11-13T12:56:19.186733", "lvl": "target", "msg": "<6>[    0.252319] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-11-13T12:56:19.186999", "lvl": "target", "msg": "<6>[    0.252592] PTP clock support registered"}
- {"dt": "2024-11-13T12:56:19.187281", "lvl": "target", "msg": "<6>[    0.252906] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-11-13T12:56:19.188304", "lvl": "target", "msg": "<6>[    0.253889] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-11-13T12:56:19.198118", "lvl": "target", "msg": "<6>[    0.263647] vgaarb: loaded"}
- {"dt": "2024-11-13T12:56:19.201790", "lvl": "target", "msg": "<6>[    0.267333] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-11-13T12:56:19.220048", "lvl": "target", "msg": "<6>[    0.285586] NET: Registered PF_INET protocol family"}
- {"dt": "2024-11-13T12:56:19.221008", "lvl": "target", "msg": "<6>[    0.286573] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-13T12:56:19.225195", "lvl": "target", "msg": "<6>[    0.290686] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)"}
- {"dt": "2024-11-13T12:56:19.225522", "lvl": "target", "msg": "<6>[    0.291082] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-11-13T12:56:19.225823", "lvl": "target", "msg": "<6>[    0.291394] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-11-13T12:56:19.226325", "lvl": "target", "msg": "<6>[    0.291770] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-11-13T12:56:19.226607", "lvl": "target", "msg": "<6>[    0.292199] TCP: Hash tables configured (established 8192 bind 8192)"}
- {"dt": "2024-11-13T12:56:19.227579", "lvl": "target", "msg": "<6>[    0.293152] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)"}
- {"dt": "2024-11-13T12:56:19.227974", "lvl": "target", "msg": "<6>[    0.293548] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)"}
- {"dt": "2024-11-13T12:56:19.228909", "lvl": "target", "msg": "<6>[    0.294488] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-11-13T12:56:19.242992", "lvl": "target", "msg": "<6>[    0.308503] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-11-13T12:56:19.243139", "lvl": "target", "msg": "<6>[    0.308774] RPC: Registered udp transport module."}
- {"dt": "2024-11-13T12:56:19.243319", "lvl": "target", "msg": "<6>[    0.308959] RPC: Registered tcp transport module."}
- {"dt": "2024-11-13T12:56:19.243492", "lvl": "target", "msg": "<6>[    0.309134] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-11-13T12:56:19.243938", "lvl": "target", "msg": "<6>[    0.309311] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-11-13T12:56:19.243998", "lvl": "target", "msg": "<6>[    0.309609] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-11-13T12:56:19.246353", "lvl": "target", "msg": "<5>[    0.311903] Initialise system trusted keyrings"}
- {"dt": "2024-11-13T12:56:19.250939", "lvl": "target", "msg": "<6>[    0.316432] workingset: timestamp_bits=30 max_order=20 bucket_order=0"}
- {"dt": "2024-11-13T12:56:19.252475", "lvl": "target", "msg": "<6>[    0.318045] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-11-13T12:56:19.253902", "lvl": "target", "msg": "<5>[    0.319452] NFS: Registering the id_resolver key type"}
- {"dt": "2024-11-13T12:56:19.254196", "lvl": "target", "msg": "<5>[    0.319845] Key type id_resolver registered"}
- {"dt": "2024-11-13T12:56:19.254383", "lvl": "target", "msg": "<5>[    0.320037] Key type id_legacy registered"}
- {"dt": "2024-11-13T12:56:19.254842", "lvl": "target", "msg": "<6>[    0.320416] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-11-13T12:56:19.255129", "lvl": "target", "msg": "<6>[    0.320710] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-11-13T12:56:19.256251", "lvl": "target", "msg": "<5>[    0.321833] Key type asymmetric registered"}
- {"dt": "2024-11-13T12:56:19.256407", "lvl": "target", "msg": "<5>[    0.322035] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-11-13T12:56:19.256698", "lvl": "target", "msg": "<6>[    0.322364] bounce: pool size: 64 pages"}
- {"dt": "2024-11-13T12:56:19.257226", "lvl": "target", "msg": "<6>[    0.322623] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)"}
- {"dt": "2024-11-13T12:56:19.257343", "lvl": "target", "msg": "<6>[    0.322993] io scheduler mq-deadline registered"}
- {"dt": "2024-11-13T12:56:19.257640", "lvl": "target", "msg": "<6>[    0.323212] io scheduler kyber registered"}
- {"dt": "2024-11-13T12:56:19.257903", "lvl": "target", "msg": "<6>[    0.323556] io scheduler bfq registered"}
- {"dt": "2024-11-13T12:56:19.280745", "lvl": "target", "msg": "<6>[    0.346181] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-11-13T12:56:19.285545", "lvl": "target", "msg": "<6>[    0.351071] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-11-13T12:56:19.288212", "lvl": "target", "msg": "<6>[    0.353754] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-11-13T12:56:19.288890", "lvl": "target", "msg": "<6>[    0.354398] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-11-13T12:56:19.289421", "lvl": "target", "msg": "<6>[    0.354994] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-11-13T12:56:19.289696", "lvl": "target", "msg": "<6>[    0.355261] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-11-13T12:56:19.290271", "lvl": "target", "msg": "<4>[    0.355859] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-11-13T12:56:19.291719", "lvl": "target", "msg": "<6>[    0.356770] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-11-13T12:56:19.293600", "lvl": "target", "msg": "<6>[    0.359164] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-11-13T12:56:19.293871", "lvl": "target", "msg": "<6>[    0.359533] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-11-13T12:56:19.294126", "lvl": "target", "msg": "<6>[    0.359723] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-11-13T12:56:19.294295", "lvl": "target", "msg": "<6>[    0.359917] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-11-13T12:56:19.294480", "lvl": "target", "msg": "<6>[    0.360105] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-11-13T12:56:19.295568", "lvl": "target", "msg": "<6>[    0.361151] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-11-13T12:56:19.298398", "lvl": "target", "msg": "<6>[    0.363945] pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint"}
- {"dt": "2024-11-13T12:56:19.298718", "lvl": "target", "msg": "<6>[    0.364357] pci 0000:00:01.0: BAR 0 [io  0x0000-0x007f]"}
- {"dt": "2024-11-13T12:56:19.298903", "lvl": "target", "msg": "<6>[    0.364557] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]"}
- {"dt": "2024-11-13T12:56:19.299202", "lvl": "target", "msg": "<6>[    0.364754] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2024-11-13T12:56:19.300431", "lvl": "target", "msg": "<6>[    0.366011] PCI: bus0: Fast back to back transfers disabled"}
- {"dt": "2024-11-13T12:56:19.301948", "lvl": "target", "msg": "<6>[    0.367500] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned"}
- {"dt": "2024-11-13T12:56:19.302270", "lvl": "target", "msg": "<6>[    0.367903] pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned"}
- {"dt": "2024-11-13T12:56:19.302455", "lvl": "target", "msg": "<6>[    0.368095] pci 0000:00:01.0: BAR 0 [io  0x1000-0x107f]: assigned"}
- {"dt": "2024-11-13T12:56:19.302794", "lvl": "target", "msg": "<6>[    0.368424] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2024-11-13T12:56:19.302944", "lvl": "target", "msg": "<6>[    0.368588] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-11-13T12:56:19.303113", "lvl": "target", "msg": "<6>[    0.368752] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-11-13T12:56:19.320695", "lvl": "target", "msg": "<6>[    0.386222] virtio-pci 0000:00:01.0: enabling device (0100 -> 0103)"}
- {"dt": "2024-11-13T12:56:19.381237", "lvl": "target", "msg": "<6>[    0.446728] Serial: 8250/16550 driver, 5 ports, IRQ sharing enabled"}
- {"dt": "2024-11-13T12:56:19.386640", "lvl": "target", "msg": "<6>[    0.452185] msm_serial: driver initialized"}
- {"dt": "2024-11-13T12:56:19.386985", "lvl": "target", "msg": "<6>[    0.452666] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-11-13T12:56:19.387359", "lvl": "target", "msg": "<6>[    0.453030] STMicroelectronics ASC driver initialized"}
- {"dt": "2024-11-13T12:56:19.387648", "lvl": "target", "msg": "<6>[    0.453302] STM32 USART driver initialized"}
- {"dt": "2024-11-13T12:56:19.410814", "lvl": "target", "msg": "<6>[    0.476337] brd: module loaded"}
- {"dt": "2024-11-13T12:56:19.417976", "lvl": "target", "msg": "<6>[    0.483519] loop: module loaded"}
- {"dt": "2024-11-13T12:56:19.418713", "lvl": "target", "msg": "<6>[    0.484264] virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2024-11-13T12:56:19.427169", "lvl": "target", "msg": "<5>[    0.492660] virtio_blk virtio0: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)"}
- {"dt": "2024-11-13T12:56:19.437622", "lvl": "target", "msg": "<5>[    0.503086] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-11-13T12:56:19.439179", "lvl": "target", "msg": "<6>[    0.504595] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-11-13T12:56:19.439865", "lvl": "target", "msg": "<6>[    0.505398] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-11-13T12:56:19.440886", "lvl": "target", "msg": "<6>[    0.506459] Using buffer write method"}
- {"dt": "2024-11-13T12:56:19.441142", "lvl": "target", "msg": "<7>[    0.506790] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-11-13T12:56:19.442704", "lvl": "target", "msg": "<5>[    0.508282] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-11-13T12:56:19.443354", "lvl": "target", "msg": "<6>[    0.508916] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-11-13T12:56:19.443512", "lvl": "target", "msg": "<6>[    0.509180] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-11-13T12:56:19.444149", "lvl": "target", "msg": "<6>[    0.509730] Using buffer write method"}
- {"dt": "2024-11-13T12:56:19.444205", "lvl": "target", "msg": "<7>[    0.509847] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-11-13T12:56:19.444517", "lvl": "target", "msg": "<5>[    0.510036] Concatenating MTD devices:"}
- {"dt": "2024-11-13T12:56:19.444563", "lvl": "target", "msg": "<5>[    0.510159] (0): \"0.flash\""}
- {"dt": "2024-11-13T12:56:19.444614", "lvl": "target", "msg": "<5>[    0.510246] (1): \"0.flash\""}
- {"dt": "2024-11-13T12:56:19.444675", "lvl": "target", "msg": "<5>[    0.510330] into device \"0.flash\""}
- {"dt": "2024-11-13T12:56:19.462100", "lvl": "target", "msg": "<6>[    0.527634] CAN device driver interface"}
- {"dt": "2024-11-13T12:56:19.463068", "lvl": "target", "msg": "<6>[    0.528627] bgmac_bcma: Broadcom 47xx GBit MAC driver loaded"}
- {"dt": "2024-11-13T12:56:19.464107", "lvl": "target", "msg": "<6>[    0.529687] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-11-13T12:56:19.464169", "lvl": "target", "msg": "<6>[    0.529828] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-11-13T12:56:19.464430", "lvl": "target", "msg": "<6>[    0.530040] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-11-13T12:56:19.464527", "lvl": "target", "msg": "<6>[    0.530190] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-11-13T12:56:19.468450", "lvl": "target", "msg": "<6>[    0.534002] pegasus: Pegasus/Pegasus II USB Ethernet driver"}
- {"dt": "2024-11-13T12:56:19.468563", "lvl": "target", "msg": "<6>[    0.534215] usbcore: registered new interface driver pegasus"}
- {"dt": "2024-11-13T12:56:19.468759", "lvl": "target", "msg": "<6>[    0.534416] usbcore: registered new interface driver asix"}
- {"dt": "2024-11-13T12:56:19.468960", "lvl": "target", "msg": "<6>[    0.534604] usbcore: registered new interface driver ax88179_178a"}
- {"dt": "2024-11-13T12:56:19.469151", "lvl": "target", "msg": "<6>[    0.534806] usbcore: registered new interface driver cdc_ether"}
- {"dt": "2024-11-13T12:56:19.469357", "lvl": "target", "msg": "<6>[    0.535001] usbcore: registered new interface driver smsc75xx"}
- {"dt": "2024-11-13T12:56:19.469544", "lvl": "target", "msg": "<6>[    0.535201] usbcore: registered new interface driver smsc95xx"}
- {"dt": "2024-11-13T12:56:19.469726", "lvl": "target", "msg": "<6>[    0.535387] usbcore: registered new interface driver net1080"}
- {"dt": "2024-11-13T12:56:19.469928", "lvl": "target", "msg": "<6>[    0.535575] usbcore: registered new interface driver cdc_subset"}
- {"dt": "2024-11-13T12:56:19.470120", "lvl": "target", "msg": "<6>[    0.535781] usbcore: registered new interface driver zaurus"}
- {"dt": "2024-11-13T12:56:19.470304", "lvl": "target", "msg": "<6>[    0.535968] usbcore: registered new interface driver cdc_ncm"}
- {"dt": "2024-11-13T12:56:19.474754", "lvl": "target", "msg": "<6>[    0.540298] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-11-13T12:56:19.481682", "lvl": "target", "msg": "<6>[    0.547227] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-11-13T12:56:19.482553", "lvl": "target", "msg": "<6>[    0.547947] rtc-pl031 9010000.pl031: setting system clock to 2024-11-13T12:56:19 UTC (1731502579)"}
- {"dt": "2024-11-13T12:56:19.484237", "lvl": "target", "msg": "<6>[    0.549822] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-11-13T12:56:19.497194", "lvl": "target", "msg": "<6>[    0.562735] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-11-13T12:56:19.497290", "lvl": "target", "msg": "<6>[    0.562934] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-11-13T12:56:19.498868", "lvl": "target", "msg": "<6>[    0.564455] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-11-13T12:56:19.500278", "lvl": "target", "msg": "<6>[    0.565858] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-11-13T12:56:19.505945", "lvl": "target", "msg": "<6>[    0.571446] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-11-13T12:56:19.506070", "lvl": "target", "msg": "<6>[    0.571685] usbhid: USB HID core driver"}
- {"dt": "2024-11-13T12:56:19.512231", "lvl": "target", "msg": "<6>[    0.577747] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-11-13T12:56:19.517192", "lvl": "target", "msg": "<6>[    0.582713] Segment Routing with IPv6"}
- {"dt": "2024-11-13T12:56:19.517331", "lvl": "target", "msg": "<6>[    0.582945] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-11-13T12:56:19.517720", "lvl": "target", "msg": "<6>[    0.583379] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2024-11-13T12:56:19.519681", "lvl": "target", "msg": "<6>[    0.585243] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-11-13T12:56:19.519762", "lvl": "target", "msg": "<6>[    0.585411] can: controller area network core"}
- {"dt": "2024-11-13T12:56:19.520066", "lvl": "target", "msg": "<6>[    0.585752] NET: Registered PF_CAN protocol family"}
- {"dt": "2024-11-13T12:56:19.520188", "lvl": "target", "msg": "<6>[    0.585909] can: raw protocol"}
- {"dt": "2024-11-13T12:56:19.520468", "lvl": "target", "msg": "<6>[    0.586079] can: broadcast manager protocol"}
- {"dt": "2024-11-13T12:56:19.520976", "lvl": "target", "msg": "<6>[    0.586485] can: netlink gateway - max_hops=1"}
- {"dt": "2024-11-13T12:56:19.521990", "lvl": "target", "msg": "<5>[    0.587573] Key type dns_resolver registered"}
- {"dt": "2024-11-13T12:56:19.522343", "lvl": "target", "msg": "<6>[    0.588033] ThumbEE CPU extension supported."}
- {"dt": "2024-11-13T12:56:19.522599", "lvl": "target", "msg": "<5>[    0.588201] Registering SWP/SWPB emulation handler"}
- {"dt": "2024-11-13T12:56:19.532642", "lvl": "target", "msg": "<5>[    0.598186] Loading compiled-in X.509 certificates"}
- {"dt": "2024-11-13T12:56:19.549361", "lvl": "target", "msg": "<6>[    0.614871] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-11-13T12:56:19.818918", "lvl": "target", "msg": "<6>[    0.884340] clk: Disabling unused clocks"}
- {"dt": "2024-11-13T12:56:19.819118", "lvl": "target", "msg": "<6>[    0.884776] PM: genpd: Disabling unused power domains"}
- {"dt": "2024-11-13T12:56:19.863145", "lvl": "target", "msg": "<6>[    0.928583] EXT4-fs (vda): orphan cleanup on readonly fs"}
- {"dt": "2024-11-13T12:56:19.864032", "lvl": "target", "msg": "<6>[    0.929420] EXT4-fs (vda): mounted filesystem 31d198d0-69d2-49e7-a44f-8143470115fd ro with ordered data mode. Quota mode: disabled."}
- {"dt": "2024-11-13T12:56:19.865262", "lvl": "target", "msg": "<6>[    0.930799] VFS: Mounted root (ext4 filesystem) readonly on device 254:0."}
- {"dt": "2024-11-13T12:56:19.866999", "lvl": "target", "msg": "<6>[    0.932562] devtmpfs: mounted"}
- {"dt": "2024-11-13T12:56:19.958961", "lvl": "target", "msg": "<6>[    1.024388] Freeing unused kernel image (initmem) memory: 2048K"}
- {"dt": "2024-11-13T12:56:19.995168", "lvl": "target", "msg": "<6>[    1.060685] Run /sbin/init as init process"}
- {"dt": "2024-11-13T12:56:19.995274", "lvl": "target", "msg": "<7>[    1.060823]   with arguments:"}
- {"dt": "2024-11-13T12:56:19.995355", "lvl": "target", "msg": "<7>[    1.060917]     /sbin/init"}
- {"dt": "2024-11-13T12:56:19.995421", "lvl": "target", "msg": "<7>[    1.060997]     verbose"}
- {"dt": "2024-11-13T12:56:19.995488", "lvl": "target", "msg": "<7>[    1.061077]   with environment:"}
- {"dt": "2024-11-13T12:56:19.995550", "lvl": "target", "msg": "<7>[    1.061175]     HOME=/"}
- {"dt": "2024-11-13T12:56:19.995614", "lvl": "target", "msg": "<7>[    1.061248]     TERM=linux"}
- {"dt": "2024-11-13T12:56:20.013019", "lvl": "target", "msg": "<1>[    1.078573] 8<--- cut here ---"}
- {"dt": "2024-11-13T12:56:20.013094", "lvl": "target", "msg": "<1>[    1.078689] Unhandled fault: synchronous abort (translation table walk) (0xa17) at 0x0059813d"}
- {"dt": "2024-11-13T12:56:20.013379", "lvl": "debug", "msg": "Matched prompt #1: Unhandled fault"}
- {"dt": "2024-11-13T12:56:20.013451", "lvl": "debug", "msg": "Setting prompt string to ['\\\\r', 'root@(.*):[/~]#', 'login:', 'Login incorrect']"}
- {"dt": "2024-11-13T12:56:20.013599", "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:20.013680", "lvl": "warning", "msg": "login-action: kernel 'fault'"}
- {"dt": "2024-11-13T12:56:20.013749", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:14:58)"}
- {"dt": "2024-11-13T12:56:20.013813", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:29)"}
- {"dt": "2024-11-13T12:56:20.013907", "lvl": "target", "msg": "<1>[    1.078925] [0059813d] *pgd=42f61003, *pmd=13fa67003"}
- {"dt": "2024-11-13T12:56:20.013968", "lvl": "target", "msg": "<0>[    1.079414] Internal error: : a17 [#1] SMP ARM"}
- {"dt": "2024-11-13T12:56:20.014036", "lvl": "target", "msg": "<4>[    1.079636] Modules linked in:"}
- {"dt": "2024-11-13T12:56:20.014290", "lvl": "target", "msg": "<4>[    1.079901] CPU: 0 UID: 0 PID: 1 Comm: init Not tainted 6.12.0-rc7 #1"}
- {"dt": "2024-11-13T12:56:20.014436", "lvl": "target", "msg": "<4>[    1.080123] Hardware name: Generic DT based system"}
- {"dt": "2024-11-13T12:56:20.015005", "lvl": "target", "msg": "<4>[    1.080306] PC is at __clear_user_std+0x1c/0x68"}
- {"dt": "2024-11-13T12:56:20.015060", "lvl": "target", "msg": "<4>[    1.080752] LR is at elf_load+0x1b0/0x214"}
- {"dt": "2024-11-13T12:56:20.015224", "lvl": "target", "msg": "<4>[    1.080874] pc : [<c1317cdc>]    lr : [<c0657724>]    psr: 80000013"}
- {"dt": "2024-11-13T12:56:20.015364", "lvl": "target", "msg": "<4>[    1.081043] sp : f0825e78  ip : 00000001  fp : 00000000"}
- {"dt": "2024-11-13T12:56:20.015510", "lvl": "target", "msg": "<4>[    1.081183] r10: 00000000  r9 : b5003500  r8 : 00596000"}
- {"dt": "2024-11-13T12:56:20.015680", "lvl": "target", "msg": "<4>[    1.081329] r7 : 00000003  r6 : c2f9b480  r5 : 005986f0  r4 : 0059813d"}
- {"dt": "2024-11-13T12:56:20.015855", "lvl": "target", "msg": "<4>[    1.081504] r3 : b5003500  r2 : 00000000  r1 : 00000ec3  r0 : 0059813d"}
- {"dt": "2024-11-13T12:56:20.016073", "lvl": "target", "msg": "<4>[    1.081708] Flags: Nzcv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user"}
- {"dt": "2024-11-13T12:56:20.016235", "lvl": "target", "msg": "<4>[    1.081904] Control: 30c5387d  Table: 42db17c0  DAC: fffffffd"}
- {"dt": "2024-11-13T12:56:20.016504", "lvl": "target", "msg": "<1>[    1.082070] Register r0 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.016657", "lvl": "target", "msg": "<1>[    1.082331] Register r1 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.016787", "lvl": "target", "msg": "<1>[    1.082469] Register r2 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:20.016919", "lvl": "target", "msg": "<1>[    1.082600] Register r3 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.017048", "lvl": "target", "msg": "<1>[    1.082732] Register r4 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.017181", "lvl": "target", "msg": "<1>[    1.082861] Register r5 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.017598", "lvl": "target", "msg": "<1>[    1.082994] Register r6 information: slab kmalloc-512 start c2f9b400 pointer offset 128 size 512"}
- {"dt": "2024-11-13T12:56:20.017754", "lvl": "target", "msg": "<1>[    1.083431] Register r7 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.017885", "lvl": "target", "msg": "<1>[    1.083568] Register r8 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.018016", "lvl": "target", "msg": "<1>[    1.083699] Register r9 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.018148", "lvl": "target", "msg": "<1>[    1.083832] Register r10 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:20.018270", "lvl": "target", "msg": "<1>[    1.083961] Register r11 information: NULL pointer"}
- {"dt": "2024-11-13T12:56:20.018406", "lvl": "target", "msg": "<1>[    1.084085] Register r12 information: non-paged memory"}
- {"dt": "2024-11-13T12:56:20.018749", "lvl": "target", "msg": "<0>[    1.084230] Process init (pid: 1, stack limit = 0x40f9fdfb)"}
- {"dt": "2024-11-13T12:56:20.018898", "lvl": "target", "msg": "<0>[    1.084593] Stack: (0xf0825e78 to 0xf0826000)"}
- {"dt": "2024-11-13T12:56:20.019148", "lvl": "target", "msg": "<0>[    1.084764] 5e60:                                                       00000ec3 c0657724"}
- {"dt": "2024-11-13T12:56:20.019351", "lvl": "target", "msg": "<0>[    1.084970] 5e80: 00000012 000000d1 000d1878 c2f9b200 c2f9b480 000d36f0 00000004 000d0e7c"}
- {"dt": "2024-11-13T12:56:20.019552", "lvl": "target", "msg": "<0>[    1.085170] 5ea0: 004c5000 c0657b78 00000012 00000000 c2f9b400 c2daf100 c2940000 00000003"}
- {"dt": "2024-11-13T12:56:20.019749", "lvl": "target", "msg": "<0>[    1.085368] 5ec0: 000d0e7c 00000000 000d0e7c c2f0f480 c2db1800 00001000 00000034 00000001"}
- {"dt": "2024-11-13T12:56:20.019950", "lvl": "target", "msg": "<0>[    1.085567] 5ee0: c2daf080 00000000 00000034 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:20.020157", "lvl": "target", "msg": "<0>[    1.085768] 5f00: 00000000 6bc72cb2 00000000 fffffff8 c2940000 c2f9b200 c1eadd60 00000006"}
- {"dt": "2024-11-13T12:56:20.020485", "lvl": "target", "msg": "<0>[    1.085976] 5f20: c1eb25e4 c2f9b25c c20b1c08 c05fb5d4 00000004 00000002 c1eadd40 00000000"}
- {"dt": "2024-11-13T12:56:20.020579", "lvl": "target", "msg": "<0>[    1.086194] 5f40: 00000001 00000001 00000100 00000000 c2940000 6bc72cb2 00000000 00000000"}
- {"dt": "2024-11-13T12:56:20.020794", "lvl": "target", "msg": "<0>[    1.086399] 5f60: c2f9b200 c384e000 c1e09254 c1e092e0 00000000 00000000 00000000 c05fbfdc"}
- {"dt": "2024-11-13T12:56:20.020998", "lvl": "target", "msg": "<0>[    1.086612] 5f80: c207d000 c189197c 00000000 00000000 00000000 c13453d0 c207d000 c1360048"}
- {"dt": "2024-11-13T12:56:20.021209", "lvl": "target", "msg": "<0>[    1.086818] 5fa0: 00000000 c1360124 00000000 c040029c 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:20.021418", "lvl": "target", "msg": "<0>[    1.087030] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:20.021624", "lvl": "target", "msg": "<0>[    1.087239] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:20.022238", "lvl": "target", "msg": "<0>[    1.087476] Call trace: "}
- {"dt": "2024-11-13T12:56:20.022284", "lvl": "target", "msg": "<0>[    1.087809]  __clear_user_std from elf_load+0x1b0/0x214"}
- {"dt": "2024-11-13T12:56:20.022450", "lvl": "target", "msg": "<0>[    1.088110]  elf_load from load_elf_binary+0x3f0/0x1310"}
- {"dt": "2024-11-13T12:56:20.022601", "lvl": "target", "msg": "<0>[    1.088266]  load_elf_binary from bprm_execve+0x1dc/0x51c"}
- {"dt": "2024-11-13T12:56:20.022746", "lvl": "target", "msg": "<0>[    1.088418]  bprm_execve from kernel_execve+0xec/0x1a4"}
- {"dt": "2024-11-13T12:56:20.022912", "lvl": "target", "msg": "<0>[    1.088562]  kernel_execve from try_to_run_init_process+0xc/0x38"}
- {"dt": "2024-11-13T12:56:20.023076", "lvl": "target", "msg": "<0>[    1.088728]  try_to_run_init_process from kernel_init+0xdc/0x12c"}
- {"dt": "2024-11-13T12:56:20.023222", "lvl": "target", "msg": "<0>[    1.088892]  kernel_init from ret_from_fork+0x14/0x38"}
- {"dt": "2024-11-13T12:56:20.023379", "lvl": "target", "msg": "<0>[    1.089057] Exception stack(0xf0825fb0 to 0xf0825ff8)"}
- {"dt": "2024-11-13T12:56:20.023587", "lvl": "target", "msg": "<0>[    1.089197] 5fa0:                                     00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:20.023795", "lvl": "target", "msg": "<0>[    1.089405] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000"}
- {"dt": "2024-11-13T12:56:20.023968", "lvl": "target", "msg": "<0>[    1.089611] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000"}
- {"dt": "2024-11-13T12:56:20.024257", "lvl": "target", "msg": "<0>[    1.089909] Code: ba00000d e210c003 0a000005 e35c0002 (e4c02001) "}
- {"dt": "2024-11-13T12:56:20.024599", "lvl": "target", "msg": "<4>[    1.090281] ---[ end trace 0000000000000000 ]---"}
- {"dt": "2024-11-13T12:56:20.024842", "lvl": "target", "msg": "<6>[    1.090485] note: init[1] exited with irqs disabled"}
- {"dt": "2024-11-13T12:56:20.025313", "lvl": "target", "msg": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b"}
- {"dt": "2024-11-13T12:56:20.025561", "lvl": "debug", "msg": "Matched prompt #9: Kernel panic - not syncing"}
- {"dt": "2024-11-13T12:56:20.025609", "lvl": "debug", "msg": "Setting prompt string to ['end Kernel panic[^\\\\r]*\\\\r', 'root@(.*):[/~]#', 'login:', 'Login incorrect']"}
- {"dt": "2024-11-13T12:56:20.026775", "lvl": "target", "msg": "<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:20.027026", "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:20.027107", "lvl": "error", "msg": "login-action kernel 'panic'"}
- {"dt": "2024-11-13T12:56:20.027182", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "1.86", "result": "fail", "extra": {"extra": [{"kind": "fault", "message": "<1>[    1.078689] Unhandled fault: synchronous abort (translation table walk) (0xa17) at 0x0059813d"}, {"kind": "panic", "message": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}]}}}
- {"dt": "2024-11-13T12:56:20.027365", "lvl": "exception", "msg": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:20.027440", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:20.027503", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "1.86", "result": "fail", "extra": {"fail": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:20.027623", "lvl": "error", "msg": "auto-login-action failed: 1 of 1 attempts. '<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---'"}
- {"dt": "2024-11-13T12:56:20.027710", "lvl": "exception", "msg": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:20.027787", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:20.027842", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "auto-login-action", "level": "2.2", "duration": "2.22", "result": "fail", "extra": {"fail": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:20.027927", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. '<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---'"}
- {"dt": "2024-11-13T12:56:20.027993", "lvl": "exception", "msg": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}
- {"dt": "2024-11-13T12:56:20.028042", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:02) [common]"}
- {"dt": "2024-11-13T12:56:20.028089", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "boot-image-retry", "level": "2", "duration": "2.23", "result": "fail", "extra": {"fail": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---"}}}
- {"dt": "2024-11-13T12:56:20.028199", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-11-13T12:56:20.028284", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-hjru_so3/url_repo"}
- {"dt": "2024-11-13T12:56:20.036153", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-5npvfaj3/url_repo"}
- {"dt": "2024-11-13T12:56:20.044358", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/url-repo-action-bvn1ovby/url_repo"}
- {"dt": "2024-11-13T12:56:20.052592", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/deployimages-ldllkn_8/kernel"}
- {"dt": "2024-11-13T12:56:20.054571", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/deployimages-ldllkn_8/rootfs"}
- {"dt": "2024-11-13T12:56:20.054684", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1/deployimages-ldllkn_8/rootfs.modules"}
- {"dt": "2024-11-13T12:56:20.059651", "lvl": "debug", "msg": "start: 6.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-11-13T12:56:20.059722", "lvl": "debug", "msg": "end: 6.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-11-13T12:56:20.059783", "lvl": "debug", "msg": "start: 6.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2024-11-13T12:56:20.059894", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-11-13T12:56:21.060953", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-11-13T12:56:21.061129", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2024-11-13T12:56:21.161406", "lvl": "input", "msg": "poweroff"}
- {"dt": "2024-11-13T12:56:26.166784", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-11-13T12:56:26.367201", "lvl": "debug", "msg": "end: 6.2 read-feedback (duration 00:00:06) [common]"}
- {"dt": "2024-11-13T12:56:26.367321", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-6kkqnpls-1"}
- {"dt": "2024-11-13T12:56:26.475949", "lvl": "error", "msg": "JobError: Your job cannot terminate cleanly."}
- {"dt": "2024-11-13T12:56:26.476068", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "fail", "error_msg": "<0>[    1.090867] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b\r\r\n<0>[    1.092367] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]---", "error_type": "Job"}}
