- {"dt": "2024-09-20T10:53:01.285834", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.05.dev0067"}
- {"dt": "2024-09-20T10:53:01.285916", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-09-20T10:53:01.285988", "lvl": "info", "msg": "Start time: 2024-09-20 10:53:01.285981+00:00 (UTC)"}
- {"dt": "2024-09-20T10:53:01.286595", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst exists"}
- {"dt": "2024-09-20T10:53:01.286913", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/145/Image.gz exists"}
- {"dt": "2024-09-20T10:53:01.287004", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tmp/debian/trixie/arm64/rootfs.ext4.xz exists"}
- {"dt": "2024-09-20T10:53:01.287082", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/145/kselftest.tar.xz exists"}
- {"dt": "2024-09-20T10:53:01.287165", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/145/modules.tar.xz exists"}
- {"dt": "2024-09-20T10:53:01.296500", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:8.2.4+ds-1, host architecture: amd64"}
- {"dt": "2024-09-20T10:53:01.296663", "lvl": "warning", "msg": "Missing image arg for url_repo"}
- {"dt": "2024-09-20T10:53:01.296757", "lvl": "warning", "msg": "Missing image arg for rootfs.kselftest"}
- {"dt": "2024-09-20T10:53:01.296819", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2024-09-20T10:53:01.296945", "lvl": "info", "msg": "validate duration: 0.01"}
- {"dt": "2024-09-20T10:53:01.297033", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2024-09-20T10:53:01.297149", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.297235", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.297438", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_"}
- {"dt": "2024-09-20T10:53:01.297654", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin"}
- {"dt": "2024-09-20T10:53:01.297773", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/tests"}
- {"dt": "2024-09-20T10:53:01.297871", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/results"}
- {"dt": "2024-09-20T10:53:01.297938", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-add-keys"}
- {"dt": "2024-09-20T10:53:01.298058", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-add-sources"}
- {"dt": "2024-09-20T10:53:01.298181", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-background-process-start"}
- {"dt": "2024-09-20T10:53:01.298300", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-background-process-stop"}
- {"dt": "2024-09-20T10:53:01.298421", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-common-functions"}
- {"dt": "2024-09-20T10:53:01.298537", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2024-09-20T10:53:01.298654", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-install-packages"}
- {"dt": "2024-09-20T10:53:01.298768", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-installed-packages"}
- {"dt": "2024-09-20T10:53:01.298882", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-os-build"}
- {"dt": "2024-09-20T10:53:01.298995", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-probe-channel"}
- {"dt": "2024-09-20T10:53:01.299114", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-probe-ip"}
- {"dt": "2024-09-20T10:53:01.299229", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-target-ip"}
- {"dt": "2024-09-20T10:53:01.299343", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-target-mac"}
- {"dt": "2024-09-20T10:53:01.299456", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-target-storage"}
- {"dt": "2024-09-20T10:53:01.299573", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-test-case"}
- {"dt": "2024-09-20T10:53:01.299687", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-test-event"}
- {"dt": "2024-09-20T10:53:01.299802", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-test-feedback"}
- {"dt": "2024-09-20T10:53:01.299914", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-test-raise"}
- {"dt": "2024-09-20T10:53:01.300025", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-test-reference"}
- {"dt": "2024-09-20T10:53:01.300139", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-test-runner"}
- {"dt": "2024-09-20T10:53:01.300254", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-test-set"}
- {"dt": "2024-09-20T10:53:01.300367", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-test-shell"}
- {"dt": "2024-09-20T10:53:01.300482", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2024-09-20T10:53:01.300619", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2024-09-20T10:53:01.300724", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/environment"}
- {"dt": "2024-09-20T10:53:01.300805", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-09-20T10:53:01.300853", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2024-09-20T10:53:01.300895", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2024-09-20T10:53:01.300965", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.301019", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-09-20T10:53:01.301071", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.301127", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.301166", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-09-20T10:53:01.301212", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.301261", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.301307", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-09-20T10:53:01.301362", "lvl": "debug", "msg": "start: 1.1.3.1 url-repo-action (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.301413", "lvl": "debug", "msg": "start: 1.1.3.1.1 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.301466", "lvl": "debug", "msg": "start: 1.1.3.1.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.301591", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst"}
- {"dt": "2024-09-20T10:53:01.301641", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/url-repo-action-o3qxkhav/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar"}
- {"dt": "2024-09-20T10:53:01.301679", "lvl": "debug", "msg": "total size: 13692979 (13\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.301717", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2024-09-20T10:53:01.308962", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.312632", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.314717", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.316791", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.318937", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.320942", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.322970", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.325205", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.327207", "lvl": "debug", "msg": "progress  40\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.329238", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.331194", "lvl": "debug", "msg": "progress  50\u202F% (6\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.333308", "lvl": "debug", "msg": "progress  55\u202F% (7\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.335371", "lvl": "debug", "msg": "progress  60\u202F% (7\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.337414", "lvl": "debug", "msg": "progress  65\u202F% (8\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.339447", "lvl": "debug", "msg": "progress  70\u202F% (9\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.341528", "lvl": "debug", "msg": "progress  75\u202F% (9\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.344300", "lvl": "debug", "msg": "progress  80\u202F% (10\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.347812", "lvl": "debug", "msg": "progress  85\u202F% (11\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.350229", "lvl": "debug", "msg": "progress  90\u202F% (11\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.354629", "lvl": "debug", "msg": "progress  95\u202F% (12\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.358721", "lvl": "debug", "msg": "progress 100\u202F% (13\u202FMB)"}
- {"dt": "2024-09-20T10:53:01.366611", "lvl": "info", "msg": "13\u202FMB downloaded in 0.06\u202Fs (201.13\u202FMB/s)"}
- {"dt": "2024-09-20T10:53:01.366769", "lvl": "debug", "msg": "end: 1.1.3.1.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.366843", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.1.3.1.1.1", "duration": "0.07", "result": "pass", "extra": {"label": "url_repo", "size": ! "13692979", "md5sum": "30eb6f40044a706e0edae9b32f16a662", "sha256sum": "c850bed1a93201581f64754245d951f99a4942fe707b841bfe6ea3c9cd7624b0", "sha512sum": "3435daea585c61fec8b4c72eeb412c9d550e229d1220195bd592f268fe6baad5d210433f9802e5f9931fb5b8b17a10a4c5c569f16a3c286247222b96634c6310"}}}
- {"dt": "2024-09-20T10:53:01.367001", "lvl": "debug", "msg": "end: 1.1.3.1.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.367069", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2024-09-20T10:53:01.367175", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/0/tests/0_kselftest-dt"}
- {"dt": "2024-09-20T10:53:01.367259", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/url-repo-action-o3qxkhav/url_repo/https___storage.tuxboot.com_test-definitions_2024.07.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/0/tests/0_kselftest-dt"}
- {"dt": "2024-09-20T10:53:01.559536", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/0/tests/0_kselftest-dt/automated/linux/kselftest/kselftest.yaml"}
- {"dt": "2024-09-20T10:53:01.559857", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2024-09-20T10:53:01.559939", "lvl": "debug", "msg": "end: 1.1.3.1 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.560000", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.560556", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.560611", "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": "kselftest-dt", "path": "automated/linux/kselftest/kselftest.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst"}}}
- {"dt": "2024-09-20T10:53:01.560710", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.561504", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.561563", "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": "kselftest-dt", "path": "automated/linux/kselftest/kselftest.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-09-20T10:53:01.561675", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:01.569871", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/0/tests/0_kselftest-dt test_uuid 1_1.1.3.1"}
- {"dt": "2024-09-20T10:53:01.569951", "lvl": "debug", "msg": "TST_CMDFILES='dt'"}
- {"dt": "2024-09-20T10:53:01.570006", "lvl": "debug", "msg": "KSELFTEST_PATH='/opt/kselftests/default-in-kernel'"}
- {"dt": "2024-09-20T10:53:01.570055", "lvl": "debug", "msg": "SKIPFILE='skipfile-lkft.yaml'"}
- {"dt": "2024-09-20T10:53:01.570102", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2024-09-20T10:53:01.570148", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2024-09-20T10:53:01.570194", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2024-09-20T10:53:01.570322", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.570375", "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": "kselftest-dt", "path": "automated/linux/kselftest/kselftest.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-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/0/tests/0_kselftest-dt/run.sh"}}}
- {"dt": "2024-09-20T10:53:01.570462", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-09-20T10:53:01.570500", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/lava-overlay-ejk62e7_/lava-1/0 for stage 0"}
- {"dt": "2024-09-20T10:53:01.570584", "lvl": "debug", "msg": "- 0_kselftest-dt"}
- {"dt": "2024-09-20T10:53:01.570663", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:01.570725", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:53:07.322953", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:06) [common]"}
- {"dt": "2024-09-20T10:53:07.323082", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 00:04:54) [common]"}
- {"dt": "2024-09-20T10:53:07.323143", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:07.323204", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:06) [common]"}
- {"dt": "2024-09-20T10:53:07.323261", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:04:54) [common]"}
- {"dt": "2024-09-20T10:53:07.323322", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2024-09-20T10:53:07.323372", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:07.323426", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:04:54) [common]"}
- {"dt": "2024-09-20T10:53:07.323483", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:07.323536", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:04:54) [common]"}
- {"dt": "2024-09-20T10:53:07.323591", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:04:54) [common]"}
- {"dt": "2024-09-20T10:53:07.323733", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/145/Image.gz"}
- {"dt": "2024-09-20T10:53:07.323813", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/kernel/Image"}
- {"dt": "2024-09-20T10:53:07.323857", "lvl": "debug", "msg": "total size: 13802486 (13\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.323899", "lvl": "info", "msg": "Using gunzip to decompress gz"}
- {"dt": "2024-09-20T10:53:07.324372", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.333535", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.341043", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.350882", "lvl": "debug", "msg": "progress  15\u202F% (2\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.361338", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.369921", "lvl": "debug", "msg": "progress  25\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.380078", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.390882", "lvl": "debug", "msg": "progress  35\u202F% (4\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.398945", "lvl": "debug", "msg": "progress  40\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.409919", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.417796", "lvl": "debug", "msg": "progress  50\u202F% (6\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.427941", "lvl": "debug", "msg": "progress  55\u202F% (7\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.438694", "lvl": "debug", "msg": "progress  60\u202F% (7\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.447117", "lvl": "debug", "msg": "progress  65\u202F% (8\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.458401", "lvl": "debug", "msg": "progress  70\u202F% (9\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.477481", "lvl": "debug", "msg": "progress  75\u202F% (9\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.487158", "lvl": "debug", "msg": "progress  80\u202F% (10\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.498456", "lvl": "debug", "msg": "progress  85\u202F% (11\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.509592", "lvl": "debug", "msg": "progress  90\u202F% (11\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.519029", "lvl": "debug", "msg": "progress  95\u202F% (12\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.542032", "lvl": "debug", "msg": "progress 100\u202F% (13\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.547243", "lvl": "info", "msg": "13\u202FMB downloaded in 0.22\u202Fs (58.93\u202FMB/s)"}
- {"dt": "2024-09-20T10:53:07.547452", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:07.547569", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.1", "duration": "0.22", "result": "pass", "extra": {"label": "kernel", "size": ! "13802486", "md5sum": "936e63929ecbf171c0165a955bd1e839", "sha256sum": "7576a6e0644f525de30b0675cac1335ef799ec4166a6b46ee6fddf43fa490c51", "sha512sum": "35dd293a702b9ef99c896f901481e4c46b2e1b902e9388eddeb33cac0ba45c9d789ca6cc81780a49bb0e74728ec91d21d35c5306dcddb0564dc2494efe552c46"}}}
- {"dt": "2024-09-20T10:53:07.547873", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:07.548004", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:04:54) [common]"}
- {"dt": "2024-09-20T10:53:07.548130", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:04:54) [common]"}
- {"dt": "2024-09-20T10:53:07.548405", "lvl": "info", "msg": "downloading file:///home/anders/src/tmp/debian/trixie/arm64/rootfs.ext4.xz"}
- {"dt": "2024-09-20T10:53:07.548518", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs/rootfs.ext4"}
- {"dt": "2024-09-20T10:53:07.548622", "lvl": "debug", "msg": "total size: 108269868 (103\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.548708", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-09-20T10:53:07.549315", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.733900", "lvl": "debug", "msg": "progress   5\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:07.886563", "lvl": "debug", "msg": "progress  10\u202F% (10\u202FMB)"}
- {"dt": "2024-09-20T10:53:08.044225", "lvl": "debug", "msg": "progress  15\u202F% (15\u202FMB)"}
- {"dt": "2024-09-20T10:53:08.199434", "lvl": "debug", "msg": "progress  20\u202F% (20\u202FMB)"}
- {"dt": "2024-09-20T10:53:08.368191", "lvl": "debug", "msg": "progress  25\u202F% (25\u202FMB)"}
- {"dt": "2024-09-20T10:53:08.513750", "lvl": "debug", "msg": "progress  30\u202F% (31\u202FMB)"}
- {"dt": "2024-09-20T10:53:08.651087", "lvl": "debug", "msg": "progress  35\u202F% (36\u202FMB)"}
- {"dt": "2024-09-20T10:53:08.803255", "lvl": "debug", "msg": "progress  40\u202F% (41\u202FMB)"}
- {"dt": "2024-09-20T10:53:08.967535", "lvl": "debug", "msg": "progress  45\u202F% (46\u202FMB)"}
- {"dt": "2024-09-20T10:53:09.131104", "lvl": "debug", "msg": "progress  50\u202F% (51\u202FMB)"}
- {"dt": "2024-09-20T10:53:09.292152", "lvl": "debug", "msg": "progress  55\u202F% (56\u202FMB)"}
- {"dt": "2024-09-20T10:53:09.458877", "lvl": "debug", "msg": "progress  60\u202F% (61\u202FMB)"}
- {"dt": "2024-09-20T10:53:09.636010", "lvl": "debug", "msg": "progress  65\u202F% (67\u202FMB)"}
- {"dt": "2024-09-20T10:53:09.782056", "lvl": "debug", "msg": "progress  70\u202F% (72\u202FMB)"}
- {"dt": "2024-09-20T10:53:09.932447", "lvl": "debug", "msg": "progress  75\u202F% (77\u202FMB)"}
- {"dt": "2024-09-20T10:53:10.083368", "lvl": "debug", "msg": "progress  80\u202F% (82\u202FMB)"}
- {"dt": "2024-09-20T10:53:10.248501", "lvl": "debug", "msg": "progress  85\u202F% (87\u202FMB)"}
- {"dt": "2024-09-20T10:53:10.367226", "lvl": "debug", "msg": "progress  90\u202F% (92\u202FMB)"}
- {"dt": "2024-09-20T10:53:10.465046", "lvl": "debug", "msg": "progress  95\u202F% (98\u202FMB)"}
- {"dt": "2024-09-20T10:53:11.908821", "lvl": "debug", "msg": "progress 100\u202F% (103\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.698817", "lvl": "info", "msg": "103\u202FMB downloaded in 5.15\u202Fs (20.05\u202FMB/s)"}
- {"dt": "2024-09-20T10:53:12.699102", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:05) [common]"}
- {"dt": "2024-09-20T10:53:12.699221", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "5.15", "result": "pass", "extra": {"label": "rootfs", "size": ! "108269868", "md5sum": "e62cbddaf51c9b2987785da9c424a0a9", "sha256sum": "e72a27bba1a9debfc27189bec6dec57250599906d68b2c3355331da9afc0cacc", "sha512sum": "b13d7fb9bb6dd8d92b0dd77f2dbad2d37391eafa1643adef647487bab5f18d238c446e34d82ee36c66c932796b3d3893e8d8dd262861ab15858c67c4fb22f1bc"}}}
- {"dt": "2024-09-20T10:53:12.699482", "lvl": "debug", "msg": "start: 1.5.2 download-retry (timeout 00:04:49) [common]"}
- {"dt": "2024-09-20T10:53:12.699631", "lvl": "debug", "msg": "start: 1.5.2.1 file-download (timeout 00:04:49) [common]"}
- {"dt": "2024-09-20T10:53:12.699947", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/145/kselftest.tar.xz"}
- {"dt": "2024-09-20T10:53:12.700057", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs.kselftest/kselftest.tar"}
- {"dt": "2024-09-20T10:53:12.700174", "lvl": "debug", "msg": "total size: 7166556 (6\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.700286", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-09-20T10:53:12.700951", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.703569", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.705825", "lvl": "debug", "msg": "progress  10\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.707981", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.710133", "lvl": "debug", "msg": "progress  20\u202F% (1\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.712257", "lvl": "debug", "msg": "progress  25\u202F% (1\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.714460", "lvl": "debug", "msg": "progress  30\u202F% (2\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.716599", "lvl": "debug", "msg": "progress  35\u202F% (2\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.718758", "lvl": "debug", "msg": "progress  40\u202F% (2\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.720958", "lvl": "debug", "msg": "progress  45\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.723139", "lvl": "debug", "msg": "progress  50\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.725318", "lvl": "debug", "msg": "progress  55\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.727489", "lvl": "debug", "msg": "progress  60\u202F% (4\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.729678", "lvl": "debug", "msg": "progress  65\u202F% (4\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.731858", "lvl": "debug", "msg": "progress  70\u202F% (4\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.734038", "lvl": "debug", "msg": "progress  75\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.736098", "lvl": "debug", "msg": "progress  80\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.738273", "lvl": "debug", "msg": "progress  85\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.740436", "lvl": "debug", "msg": "progress  90\u202F% (6\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.742607", "lvl": "debug", "msg": "progress  95\u202F% (6\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.744787", "lvl": "debug", "msg": "progress 100\u202F% (6\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.825593", "lvl": "info", "msg": "6\u202FMB downloaded in 0.13\u202Fs (54.50\u202FMB/s)"}
- {"dt": "2024-09-20T10:53:12.825822", "lvl": "debug", "msg": "end: 1.5.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:12.825940", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.2.1", "duration": "0.13", "result": "pass", "extra": {"label": "rootfs.kselftest", "size": ! "7166556", "md5sum": "5fba6fea20e767b61d02289ccf86212c", "sha256sum": "eb239b3ca9c610e6664a3df65d7e5250de9ebca0b4ab83bd4b674a3c226a2e9c", "sha512sum": "b4bb5876e8a02a08c4122d3d568850e3b120a3d8205d309d26d14d934392012a5ab7ff5a6923e3b7f01462c910c9906cd9662c3025b10aa6effffa37cefb8b7b"}}}
- {"dt": "2024-09-20T10:53:12.826163", "lvl": "debug", "msg": "end: 1.5.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:12.826282", "lvl": "debug", "msg": "start: 1.5.3 download-retry (timeout 00:04:48) [common]"}
- {"dt": "2024-09-20T10:53:12.826398", "lvl": "debug", "msg": "start: 1.5.3.1 file-download (timeout 00:04:48) [common]"}
- {"dt": "2024-09-20T10:53:12.826681", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/145/modules.tar.xz"}
- {"dt": "2024-09-20T10:53:12.826788", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs.modules/modules.tar"}
- {"dt": "2024-09-20T10:53:12.826872", "lvl": "debug", "msg": "total size: 11624896 (11\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.826962", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-09-20T10:53:12.827531", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.831561", "lvl": "debug", "msg": "progress   5\u202F% (0\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.835052", "lvl": "debug", "msg": "progress  10\u202F% (1\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.838491", "lvl": "debug", "msg": "progress  15\u202F% (1\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.841737", "lvl": "debug", "msg": "progress  20\u202F% (2\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.845195", "lvl": "debug", "msg": "progress  25\u202F% (2\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.848799", "lvl": "debug", "msg": "progress  30\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.852258", "lvl": "debug", "msg": "progress  35\u202F% (3\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.855615", "lvl": "debug", "msg": "progress  40\u202F% (4\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.859121", "lvl": "debug", "msg": "progress  45\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.862573", "lvl": "debug", "msg": "progress  50\u202F% (5\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.866031", "lvl": "debug", "msg": "progress  55\u202F% (6\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.869304", "lvl": "debug", "msg": "progress  60\u202F% (6\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.872744", "lvl": "debug", "msg": "progress  65\u202F% (7\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.875649", "lvl": "debug", "msg": "progress  70\u202F% (7\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.878357", "lvl": "debug", "msg": "progress  75\u202F% (8\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.880976", "lvl": "debug", "msg": "progress  80\u202F% (8\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.883715", "lvl": "debug", "msg": "progress  85\u202F% (9\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.886438", "lvl": "debug", "msg": "progress  90\u202F% (10\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.889156", "lvl": "debug", "msg": "progress  95\u202F% (10\u202FMB)"}
- {"dt": "2024-09-20T10:53:12.891734", "lvl": "debug", "msg": "progress 100\u202F% (11\u202FMB)"}
- {"dt": "2024-09-20T10:53:13.008566", "lvl": "info", "msg": "11\u202FMB downloaded in 0.18\u202Fs (61.02\u202FMB/s)"}
- {"dt": "2024-09-20T10:53:13.008865", "lvl": "debug", "msg": "end: 1.5.3.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:13.008970", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.3.1", "duration": "0.18", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "11624896", "md5sum": "970454c13ae05ac075eee27b64d70d2f", "sha256sum": "c82353c45fd885d058e2e600b8c069943f054cf7ec7ead24cc3f9b2b8769d46d", "sha512sum": "9c1dc9675b09fb573b172b80b43c82a6ce8221df03f4bf87605f3485262fcfc21b16748b1bdd4dae29ae994ae9c45a5620b3be633d6fbc2537970e180120a305"}}}
- {"dt": "2024-09-20T10:53:13.009188", "lvl": "debug", "msg": "end: 1.5.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:13.009305", "lvl": "debug", "msg": "start: 1.5.4 append-overlays (timeout 00:04:48) [common]"}
- {"dt": "2024-09-20T10:53:13.009405", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs/rootfs.ext4'"}
- {"dt": "2024-09-20T10:53:14.696618", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2024-09-20T10:53:14.697274", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/compress-overlay-27d65fmx/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2024-09-20T10:53:15.075723", "lvl": "debug", "msg": "- rootfs.kselftest: '/var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs.kselftest/kselftest.tar' to '/opt/kselftests/default-in-kernel/'"}
- {"dt": "2024-09-20T10:53:15.415396", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs.modules/modules.tar' to '/usr/'"}
- {"dt": "2024-09-20T10:53:15.862326", "lvl": "debug", "msg": "end: 1.5.4 append-overlays (duration 00:00:03) [common]"}
- {"dt": "2024-09-20T10:53:15.862871", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:08) [common]"}
- {"dt": "2024-09-20T10:53:15.863034", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:15) [common]"}
- {"dt": "2024-09-20T10:53:15.863197", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:15:00) [common]"}
- {"dt": "2024-09-20T10:53:15.863349", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:15:00) [common]"}
- {"dt": "2024-09-20T10:53:15.863479", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:15:00) [common]"}
- {"dt": "2024-09-20T10:53:15.863964", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-aarch64 -cpu max,pauth-impdef=on -machine virt,virtualization=on,gic-version=3,mte=on -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/kernel/Image -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning rw earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs/rootfs.ext4,if=none,format=raw,id=hd0 -device virtio-blk-device,drive=hd0"}
- {"dt": "2024-09-20T10:53:15.868631", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2024-09-20T10:53:15.869220", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:15.869371", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.01", "result": "pass", "extra": {"qemu_version": "1:8.2.4+ds-1", "host_arch": "amd64", "job_arch": "arm64"}}}
- {"dt": "2024-09-20T10:53:15.869620", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:15.869777", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:15:00) [common]"}
- {"dt": "2024-09-20T10:53:15.869920", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-09-20T10:53:15.870033", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:15:00)"}
- {"dt": "2024-09-20T10:53:15.960747", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2024-09-20T10:53:15.961156", "lvl": "target", "msg": "[    0.000000] Linux version 6.11.0-07341-gbaeb9a7d8b60 (tuxmake@tuxmake) (aarch64-linux-gnu-gcc (Debian 13.3.0-5) 13.3.0, GNU ld (GNU Binutils for Debian) 2.43) #1 SMP PREEMPT @1726805067"}
- {"dt": "2024-09-20T10:53:15.961254", "lvl": "target", "msg": "[    0.000000] KASLR enabled"}
- {"dt": "2024-09-20T10:53:15.961328", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2024-09-20T10:53:15.961428", "lvl": "target", "msg": "[    0.000000] Machine model: linux,dummy-virt"}
- {"dt": "2024-09-20T10:53:15.961511", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-09-20T10:53:15.961679", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2024-09-20T10:53:15.961822", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2024-09-20T10:53:15.962066", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:15:00) [common]"}
- {"dt": "2024-09-20T10:53:15.962132", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-09-20T10:53:15.962192", "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-09-20T10:53:15.962245", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-09-20T10:53:15.962286", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-09-20T10:53:15.962327", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-09-20T10:53:15.962365", "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-09-20T10:53:15.962448", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:15:00)"}
- {"dt": "2024-09-20T10:53:15.962505", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2024-09-20T10:53:15.965104", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-09-20T10:53:15.965399", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-09-20T10:53:15.965891", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x13f7f89c0-0x13f7fafff]"}
- {"dt": "2024-09-20T10:53:15.967967", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-09-20T10:53:15.968363", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2024-09-20T10:53:15.968473", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-09-20T10:53:15.968660", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2024-09-20T10:53:15.968785", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-09-20T10:53:15.968917", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-09-20T10:53:15.969128", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-09-20T10:53:15.969509", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2024-09-20T10:53:16.007663", "lvl": "target", "msg": "[    0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1"}
- {"dt": "2024-09-20T10:53:16.008492", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-09-20T10:53:16.008646", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-09-20T10:53:16.008799", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-09-20T10:53:16.009100", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2024-09-20T10:53:16.009209", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2024-09-20T10:53:16.011214", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 24 pages/cpu s61400 r8192 d28712 u98304"}
- {"dt": "2024-09-20T10:53:16.011773", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s61400 r8192 d28712 u98304 alloc=24*4096"}
- {"dt": "2024-09-20T10:53:16.011953", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2024-09-20T10:53:16.013100", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-09-20T10:53:16.014680", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2024-09-20T10:53:16.014927", "lvl": "target", "msg": "[    0.000000] CPU features: detected: GIC system register CPU interface"}
- {"dt": "2024-09-20T10:53:16.015108", "lvl": "target", "msg": "[    0.000000] CPU features: detected: HCRX_EL2 register"}
- {"dt": "2024-09-20T10:53:16.015328", "lvl": "target", "msg": "[    0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)"}
- {"dt": "2024-09-20T10:53:16.015513", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2024-09-20T10:53:16.015760", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Memory Tagging Extension"}
- {"dt": "2024-09-20T10:53:16.015924", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2024-09-20T10:53:16.016234", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v4"}
- {"dt": "2024-09-20T10:53:16.019340", "lvl": "target", "msg": "[    0.000000] alternatives: applying boot alternatives"}
- {"dt": "2024-09-20T10:53:16.021549", "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 rw earlycon"}
- {"dt": "2024-09-20T10:53:16.023483", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2024-09-20T10:53:16.027548", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.028802", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.030082", "lvl": "target", "msg": "<6>[    0.000000] Fallback order for Node 0: 0 "}
- {"dt": "2024-09-20T10:53:16.030190", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2024-09-20T10:53:16.030286", "lvl": "target", "msg": "<6>[    0.000000] Policy zone: Normal"}
- {"dt": "2024-09-20T10:53:16.030719", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-09-20T10:53:16.031050", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2024-09-20T10:53:16.079840", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)"}
- {"dt": "2024-09-20T10:53:16.096635", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2024-09-20T10:53:16.102826", "lvl": "target", "msg": "<6>[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-09-20T10:53:16.102956", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-09-20T10:53:16.103074", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2."}
- {"dt": "2024-09-20T10:53:16.103297", "lvl": "target", "msg": "<6>[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2024-09-20T10:53:16.103446", "lvl": "target", "msg": "<6>[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-09-20T10:53:16.103787", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-09-20T10:53:16.103956", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2024-09-20T10:53:16.105249", "lvl": "target", "msg": "<6>[    0.000000] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2."}
- {"dt": "2024-09-20T10:53:16.105431", "lvl": "target", "msg": "<6>[    0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2."}
- {"dt": "2024-09-20T10:53:16.105684", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-09-20T10:53:16.110851", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-09-20T10:53:16.110980", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2024-09-20T10:53:16.111097", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2024-09-20T10:53:16.112526", "lvl": "target", "msg": "<6>[    0.000000] Root IRQ handler: gic_handle_irq"}
- {"dt": "2024-09-20T10:53:16.112646", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2024-09-20T10:53:16.112836", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2024-09-20T10:53:16.113497", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2024-09-20T10:53:16.117802", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2024-09-20T10:53:16.118608", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100050000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-09-20T10:53:16.118935", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100060000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2024-09-20T10:53:16.119648", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x0000000100070000"}
- {"dt": "2024-09-20T10:53:16.120334", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100080000"}
- {"dt": "2024-09-20T10:53:16.122181", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2024-09-20T10:53:16.127626", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (phys)."}
- {"dt": "2024-09-20T10:53:16.128028", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2024-09-20T10:53:16.128561", "lvl": "target", "msg": "<6>[    0.000060] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2024-09-20T10:53:16.143020", "lvl": "target", "msg": "<6>[    0.012760] Console: colour dummy device 80x25"}
- {"dt": "2024-09-20T10:53:16.146804", "lvl": "target", "msg": "<6>[    0.017558] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000)"}
- {"dt": "2024-09-20T10:53:16.146997", "lvl": "target", "msg": "<6>[    0.018619] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-09-20T10:53:16.148824", "lvl": "target", "msg": "<6>[    0.020237] LSM: initializing lsm=capability"}
- {"dt": "2024-09-20T10:53:16.151835", "lvl": "target", "msg": "<6>[    0.023302] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.152034", "lvl": "target", "msg": "<6>[    0.023582] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.174657", "lvl": "target", "msg": "<4>[    0.046133] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2024-09-20T10:53:16.193493", "lvl": "target", "msg": "<6>[    0.064908] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-09-20T10:53:16.193622", "lvl": "target", "msg": "<6>[    0.065078] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2024-09-20T10:53:16.194998", "lvl": "target", "msg": "<6>[    0.066453] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2024-09-20T10:53:16.198288", "lvl": "target", "msg": "<6>[    0.069743] fsl-mc MSI: its@8080000 domain created"}
- {"dt": "2024-09-20T10:53:16.200165", "lvl": "target", "msg": "<6>[    0.071651] EFI services will not be available."}
- {"dt": "2024-09-20T10:53:16.201687", "lvl": "target", "msg": "<6>[    0.073169] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-09-20T10:53:16.224613", "lvl": "target", "msg": "<6>[    0.081477] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-09-20T10:53:16.224768", "lvl": "target", "msg": "<6>[    0.085140] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2024-09-20T10:53:16.224924", "lvl": "target", "msg": "<6>[    0.086739] GICv3: CPU1: using allocated LPI pending table @0x0000000100090000"}
- {"dt": "2024-09-20T10:53:16.225182", "lvl": "target", "msg": "<6>[    0.090190] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2024-09-20T10:53:16.225306", "lvl": "target", "msg": "<6>[    0.096017] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2024-09-20T10:53:16.225662", "lvl": "target", "msg": "<6>[    0.097121] SMP: Total of 2 processors activated."}
- {"dt": "2024-09-20T10:53:16.225768", "lvl": "target", "msg": "<6>[    0.097365] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-09-20T10:53:16.225979", "lvl": "target", "msg": "<6>[    0.097576] CPU features: detected: Branch Target Identification"}
- {"dt": "2024-09-20T10:53:16.226413", "lvl": "target", "msg": "<6>[    0.097794] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-09-20T10:53:16.226509", "lvl": "target", "msg": "<6>[    0.097968] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2024-09-20T10:53:16.226603", "lvl": "target", "msg": "<6>[    0.098118] CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2024-09-20T10:53:16.226811", "lvl": "target", "msg": "<6>[    0.098328] CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2024-09-20T10:53:16.227019", "lvl": "target", "msg": "<6>[    0.098612] CPU features: detected: Common not Private translations"}
- {"dt": "2024-09-20T10:53:16.227177", "lvl": "target", "msg": "<6>[    0.098799] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-09-20T10:53:16.227366", "lvl": "target", "msg": "<6>[    0.098953] CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2024-09-20T10:53:16.227490", "lvl": "target", "msg": "<6>[    0.099145] CPU features: detected: E0PD"}
- {"dt": "2024-09-20T10:53:16.227677", "lvl": "target", "msg": "<6>[    0.099272] CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2024-09-20T10:53:16.227857", "lvl": "target", "msg": "<6>[    0.099454] CPU features: detected: Enhanced Virtualization Traps"}
- {"dt": "2024-09-20T10:53:16.228013", "lvl": "target", "msg": "<6>[    0.099631] CPU features: detected: Fine Grained Traps"}
- {"dt": "2024-09-20T10:53:16.228277", "lvl": "target", "msg": "<6>[    0.099842] CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2024-09-20T10:53:16.228500", "lvl": "target", "msg": "<6>[    0.100111] CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2024-09-20T10:53:16.228737", "lvl": "target", "msg": "<6>[    0.100312] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)"}
- {"dt": "2024-09-20T10:53:16.228903", "lvl": "target", "msg": "<6>[    0.100517] CPU features: detected: LSE atomic instructions"}
- {"dt": "2024-09-20T10:53:16.229149", "lvl": "target", "msg": "<6>[    0.100680] CPU features: detected: Memory Copy and Memory Set instructions"}
- {"dt": "2024-09-20T10:53:16.229501", "lvl": "target", "msg": "<6>[    0.101047] CPU features: detected: Privileged Access Never"}
- {"dt": "2024-09-20T10:53:16.229601", "lvl": "target", "msg": "<6>[    0.101214] CPU features: detected: RAS Extension Support"}
- {"dt": "2024-09-20T10:53:16.229841", "lvl": "target", "msg": "<6>[    0.101362] CPU features: detected: Random Number Generator"}
- {"dt": "2024-09-20T10:53:16.230227", "lvl": "target", "msg": "<6>[    0.101621] CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2024-09-20T10:53:16.230310", "lvl": "target", "msg": "<6>[    0.101831] CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2024-09-20T10:53:16.230422", "lvl": "target", "msg": "<6>[    0.101990] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2024-09-20T10:53:16.230610", "lvl": "target", "msg": "<6>[    0.102202] CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2024-09-20T10:53:16.230813", "lvl": "target", "msg": "<6>[    0.102419] CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2024-09-20T10:53:16.230939", "lvl": "target", "msg": "<6>[    0.102593] CPU features: detected: FA64"}
- {"dt": "2024-09-20T10:53:16.231149", "lvl": "target", "msg": "<6>[    0.102719] CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2024-09-20T10:53:16.231313", "lvl": "target", "msg": "<6>[    0.102917] CPU features: detected: Scalable Vector Extension"}
- {"dt": "2024-09-20T10:53:16.247845", "lvl": "target", "msg": "<6>[    0.117479] alternatives: applying system-wide alternatives"}
- {"dt": "2024-09-20T10:53:16.257774", "lvl": "target", "msg": "<6>[    0.128796] CPU features: detected: Hardware dirty bit management on CPU0-1"}
- {"dt": "2024-09-20T10:53:16.258058", "lvl": "target", "msg": "<6>[    0.129607] SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-09-20T10:53:16.258244", "lvl": "target", "msg": "<6>[    0.129840] SVE: default vector length 64 bytes per vector"}
- {"dt": "2024-09-20T10:53:16.259648", "lvl": "target", "msg": "<6>[    0.131090] SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2024-09-20T10:53:16.259793", "lvl": "target", "msg": "<6>[    0.131284] SME: maximum available vector length 256 bytes per vector"}
- {"dt": "2024-09-20T10:53:16.259910", "lvl": "target", "msg": "<6>[    0.131469] SME: default vector length 32 bytes per vector"}
- {"dt": "2024-09-20T10:53:16.269688", "lvl": "target", "msg": "<6>[    0.141007] Memory: 3971072K/4194304K available (17280K kernel code, 4992K rwdata, 11744K rodata, 2880K init, 740K bss, 186288K reserved, 32768K cma-reserved)"}
- {"dt": "2024-09-20T10:53:16.296724", "lvl": "target", "msg": "<6>[    0.168105] devtmpfs: initialized"}
- {"dt": "2024-09-20T10:53:16.334796", "lvl": "target", "msg": "<6>[    0.206142] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-09-20T10:53:16.335191", "lvl": "target", "msg": "<6>[    0.206766] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.336940", "lvl": "target", "msg": "<6>[    0.208381] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL"}
- {"dt": "2024-09-20T10:53:16.337104", "lvl": "target", "msg": "<6>[    0.208612] 0 pages in range for non-PLT usage"}
- {"dt": "2024-09-20T10:53:16.339501", "lvl": "target", "msg": "<6>[    0.208660] 514816 pages in range for PLT usage"}
- {"dt": "2024-09-20T10:53:16.339621", "lvl": "target", "msg": "<6>[    0.210934] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-09-20T10:53:16.346344", "lvl": "target", "msg": "<6>[    0.217785] DMI not present or invalid."}
- {"dt": "2024-09-20T10:53:16.361365", "lvl": "target", "msg": "<6>[    0.232775] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2024-09-20T10:53:16.369564", "lvl": "target", "msg": "<6>[    0.240909] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-09-20T10:53:16.370498", "lvl": "target", "msg": "<6>[    0.241944] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-09-20T10:53:16.371227", "lvl": "target", "msg": "<6>[    0.242705] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-09-20T10:53:16.371591", "lvl": "target", "msg": "<6>[    0.243146] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-09-20T10:53:16.373711", "lvl": "target", "msg": "<5>[    0.244969] audit: type=2000 audit(0.220:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-09-20T10:53:16.377419", "lvl": "target", "msg": "<6>[    0.248816] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2024-09-20T10:53:16.377902", "lvl": "target", "msg": "<6>[    0.248865] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-09-20T10:53:16.378025", "lvl": "target", "msg": "<6>[    0.249480] cpuidle: using governor menu"}
- {"dt": "2024-09-20T10:53:16.379502", "lvl": "target", "msg": "<6>[    0.250977] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-09-20T10:53:16.380238", "lvl": "target", "msg": "<6>[    0.251699] ASID allocator initialised with 65536 entries"}
- {"dt": "2024-09-20T10:53:16.387083", "lvl": "target", "msg": "<6>[    0.258559] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-09-20T10:53:16.437868", "lvl": "target", "msg": "<6>[    0.309276] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 14, base_baud = 0) is a PL011 rev1"}
- {"dt": "2024-09-20T10:53:16.439338", "lvl": "target", "msg": "<6>[    0.310677] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-09-20T10:53:16.439483", "lvl": "target", "msg": "<6>[    0.310677] printk: legacy console [ttyAMA0] enabled"}
- {"dt": "2024-09-20T10:53:16.439616", "lvl": "target", "msg": "<6>[    0.311203] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-09-20T10:53:16.439814", "lvl": "target", "msg": "<6>[    0.311203] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2024-09-20T10:53:16.465697", "lvl": "target", "msg": "<6>[    0.337044] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-09-20T10:53:16.466102", "lvl": "target", "msg": "<6>[    0.337497] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page"}
- {"dt": "2024-09-20T10:53:16.466601", "lvl": "target", "msg": "<6>[    0.337926] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-09-20T10:53:16.467194", "lvl": "target", "msg": "<6>[    0.338580] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page"}
- {"dt": "2024-09-20T10:53:16.467607", "lvl": "target", "msg": "<6>[    0.338989] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-09-20T10:53:16.467980", "lvl": "target", "msg": "<6>[    0.339390] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page"}
- {"dt": "2024-09-20T10:53:16.468462", "lvl": "target", "msg": "<6>[    0.339768] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-09-20T10:53:16.468834", "lvl": "target", "msg": "<6>[    0.340245] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page"}
- {"dt": "2024-09-20T10:53:16.481605", "lvl": "target", "msg": "<6>[    0.353039] ACPI: Interpreter disabled."}
- {"dt": "2024-09-20T10:53:16.492875", "lvl": "target", "msg": "<6>[    0.364292] iommu: Default domain type: Translated"}
- {"dt": "2024-09-20T10:53:16.493041", "lvl": "target", "msg": "<6>[    0.364537] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2024-09-20T10:53:16.494618", "lvl": "target", "msg": "<5>[    0.366055] SCSI subsystem initialized"}
- {"dt": "2024-09-20T10:53:16.496084", "lvl": "target", "msg": "<7>[    0.367513] libata version 3.00 loaded."}
- {"dt": "2024-09-20T10:53:16.498958", "lvl": "target", "msg": "<6>[    0.370351] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-09-20T10:53:16.499731", "lvl": "target", "msg": "<6>[    0.371143] usbcore: registered new interface driver hub"}
- {"dt": "2024-09-20T10:53:16.500341", "lvl": "target", "msg": "<6>[    0.371788] usbcore: registered new device driver usb"}
- {"dt": "2024-09-20T10:53:16.505820", "lvl": "target", "msg": "<6>[    0.377251] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-09-20T10:53:16.506373", "lvl": "target", "msg": "<6>[    0.377612] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-09-20T10:53:16.506775", "lvl": "target", "msg": "<6>[    0.378297] PTP clock support registered"}
- {"dt": "2024-09-20T10:53:16.508006", "lvl": "target", "msg": "<6>[    0.379426] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-09-20T10:53:16.510161", "lvl": "target", "msg": "<6>[    0.381633] scmi_core: SCMI protocol bus registered"}
- {"dt": "2024-09-20T10:53:16.514166", "lvl": "target", "msg": "<6>[    0.385600] FPGA manager framework"}
- {"dt": "2024-09-20T10:53:16.515717", "lvl": "target", "msg": "<6>[    0.387081] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-09-20T10:53:16.532938", "lvl": "target", "msg": "<6>[    0.404326] vgaarb: loaded"}
- {"dt": "2024-09-20T10:53:16.536335", "lvl": "target", "msg": "<6>[    0.407743] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-09-20T10:53:16.539338", "lvl": "target", "msg": "<5>[    0.410763] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-09-20T10:53:16.539903", "lvl": "target", "msg": "<6>[    0.411120] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-09-20T10:53:16.542656", "lvl": "target", "msg": "<6>[    0.414086] pnp: PnP ACPI: disabled"}
- {"dt": "2024-09-20T10:53:16.593084", "lvl": "target", "msg": "<6>[    0.464436] NET: Registered PF_INET protocol family"}
- {"dt": "2024-09-20T10:53:16.595227", "lvl": "target", "msg": "<6>[    0.466589] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.600892", "lvl": "target", "msg": "<6>[    0.472322] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.601150", "lvl": "target", "msg": "<6>[    0.472679] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.601462", "lvl": "target", "msg": "<6>[    0.473008] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.601934", "lvl": "target", "msg": "<6>[    0.473495] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.602739", "lvl": "target", "msg": "<6>[    0.474217] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2024-09-20T10:53:16.605343", "lvl": "target", "msg": "<6>[    0.476812] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.605701", "lvl": "target", "msg": "<6>[    0.477216] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-09-20T10:53:16.606744", "lvl": "target", "msg": "<6>[    0.478223] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2024-09-20T10:53:16.609868", "lvl": "target", "msg": "<6>[    0.481338] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-09-20T10:53:16.609974", "lvl": "target", "msg": "<6>[    0.481549] RPC: Registered udp transport module."}
- {"dt": "2024-09-20T10:53:16.610072", "lvl": "target", "msg": "<6>[    0.481690] RPC: Registered tcp transport module."}
- {"dt": "2024-09-20T10:53:16.610235", "lvl": "target", "msg": "<6>[    0.481845] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2024-09-20T10:53:16.610445", "lvl": "target", "msg": "<6>[    0.482019] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-09-20T10:53:16.610802", "lvl": "target", "msg": "<6>[    0.482291] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-09-20T10:53:16.631703", "lvl": "target", "msg": "<6>[    0.503064] kvm [1]: nv: 554 coarse grained trap handlers"}
- {"dt": "2024-09-20T10:53:16.633889", "lvl": "target", "msg": "<6>[    0.505295] kvm [1]: nv: 669 fine grained trap handlers"}
- {"dt": "2024-09-20T10:53:16.635510", "lvl": "target", "msg": "<6>[    0.506948] kvm [1]: IPA Size Limit: 52 bits"}
- {"dt": "2024-09-20T10:53:16.636709", "lvl": "target", "msg": "<6>[    0.508157] kvm [1]: GICv3: no GICV resource entry"}
- {"dt": "2024-09-20T10:53:16.637028", "lvl": "target", "msg": "<6>[    0.508564] kvm [1]: disabling GICv2 emulation"}
- {"dt": "2024-09-20T10:53:16.637601", "lvl": "target", "msg": "<6>[    0.509076] kvm [1]: GIC system register CPU interface enabled"}
- {"dt": "2024-09-20T10:53:16.638987", "lvl": "target", "msg": "<6>[    0.510426] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-09-20T10:53:16.640087", "lvl": "target", "msg": "<6>[    0.511501] kvm [1]: VHE mode initialized successfully"}
- {"dt": "2024-09-20T10:53:16.646418", "lvl": "target", "msg": "<5>[    0.517838] Initialise system trusted keyrings"}
- {"dt": "2024-09-20T10:53:16.648592", "lvl": "target", "msg": "<6>[    0.519997] workingset: timestamp_bits=42 max_order=20 bucket_order=0"}
- {"dt": "2024-09-20T10:53:16.651515", "lvl": "target", "msg": "<6>[    0.522961] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-09-20T10:53:16.654174", "lvl": "target", "msg": "<5>[    0.525615] NFS: Registering the id_resolver key type"}
- {"dt": "2024-09-20T10:53:16.654719", "lvl": "target", "msg": "<5>[    0.526258] Key type id_resolver registered"}
- {"dt": "2024-09-20T10:53:16.654976", "lvl": "target", "msg": "<5>[    0.526534] Key type id_legacy registered"}
- {"dt": "2024-09-20T10:53:16.655693", "lvl": "target", "msg": "<6>[    0.527123] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-09-20T10:53:16.656286", "lvl": "target", "msg": "<6>[    0.527692] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-09-20T10:53:16.657626", "lvl": "target", "msg": "<6>[    0.529026] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-09-20T10:53:16.725924", "lvl": "target", "msg": "<5>[    0.597174] Key type asymmetric registered"}
- {"dt": "2024-09-20T10:53:16.726055", "lvl": "target", "msg": "<5>[    0.597337] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-09-20T10:53:16.726432", "lvl": "target", "msg": "<6>[    0.597629] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2024-09-20T10:53:16.726530", "lvl": "target", "msg": "<6>[    0.597927] io scheduler mq-deadline registered"}
- {"dt": "2024-09-20T10:53:16.726628", "lvl": "target", "msg": "<6>[    0.598108] io scheduler kyber registered"}
- {"dt": "2024-09-20T10:53:16.726827", "lvl": "target", "msg": "<6>[    0.598468] io scheduler bfq registered"}
- {"dt": "2024-09-20T10:53:16.752945", "lvl": "target", "msg": "<6>[    0.624142] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2024-09-20T10:53:16.758666", "lvl": "target", "msg": "<6>[    0.630052] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2024-09-20T10:53:16.759260", "lvl": "target", "msg": "<6>[    0.630717] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2024-09-20T10:53:16.759813", "lvl": "target", "msg": "<6>[    0.631319] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2024-09-20T10:53:16.760515", "lvl": "target", "msg": "<6>[    0.631695] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2024-09-20T10:53:16.760876", "lvl": "target", "msg": "<4>[    0.632411] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2024-09-20T10:53:16.761755", "lvl": "target", "msg": "<6>[    0.633030] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2024-09-20T10:53:16.762861", "lvl": "target", "msg": "<6>[    0.634330] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2024-09-20T10:53:16.763238", "lvl": "target", "msg": "<6>[    0.634737] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2024-09-20T10:53:16.763376", "lvl": "target", "msg": "<6>[    0.634951] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2024-09-20T10:53:16.763579", "lvl": "target", "msg": "<6>[    0.635154] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-09-20T10:53:16.763843", "lvl": "target", "msg": "<6>[    0.635409] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-09-20T10:53:16.765229", "lvl": "target", "msg": "<6>[    0.636676] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2024-09-20T10:53:16.769439", "lvl": "target", "msg": "<6>[    0.640903] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2024-09-20T10:53:16.769548", "lvl": "target", "msg": "<6>[    0.641102] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2024-09-20T10:53:16.769693", "lvl": "target", "msg": "<6>[    0.641285] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2024-09-20T10:53:16.888006", "lvl": "target", "msg": "<6>[    0.759326] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-09-20T10:53:16.896811", "lvl": "target", "msg": "<6>[    0.768228] msm_serial: driver initialized"}
- {"dt": "2024-09-20T10:53:16.897764", "lvl": "target", "msg": "<6>[    0.769205] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-09-20T10:53:16.898161", "lvl": "target", "msg": "<6>[    0.769751] STM32 USART driver initialized"}
- {"dt": "2024-09-20T10:53:16.920397", "lvl": "target", "msg": "<6>[    0.791793] loop: module loaded"}
- {"dt": "2024-09-20T10:53:16.920853", "lvl": "target", "msg": "<6>[    0.792456] virtio_blk virtio0: 1/0/0 default/read/poll queues"}
- {"dt": "2024-09-20T10:53:16.924188", "lvl": "target", "msg": "<5>[    0.795618] virtio_blk virtio0: [vda] 5302048 512-byte logical blocks (2.71 GB/2.53 GiB)"}
- {"dt": "2024-09-20T10:53:16.937244", "lvl": "target", "msg": "<6>[    0.808677] megasas: 07.727.03.00-rc1"}
- {"dt": "2024-09-20T10:53:16.941759", "lvl": "target", "msg": "<5>[    0.813185] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2024-09-20T10:53:16.943058", "lvl": "target", "msg": "<6>[    0.814471] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-09-20T10:53:16.943456", "lvl": "target", "msg": "<6>[    0.815051] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-09-20T10:53:16.944461", "lvl": "target", "msg": "<6>[    0.815917] Using buffer write method"}
- {"dt": "2024-09-20T10:53:16.944710", "lvl": "target", "msg": "<7>[    0.816289] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-09-20T10:53:16.945115", "lvl": "target", "msg": "<5>[    0.816638] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2024-09-20T10:53:16.945873", "lvl": "target", "msg": "<6>[    0.817291] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2024-09-20T10:53:16.945984", "lvl": "target", "msg": "<6>[    0.817600] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2024-09-20T10:53:16.946868", "lvl": "target", "msg": "<6>[    0.818301] Using buffer write method"}
- {"dt": "2024-09-20T10:53:16.947041", "lvl": "target", "msg": "<7>[    0.818540] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2024-09-20T10:53:16.947389", "lvl": "target", "msg": "<5>[    0.818893] Concatenating MTD devices:"}
- {"dt": "2024-09-20T10:53:16.947490", "lvl": "target", "msg": "<5>[    0.819096] (0): \"0.flash\""}
- {"dt": "2024-09-20T10:53:16.947581", "lvl": "target", "msg": "<5>[    0.819204] (1): \"0.flash\""}
- {"dt": "2024-09-20T10:53:16.947671", "lvl": "target", "msg": "<5>[    0.819299] into device \"0.flash\""}
- {"dt": "2024-09-20T10:53:16.968708", "lvl": "target", "msg": "<6>[    0.840138] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2024-09-20T10:53:16.971402", "lvl": "target", "msg": "<6>[    0.842869] thunder_xcv, ver 1.0"}
- {"dt": "2024-09-20T10:53:16.971513", "lvl": "target", "msg": "<6>[    0.843061] thunder_bgx, ver 1.0"}
- {"dt": "2024-09-20T10:53:16.971619", "lvl": "target", "msg": "<6>[    0.843238] nicpf, ver 1.0"}
- {"dt": "2024-09-20T10:53:16.974628", "lvl": "target", "msg": "<6>[    0.846100] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2024-09-20T10:53:16.974744", "lvl": "target", "msg": "<6>[    0.846313] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2024-09-20T10:53:16.975091", "lvl": "target", "msg": "<6>[    0.846641] hclge is initializing"}
- {"dt": "2024-09-20T10:53:16.975214", "lvl": "target", "msg": "<6>[    0.846847] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-09-20T10:53:16.975391", "lvl": "target", "msg": "<6>[    0.846997] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2024-09-20T10:53:16.975741", "lvl": "target", "msg": "<6>[    0.847254] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-09-20T10:53:16.975928", "lvl": "target", "msg": "<6>[    0.847533] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-09-20T10:53:16.976275", "lvl": "target", "msg": "<6>[    0.847789] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-09-20T10:53:16.976376", "lvl": "target", "msg": "<6>[    0.847954] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-09-20T10:53:16.976597", "lvl": "target", "msg": "<6>[    0.848196] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2024-09-20T10:53:16.976770", "lvl": "target", "msg": "<6>[    0.848379] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-09-20T10:53:16.977740", "lvl": "target", "msg": "<6>[    0.849226] sky2: driver version 1.30"}
- {"dt": "2024-09-20T10:53:16.981658", "lvl": "target", "msg": "<6>[    0.852928] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2024-09-20T10:53:16.998930", "lvl": "target", "msg": "<6>[    0.870159] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-09-20T10:53:17.018794", "lvl": "target", "msg": "<6>[    0.890223] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2024-09-20T10:53:17.020923", "lvl": "target", "msg": "<6>[    0.891780] rtc-pl031 9010000.pl031: setting system clock to 2024-09-20T10:53:17 UTC (1726829597)"}
- {"dt": "2024-09-20T10:53:17.024667", "lvl": "target", "msg": "<6>[    0.896173] i2c_dev: i2c /dev entries driver"}
- {"dt": "2024-09-20T10:53:17.054044", "lvl": "target", "msg": "<6>[    0.925512] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-09-20T10:53:17.054147", "lvl": "target", "msg": "<6>[    0.925697] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-09-20T10:53:17.055902", "lvl": "target", "msg": "<6>[    0.927341] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-09-20T10:53:17.058023", "lvl": "target", "msg": "<6>[    0.929520] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-09-20T10:53:17.062788", "lvl": "target", "msg": "<6>[    0.934250] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-09-20T10:53:17.068377", "lvl": "target", "msg": "<6>[    0.939828] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-09-20T10:53:17.068492", "lvl": "target", "msg": "<6>[    0.940016] usbhid: USB HID core driver"}
- {"dt": "2024-09-20T10:53:17.077614", "lvl": "target", "msg": "<6>[    0.948967] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available"}
- {"dt": "2024-09-20T10:53:17.090346", "lvl": "target", "msg": "<6>[    0.961797] NET: Registered PF_PACKET protocol family"}
- {"dt": "2024-09-20T10:53:17.091338", "lvl": "target", "msg": "<6>[    0.962812] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-09-20T10:53:17.091513", "lvl": "target", "msg": "<5>[    0.963154] Key type dns_resolver registered"}
- {"dt": "2024-09-20T10:53:17.115730", "lvl": "target", "msg": "<6>[    0.987110] registered taskstats version 1"}
- {"dt": "2024-09-20T10:53:17.116932", "lvl": "target", "msg": "<5>[    0.988406] Loading compiled-in X.509 certificates"}
- {"dt": "2024-09-20T10:53:17.137100", "lvl": "target", "msg": "<6>[    1.008581] Demotion targets for Node 0: null"}
- {"dt": "2024-09-20T10:53:17.144114", "lvl": "target", "msg": "<6>[    1.015568] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2024-09-20T10:53:17.149816", "lvl": "target", "msg": "<6>[    1.021275] clk: Disabling unused clocks"}
- {"dt": "2024-09-20T10:53:17.149949", "lvl": "target", "msg": "<6>[    1.021577] PM: genpd: Disabling unused power domains"}
- {"dt": "2024-09-20T10:53:17.150313", "lvl": "target", "msg": "<6>[    1.021921] ALSA device list:"}
- {"dt": "2024-09-20T10:53:17.150424", "lvl": "target", "msg": "<6>[    1.022065]   No soundcards found."}
- {"dt": "2024-09-20T10:53:17.182631", "lvl": "target", "msg": "<6>[    1.053217] EXT4-fs (vda): mounted filesystem 613b2bea-57ad-4fe3-a3fb-8ce3ef4c8c06 r/w with ordered data mode. Quota mode: none."}
- {"dt": "2024-09-20T10:53:17.183849", "lvl": "target", "msg": "<6>[    1.054903] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2024-09-20T10:53:17.187247", "lvl": "target", "msg": "<6>[    1.058682] devtmpfs: mounted"}
- {"dt": "2024-09-20T10:53:17.259363", "lvl": "target", "msg": "<6>[    1.130739] Freeing unused kernel memory: 2880K"}
- {"dt": "2024-09-20T10:53:17.260102", "lvl": "target", "msg": "<6>[    1.131558] Run /sbin/init as init process"}
- {"dt": "2024-09-20T10:53:17.260214", "lvl": "target", "msg": "<7>[    1.131722]   with arguments:"}
- {"dt": "2024-09-20T10:53:17.260331", "lvl": "target", "msg": "<7>[    1.131861]     /sbin/init"}
- {"dt": "2024-09-20T10:53:17.260423", "lvl": "target", "msg": "<7>[    1.131987]     verbose"}
- {"dt": "2024-09-20T10:53:17.260518", "lvl": "target", "msg": "<7>[    1.132076]   with environment:"}
- {"dt": "2024-09-20T10:53:17.260621", "lvl": "target", "msg": "<7>[    1.132210]     HOME=/"}
- {"dt": "2024-09-20T10:53:17.260715", "lvl": "target", "msg": "<7>[    1.132330]     TERM=linux"}
- {"dt": "2024-09-20T10:53:17.486351", "lvl": "target", "msg": "<6>[    1.357761] NET: Registered PF_INET6 protocol family"}
- {"dt": "2024-09-20T10:53:17.494633", "lvl": "target", "msg": "<6>[    1.366048] Segment Routing with IPv6"}
- {"dt": "2024-09-20T10:53:17.494780", "lvl": "target", "msg": "<6>[    1.366381] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2024-09-20T10:53:17.592603", "lvl": "target", "msg": "[!p]104\a[?7h"}
- {"dt": "2024-09-20T10:53:17.593021", "lvl": "target", "msg": "Welcome to [1mDebian GNU/Linux trixie/sid[0m!"}
- {"dt": "2024-09-20T10:53:17.593131", "lvl": "target", "msg": "\r"}
- {"dt": "2024-09-20T10:53:19.492296", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty.\r"}
- {"dt": "2024-09-20T10:53:19.502806", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe.\r"}
- {"dt": "2024-09-20T10:53:19.512206", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-serial\\x2dget\u2026slice[0m - Slice /system/serial-getty.\r"}
- {"dt": "2024-09-20T10:53:19.517870", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice.\r"}
- {"dt": "2024-09-20T10:53:19.522157", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-conso\u2026[0mequests to Console Directory Watch.\r"}
- {"dt": "2024-09-20T10:53:19.524498", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-wall.\u2026[0md Requests to Wall Directory Watch.\r"}
- {"dt": "2024-09-20T10:53:19.527589", "lvl": "target", "msg": "         Expecting device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0...\r"}
- {"dt": "2024-09-20T10:53:19.529579", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units.\r"}
- {"dt": "2024-09-20T10:53:19.530989", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems.\r"}
- {"dt": "2024-09-20T10:53:19.532398", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units.\r"}
- {"dt": "2024-09-20T10:53:19.533090", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps.\r"}
- {"dt": "2024-09-20T10:53:19.544451", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-creds.socket[0m - Credential Encryption/Decryption.\r"}
- {"dt": "2024-09-20T10:53:19.546583", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initctl.socke\u2026[0m- initctl Compatibility Named Pipe.\r"}
- {"dt": "2024-09-20T10:53:19.548507", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald-dev-\u2026socket[0m - Journal Socket (/dev/log).\r"}
- {"dt": "2024-09-20T10:53:19.550109", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Sockets.\r"}
- {"dt": "2024-09-20T10:53:19.552338", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket.\r"}
- {"dt": "2024-09-20T10:53:19.553725", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket.\r"}
- {"dt": "2024-09-20T10:53:19.574101", "lvl": "target", "msg": "         Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System...\r"}
- {"dt": "2024-09-20T10:53:19.584696", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System...\r"}
- {"dt": "2024-09-20T10:53:19.602376", "lvl": "target", "msg": "         Mounting [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock...\r"}
- {"dt": "2024-09-20T10:53:19.614389", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System...\r"}
- {"dt": "2024-09-20T10:53:19.645390", "lvl": "target", "msg": "         Mounting [0;1;39mtmp.mount[0m - Temporary Directory /tmp...\r"}
- {"dt": "2024-09-20T10:53:19.680566", "lvl": "target", "msg": "         Starting [0;1;39mkmod-static-nodes.service[0m\u2026eate List of Static Device Nodes...\r"}
- {"dt": "2024-09-20T10:53:19.705821", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs...\r"}
- {"dt": "2024-09-20T10:53:19.749230", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm...\r"}
- {"dt": "2024-09-20T10:53:19.785303", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_pstore.servi\u2026[0m - Load Kernel Module efi_pstore...\r"}
- {"dt": "2024-09-20T10:53:19.845095", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse...\r"}
- {"dt": "2024-09-20T10:53:20.001531", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service...\r"}
- {"dt": "2024-09-20T10:53:20.069540", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules...\r"}
- {"dt": "2024-09-20T10:53:20.157568", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-fs.servic\u2026[0munt Root and Kernel File Systems...\r"}
- {"dt": "2024-09-20T10:53:20.270128", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-load-credent\u2026[0mLoad udev Rules from Credentials...\r"}
- {"dt": "2024-09-20T10:53:20.357200", "lvl": "target", "msg": "<6>[    4.228556] fuse: init (API version 7.40)"}
- {"dt": "2024-09-20T10:53:20.397443", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices...\r"}
- {"dt": "2024-09-20T10:53:20.469552", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System.\r"}
- {"dt": "2024-09-20T10:53:20.471325", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System.\r"}
- {"dt": "2024-09-20T10:53:20.477125", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock.\r"}
- {"dt": "2024-09-20T10:53:20.481500", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System.\r"}
- {"dt": "2024-09-20T10:53:20.485653", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mtmp.mount[0m - Temporary Directory /tmp.\r"}
- {"dt": "2024-09-20T10:53:20.502002", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mkmod-static-nodes.service[0m\u2026Create List of Static Device Nodes.\r"}
- {"dt": "2024-09-20T10:53:20.557230", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs.\r"}
- {"dt": "2024-09-20T10:53:20.601652", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm.\r"}
- {"dt": "2024-09-20T10:53:20.646794", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore.\r"}
- {"dt": "2024-09-20T10:53:20.671644", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse.\r"}
- {"dt": "2024-09-20T10:53:20.689104", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-fs.servic\u2026[0mmount Root and Kernel File Systems.\r"}
- {"dt": "2024-09-20T10:53:20.744166", "lvl": "target", "msg": "         Mounting [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System...\r"}
- {"dt": "2024-09-20T10:53:20.782218", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System...\r"}
- {"dt": "2024-09-20T10:53:20.857973", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed...\r"}
- {"dt": "2024-09-20T10:53:20.905318", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0m Device Nodes in /dev gracefully...\r"}
- {"dt": "2024-09-20T10:53:20.992497", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules.\r"}
- {"dt": "2024-09-20T10:53:21.020696", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-load-credent\u2026[0m- Load udev Rules from Credentials.\r"}
- {"dt": "2024-09-20T10:53:21.085902", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables...\r"}
- {"dt": "2024-09-20T10:53:21.217590", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service.\r"}
- {"dt": "2024-09-20T10:53:21.398616", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System.\r"}
- {"dt": "2024-09-20T10:53:21.553553", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System.\r"}
- {"dt": "2024-09-20T10:53:21.592616", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed.\r"}
- {"dt": "2024-09-20T10:53:21.706317", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-flush.ser\u2026[0msh Journal to Persistent Storage...\r"}
- {"dt": "2024-09-20T10:53:21.761370", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mic Device Nodes in /dev gracefully.\r"}
- {"dt": "2024-09-20T10:53:21.811689", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.service[0m - Create System Users...\r"}
- {"dt": "2024-09-20T10:53:21.865152", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables.\r"}
- {"dt": "2024-09-20T10:53:22.148777", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users.\r"}
- {"dt": "2024-09-20T10:53:22.163841", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0meate Static Device Nodes in /dev...\r"}
- {"dt": "2024-09-20T10:53:22.302333", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-flush.ser\u2026[0mlush Journal to Persistent Storage.\r"}
- {"dt": "2024-09-20T10:53:22.494004", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mCreate Static Device Nodes in /dev.\r"}
- {"dt": "2024-09-20T10:53:22.496424", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pre.target[0m\u2026Preparation for Local File Systems.\r"}
- {"dt": "2024-09-20T10:53:22.497518", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems.\r"}
- {"dt": "2024-09-20T10:53:22.513507", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-sysext.socket[0m\u2026 System Extension Image Management.\r"}
- {"dt": "2024-09-20T10:53:22.523411", "lvl": "target", "msg": "         Starting [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache...\r"}
- {"dt": "2024-09-20T10:53:22.593775", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup.se\u2026[0mate System Files and Directories...\r"}
- {"dt": "2024-09-20T10:53:22.641902", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.service[0m - R\u2026ager for Device Events and Files...\r"}
- {"dt": "2024-09-20T10:53:23.338988", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache.\r"}
- {"dt": "2024-09-20T10:53:23.386484", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup.se\u2026[0mreate System Files and Directories.\r"}
- {"dt": "2024-09-20T10:53:23.409459", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-catalog-u\u2026ervice[0m - Rebuild Journal Catalog...\r"}
- {"dt": "2024-09-20T10:53:23.445855", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-utmp.servi\u2026[0mord System Boot/Shutdown in UTMP...\r"}
- {"dt": "2024-09-20T10:53:23.501255", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.service[0m - Ru\u2026anager for Device Events and Files.\r"}
- {"dt": "2024-09-20T10:53:23.949053", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-utmp.servi\u2026[0mecord System Boot/Shutdown in UTMP.\r"}
- {"dt": "2024-09-20T10:53:24.115574", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-catalog-u\u2026.service[0m - Rebuild Journal Catalog.\r"}
- {"dt": "2024-09-20T10:53:24.131175", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-done.service[0m - Update is Completed...\r"}
- {"dt": "2024-09-20T10:53:24.139425", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices.\r"}
- {"dt": "2024-09-20T10:53:24.303253", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0.\r"}
- {"dt": "2024-09-20T10:53:24.376889", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-done.service[0m - Update is Completed.\r"}
- {"dt": "2024-09-20T10:53:24.386022", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msysinit.target[0m - System Initialization.\r"}
- {"dt": "2024-09-20T10:53:24.394695", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities.\r"}
- {"dt": "2024-09-20T10:53:24.400294", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - \u2026y apt upgrade and clean activities.\r"}
- {"dt": "2024-09-20T10:53:24.401795", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer.\r"}
- {"dt": "2024-09-20T10:53:24.403433", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39me2scrub_all.timer[0m - Period\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2024-09-20T10:53:24.405396", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week.\r"}
- {"dt": "2024-09-20T10:53:24.406986", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files.\r"}
- {"dt": "2024-09-20T10:53:24.409189", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-tmpfiles-clean.tim\u2026[0my Cleanup of Temporary Directories.\r"}
- {"dt": "2024-09-20T10:53:24.410170", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units.\r"}
- {"dt": "2024-09-20T10:53:24.413003", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket.\r"}
- {"dt": "2024-09-20T10:53:24.414642", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-hostnamed.socket[0m - Hostname Service Socket.\r"}
- {"dt": "2024-09-20T10:53:24.415960", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units.\r"}
- {"dt": "2024-09-20T10:53:24.417286", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mbasic.target[0m - Basic System.\r"}
- {"dt": "2024-09-20T10:53:24.433305", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon.\r"}
- {"dt": "2024-09-20T10:53:24.461371", "lvl": "target", "msg": "         Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus...\r"}
- {"dt": "2024-09-20T10:53:24.503179", "lvl": "target", "msg": "         Starting [0;1;39me2scrub_reap.service[0m - Re\u2026ne ext4 Metadata Check Snapshots...\r"}
- {"dt": "2024-09-20T10:53:24.605331", "lvl": "target", "msg": "         Starting [0;1;39msystemd-logind.service[0m - User Login Management...\r"}
- {"dt": "2024-09-20T10:53:24.641691", "lvl": "target", "msg": "         Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions...\r"}
- {"dt": "2024-09-20T10:53:25.159582", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus.\r"}
- {"dt": "2024-09-20T10:53:25.237561", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions.\r"}
- {"dt": "2024-09-20T10:53:25.399183", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1.\r"}
- {"dt": "2024-09-20T10:53:25.434164", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mserial-getty@ttyAMA0.service[0m - Serial Getty on ttyAMA0.\r"}
- {"dt": "2024-09-20T10:53:25.444924", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts.\r"}
- {"dt": "2024-09-20T10:53:25.783707", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39me2scrub_reap.service[0m - Re\u2026line ext4 Metadata Check Snapshots.\r"}
- {"dt": "2024-09-20T10:53:26.268944", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management.\r"}
- {"dt": "2024-09-20T10:53:26.272494", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System.\r"}
- {"dt": "2024-09-20T10:53:26.272916", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface.\r"}
- {"dt": "2024-09-20T10:53:26.291551", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-utmp-runle\u2026[0m- Record Runlevel Change in UTMP...\r"}
- {"dt": "2024-09-20T10:53:26.572739", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-utmp-runle\u2026e[0m - Record Runlevel Change in UTMP.\r"}
- {"dt": "2024-09-20T10:53:27.759812", "lvl": "target", "msg": "\r"}
- {"dt": "2024-09-20T10:53:27.760287", "lvl": "target", "msg": "Debian GNU/Linux trixie/sid 2f9bfa2d0ac1 ttyAMA0"}
- {"dt": "2024-09-20T10:53:27.760397", "lvl": "target", "msg": ""}
- {"dt": "2024-09-20T10:53:27.760922", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2024-09-20T10:53:27.761068", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "11.80", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2024-09-20T10:53:27.761242", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2024-09-20T10:53:27.761335", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2024-09-20T10:53:27.862016", "lvl": "input", "msg": "root"}
- {"dt": "2024-09-20T10:53:27.862230", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-09-20T10:53:27.862363", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:14:48)"}
- {"dt": "2024-09-20T10:53:27.862462", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:24)"}
- {"dt": "2024-09-20T10:53:27.863051", "lvl": "target", "msg": "2f9bfa2d0ac1 login: root\r"}
- {"dt": "2024-09-20T10:53:28.132427", "lvl": "target", "msg": "Linux 2f9bfa2d0ac1 6.11.0-07341-gbaeb9a7d8b60 #1 SMP PREEMPT @1726805067 aarch64"}
- {"dt": "2024-09-20T10:53:28.133752", "lvl": "target", "msg": ""}
- {"dt": "2024-09-20T10:53:28.134076", "lvl": "target", "msg": "The programs included with the Debian GNU/Linux system are free software;"}
- {"dt": "2024-09-20T10:53:28.134206", "lvl": "target", "msg": "the exact distribution terms for each program are described in the"}
- {"dt": "2024-09-20T10:53:28.134322", "lvl": "target", "msg": "individual files in /usr/share/doc/*/copyright."}
- {"dt": "2024-09-20T10:53:28.134414", "lvl": "target", "msg": ""}
- {"dt": "2024-09-20T10:53:28.134509", "lvl": "target", "msg": "Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent"}
- {"dt": "2024-09-20T10:53:28.134609", "lvl": "target", "msg": "permitted by applicable law."}
- {"dt": "2024-09-20T10:53:30.718412", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-09-20T10:53:30.718650", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:15) [common]"}
- {"dt": "2024-09-20T10:53:30.718780", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "14.76", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2024-09-20T10:53:30.718976", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:15) [common]"}
- {"dt": "2024-09-20T10:53:30.719109", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:14:45) [common]"}
- {"dt": "2024-09-20T10:53:30.719218", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2024-09-20T10:53:30.719302", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2024-09-20T10:53:30.719384", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2024-09-20T10:53:30.769799", "lvl": "input", "msg": ""}
- {"dt": "2024-09-20T10:53:30.770015", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:15:00)"}
- {"dt": "2024-09-20T10:53:30.770142", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2024-09-20T10:53:30.772056", "lvl": "target", "msg": "[?2004hroot@2f9bfa2d0ac1:~# "}
- {"dt": "2024-09-20T10:53:30.775211", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:30.775383", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:14:45) [common]"}
- {"dt": "2024-09-20T10:53:30.775544", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:53:30.775688", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:15) [common]"}
- {"dt": "2024-09-20T10:53:30.775818", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:15:00) [common]"}
- {"dt": "2024-09-20T10:53:30.775932", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:15:00) [common]"}
- {"dt": "2024-09-20T10:53:30.776032", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2024-09-20T10:53:30.776124", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2024-09-20T10:53:30.876412", "lvl": "input", "msg": "#"}
- {"dt": "2024-09-20T10:53:30.876627", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:15:00)"}
- {"dt": "2024-09-20T10:53:30.877600", "lvl": "target", "msg": "[?2004l\r[?2004hroot@2f9bfa2d0ac1:~# #"}
- {"dt": "2024-09-20T10:53:30.880524", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2024-09-20T10:53:30.880654", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2024-09-20T10:53:30.980954", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2024-09-20T10:53:30.982368", "lvl": "target", "msg": "[?2004l\r[?2004hroot@2f9bfa2d0ac1:~# export SHELL=/bin/sh"}
- {"dt": "2024-09-20T10:53:30.986260", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2024-09-20T10:53:31.087309", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2024-09-20T10:53:31.088774", "lvl": "target", "msg": "[?2004l\r[?2004hroot@2f9bfa2d0ac1:~# . /lava-1/environment"}
- {"dt": "2024-09-20T10:53:31.096841", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2024-09-20T10:53:31.197851", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2024-09-20T10:53:31.198139", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2024-09-20T10:53:31.199375", "lvl": "target", "msg": "[?2004l\r[?2004hroot@2f9bfa2d0ac1:~# /lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2024-09-20T10:53:31.602870", "lvl": "target", "msg": "[?2004l\r+ export TESTRUN_ID=0_kselftest-dt"}
- {"dt": "2024-09-20T10:53:31.603330", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_kselftest-dt"}
- {"dt": "2024-09-20T10:53:31.607805", "lvl": "target", "msg": "+ cat uuid"}
- {"dt": "2024-09-20T10:53:31.632814", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2024-09-20T10:53:31.633154", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-09-20T10:53:31.636252", "lvl": "target", "msg": "<8>[   15.507634] <LAVA_SIGNAL_STARTRUN 0_kselftest-dt 1_1.1.3.1>"}
- {"dt": "2024-09-20T10:53:31.636584", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_kselftest-dt 1_1.1.3.1"}
- {"dt": "2024-09-20T10:53:31.636701", "lvl": "info", "msg": "Starting test lava.0_kselftest-dt (1_1.1.3.1)"}
- {"dt": "2024-09-20T10:53:31.636840", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2024-09-20T10:53:31.637388", "lvl": "target", "msg": "+ cd ./automated/linux/kselftest/"}
- {"dt": "2024-09-20T10:53:31.644638", "lvl": "target", "msg": "+ ./kselftest.sh -c dt -T  -t kselftest_armhf.tar.gz -s false -u  -L  -S skipfile-lkft.yaml -b  -g  -e production -p /opt/kselftests/default-in-kernel -n 1 -i 1 -E "}
- {"dt": "2024-09-20T10:53:31.783169", "lvl": "target", "msg": "INFO: Generating a skipfile based on /lava-1/0/tests/0_kselftest-dt/automated/linux/kselftest/skipfile-lkft.yaml"}
- {"dt": "2024-09-20T10:53:31.945422", "lvl": "target", "msg": "INFO: Using the following generated skipfile contents (until EOF):"}
- {"dt": "2024-09-20T10:53:31.979759", "lvl": "target", "msg": "bpf:test_btf"}
- {"dt": "2024-09-20T10:53:31.979911", "lvl": "target", "msg": "bpf:test_progs"}
- {"dt": "2024-09-20T10:53:31.980031", "lvl": "target", "msg": "breakpoints:breakpoint_test"}
- {"dt": "2024-09-20T10:53:31.980148", "lvl": "target", "msg": "breakpoints:breakpoint_test_arm64"}
- {"dt": "2024-09-20T10:53:31.980483", "lvl": "target", "msg": "breakpoints:step_after_suspend_test"}
- {"dt": "2024-09-20T10:53:31.980585", "lvl": "target", "msg": "kmod:test_kmod.sh"}
- {"dt": "2024-09-20T10:53:31.980699", "lvl": "target", "msg": "kvm:get-reg-list"}
- {"dt": "2024-09-20T10:53:31.980783", "lvl": "target", "msg": "kvm:kvm_create_max_vcpus"}
- {"dt": "2024-09-20T10:53:31.980883", "lvl": "target", "msg": "mqueue:mq_open_tests"}
- {"dt": "2024-09-20T10:53:31.980969", "lvl": "target", "msg": "mqueue:mq_perf_tests"}
- {"dt": "2024-09-20T10:53:31.981076", "lvl": "target", "msg": "net:fib_nexthops.sh"}
- {"dt": "2024-09-20T10:53:31.981154", "lvl": "target", "msg": "net:msg_zerocopy.sh"}
- {"dt": "2024-09-20T10:53:31.981249", "lvl": "target", "msg": "net:rtnetlink.sh"}
- {"dt": "2024-09-20T10:53:31.981328", "lvl": "target", "msg": "net:run_afpackettests"}
- {"dt": "2024-09-20T10:53:31.981400", "lvl": "target", "msg": "net:tls"}
- {"dt": "2024-09-20T10:53:31.981488", "lvl": "target", "msg": "net:udpgro.sh"}
- {"dt": "2024-09-20T10:53:31.981564", "lvl": "target", "msg": "net:xfrm_policy.sh"}
- {"dt": "2024-09-20T10:53:31.981652", "lvl": "target", "msg": "netfilter:bridge_brouter.sh"}
- {"dt": "2024-09-20T10:53:31.981727", "lvl": "target", "msg": "netfilter:conntrack_icmp_related.sh"}
- {"dt": "2024-09-20T10:53:31.981813", "lvl": "target", "msg": "netfilter:nft_flowtable.sh"}
- {"dt": "2024-09-20T10:53:31.981903", "lvl": "target", "msg": "netfilter:nft_nat.sh"}
- {"dt": "2024-09-20T10:53:31.981994", "lvl": "target", "msg": "netfilter:nft_trans_stress.sh"}
- {"dt": "2024-09-20T10:53:31.982085", "lvl": "target", "msg": "pidfd:pidfd_wait"}
- {"dt": "2024-09-20T10:53:31.982175", "lvl": "target", "msg": "proc:proc-pid-vm"}
- {"dt": "2024-09-20T10:53:31.982263", "lvl": "target", "msg": "ptrace:vmaccess"}
- {"dt": "2024-09-20T10:53:31.982351", "lvl": "target", "msg": "rseq:run_param_test.sh"}
- {"dt": "2024-09-20T10:53:31.982448", "lvl": "target", "msg": "sync:sync_test"}
- {"dt": "2024-09-20T10:53:31.982524", "lvl": "target", "msg": "vm:run_vmtests"}
- {"dt": "2024-09-20T10:53:31.982609", "lvl": "target", "msg": "zram:zram.sh"}
- {"dt": "2024-09-20T10:53:31.987188", "lvl": "target", "msg": "INFO: EOF"}
- {"dt": "2024-09-20T10:53:32.121856", "lvl": "target", "msg": "INFO: Installing sed perl wget xz-utils iproute2 python3-tap"}
- {"dt": "2024-09-20T10:53:32.595037", "lvl": "target", "msg": "Ign:1 https://deb.debian.org/debian trixie InRelease"}
- {"dt": "2024-09-20T10:53:33.603554", "lvl": "target", "msg": "Ign:1 https://deb.debian.org/debian trixie InRelease"}
- {"dt": "2024-09-20T10:53:35.611198", "lvl": "target", "msg": "Ign:1 https://deb.debian.org/debian trixie InRelease"}
- {"dt": "2024-09-20T10:53:39.621565", "lvl": "target", "msg": "Err:1 https://deb.debian.org/debian trixie InRelease"}
- {"dt": "2024-09-20T10:53:39.621796", "lvl": "target", "msg": "  Temporary failure resolving 'deb.debian.org'"}
- {"dt": "2024-09-20T10:53:39.828181", "lvl": "target", "msg": "Reading package lists..."}
- {"dt": "2024-09-20T10:53:39.911446", "lvl": "target", "msg": "W: Failed to fetch https://deb.debian.org/debian/dists/trixie/InRelease  Temporary failure resolving 'deb.debian.org'"}
- {"dt": "2024-09-20T10:53:39.912515", "lvl": "target", "msg": "W: Some index files failed to download. They have been ignored, or old ones used instead."}
- {"dt": "2024-09-20T10:53:40.224941", "lvl": "target", "msg": "Reading package lists..."}
- {"dt": "2024-09-20T10:53:40.253970", "lvl": "target", "msg": "Building dependency tree..."}
- {"dt": "2024-09-20T10:53:40.258048", "lvl": "target", "msg": "Reading state information..."}
- {"dt": "2024-09-20T10:53:40.270209", "lvl": "target", "msg": "sed is already the newest version (4.9-2)."}
- {"dt": "2024-09-20T10:53:40.270375", "lvl": "target", "msg": "perl is already the newest version (5.38.2-5)."}
- {"dt": "2024-09-20T10:53:40.270729", "lvl": "target", "msg": "wget is already the newest version (1.24.5-2)."}
- {"dt": "2024-09-20T10:53:40.270853", "lvl": "target", "msg": "xz-utils is already the newest version (5.6.2-2)."}
- {"dt": "2024-09-20T10:53:40.270952", "lvl": "target", "msg": "iproute2 is already the newest version (6.10.0-2)."}
- {"dt": "2024-09-20T10:53:40.271069", "lvl": "target", "msg": "python3-tap is already the newest version (3.1-1)."}
- {"dt": "2024-09-20T10:53:40.318073", "lvl": "target", "msg": "0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded."}
- {"dt": "2024-09-20T10:53:40.389280", "lvl": "target", "msg": "kselftests found on rootfs"}
- {"dt": "2024-09-20T10:53:40.469852", "lvl": "target", "msg": "skiplist:"}
- {"dt": "2024-09-20T10:53:40.469974", "lvl": "target", "msg": "========================================"}
- {"dt": "2024-09-20T10:53:40.470409", "lvl": "target", "msg": "bpf:test_btf"}
- {"dt": "2024-09-20T10:53:40.565276", "lvl": "target", "msg": "bpf:test_progs"}
- {"dt": "2024-09-20T10:53:40.639177", "lvl": "target", "msg": "breakpoints:breakpoint_test"}
- {"dt": "2024-09-20T10:53:40.711038", "lvl": "target", "msg": "breakpoints:breakpoint_test_arm64"}
- {"dt": "2024-09-20T10:53:40.786304", "lvl": "target", "msg": "breakpoints:step_after_suspend_test"}
- {"dt": "2024-09-20T10:53:40.859601", "lvl": "target", "msg": "kmod:test_kmod.sh"}
- {"dt": "2024-09-20T10:53:40.930542", "lvl": "target", "msg": "kvm:get-reg-list"}
- {"dt": "2024-09-20T10:53:41.005359", "lvl": "target", "msg": "kvm:kvm_create_max_vcpus"}
- {"dt": "2024-09-20T10:53:41.081489", "lvl": "target", "msg": "mqueue:mq_open_tests"}
- {"dt": "2024-09-20T10:53:41.156525", "lvl": "target", "msg": "mqueue:mq_perf_tests"}
- {"dt": "2024-09-20T10:53:41.233350", "lvl": "target", "msg": "net:fib_nexthops.sh"}
- {"dt": "2024-09-20T10:53:41.308442", "lvl": "target", "msg": "net:msg_zerocopy.sh"}
- {"dt": "2024-09-20T10:53:41.383215", "lvl": "target", "msg": "net:rtnetlink.sh"}
- {"dt": "2024-09-20T10:53:41.458223", "lvl": "target", "msg": "net:run_afpackettests"}
- {"dt": "2024-09-20T10:53:41.531343", "lvl": "target", "msg": "net:tls"}
- {"dt": "2024-09-20T10:53:41.605470", "lvl": "target", "msg": "net:udpgro.sh"}
- {"dt": "2024-09-20T10:53:41.678553", "lvl": "target", "msg": "net:xfrm_policy.sh"}
- {"dt": "2024-09-20T10:53:41.752673", "lvl": "target", "msg": "netfilter:bridge_brouter.sh"}
- {"dt": "2024-09-20T10:53:41.824026", "lvl": "target", "msg": "netfilter:conntrack_icmp_related.sh"}
- {"dt": "2024-09-20T10:53:41.895446", "lvl": "target", "msg": "netfilter:nft_flowtable.sh"}
- {"dt": "2024-09-20T10:53:41.967322", "lvl": "target", "msg": "netfilter:nft_nat.sh"}
- {"dt": "2024-09-20T10:53:42.040356", "lvl": "target", "msg": "netfilter:nft_trans_stress.sh"}
- {"dt": "2024-09-20T10:53:42.112380", "lvl": "target", "msg": "pidfd:pidfd_wait"}
- {"dt": "2024-09-20T10:53:42.187263", "lvl": "target", "msg": "proc:proc-pid-vm"}
- {"dt": "2024-09-20T10:53:42.262211", "lvl": "target", "msg": "ptrace:vmaccess"}
- {"dt": "2024-09-20T10:53:42.336450", "lvl": "target", "msg": "rseq:run_param_test.sh"}
- {"dt": "2024-09-20T10:53:42.410733", "lvl": "target", "msg": "sync:sync_test"}
- {"dt": "2024-09-20T10:53:42.485080", "lvl": "target", "msg": "vm:run_vmtests"}
- {"dt": "2024-09-20T10:53:42.556436", "lvl": "target", "msg": "zram:zram.sh"}
- {"dt": "2024-09-20T10:53:42.629595", "lvl": "target", "msg": "========================================"}
- {"dt": "2024-09-20T10:53:42.750490", "lvl": "target", "msg": "dt:test_unprobed_devices.sh"}
- {"dt": "2024-09-20T10:53:42.788421", "lvl": "target", "msg": "============== Tests to run ==============="}
- {"dt": "2024-09-20T10:53:42.830118", "lvl": "target", "msg": "dt:test_unprobed_devices.sh"}
- {"dt": "2024-09-20T10:53:42.833051", "lvl": "target", "msg": "===========End Tests to run ==============="}
- {"dt": "2024-09-20T10:53:42.854297", "lvl": "target", "msg": "shardfile-dt pass"}
- {"dt": "2024-09-20T10:53:43.218990", "lvl": "target", "msg": "<12>[   27.090394] kselftest: Running tests in dt"}
- {"dt": "2024-09-20T10:53:43.259893", "lvl": "target", "msg": "TAP version 13"}
- {"dt": "2024-09-20T10:53:43.285341", "lvl": "target", "msg": "1..1"}
- {"dt": "2024-09-20T10:53:43.365887", "lvl": "target", "msg": "# timeout set to 45"}
- {"dt": "2024-09-20T10:53:43.366218", "lvl": "target", "msg": "# selftests: dt: test_unprobed_devices.sh"}
- {"dt": "2024-09-20T10:53:43.680437", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2024-09-20T10:53:47.385899", "lvl": "target", "msg": "# 1..49"}
- {"dt": "2024-09-20T10:53:47.567543", "lvl": "target", "msg": "# ok 1 / # SKIP"}
- {"dt": "2024-09-20T10:53:47.739615", "lvl": "target", "msg": "# ok 2 /apb-pclk # SKIP"}
- {"dt": "2024-09-20T10:53:47.890230", "lvl": "target", "msg": "# ok 3 /cpus/cpu@0 # SKIP"}
- {"dt": "2024-09-20T10:53:48.075725", "lvl": "target", "msg": "# ok 4 /cpus/cpu@1 # SKIP"}
- {"dt": "2024-09-20T10:53:48.120750", "lvl": "target", "msg": "# ok 5 /flash@0"}
- {"dt": "2024-09-20T10:53:48.282991", "lvl": "target", "msg": "# not ok 6 /fw-cfg@9020000"}
- {"dt": "2024-09-20T10:53:48.328296", "lvl": "target", "msg": "# ok 7 /gpio-keys"}
- {"dt": "2024-09-20T10:53:48.489297", "lvl": "target", "msg": "# ok 8 /intc@8000000 # SKIP"}
- {"dt": "2024-09-20T10:53:48.655088", "lvl": "target", "msg": "# ok 9 /intc@8000000/its@8080000 # SKIP"}
- {"dt": "2024-09-20T10:53:48.701427", "lvl": "target", "msg": "# ok 10 /pcie@10000000"}
- {"dt": "2024-09-20T10:53:48.770459", "lvl": "target", "msg": "# ok 11 /pl011@9000000"}
- {"dt": "2024-09-20T10:53:48.809888", "lvl": "target", "msg": "# ok 12 /pl031@9010000"}
- {"dt": "2024-09-20T10:53:48.869290", "lvl": "target", "msg": "# ok 13 /pl061@9030000"}
- {"dt": "2024-09-20T10:53:49.102805", "lvl": "target", "msg": "# not ok 14 /platform-bus@c000000"}
- {"dt": "2024-09-20T10:53:49.169866", "lvl": "target", "msg": "# ok 15 /pmu"}
- {"dt": "2024-09-20T10:53:49.220612", "lvl": "target", "msg": "# ok 16 /psci"}
- {"dt": "2024-09-20T10:53:49.455068", "lvl": "target", "msg": "# ok 17 /timer # SKIP"}
- {"dt": "2024-09-20T10:53:49.643611", "lvl": "target", "msg": "# not ok 18 /virtio_mmio@a000000"}
- {"dt": "2024-09-20T10:53:49.837074", "lvl": "target", "msg": "# not ok 19 /virtio_mmio@a000200"}
- {"dt": "2024-09-20T10:53:50.007617", "lvl": "target", "msg": "# not ok 20 /virtio_mmio@a000400"}
- {"dt": "2024-09-20T10:53:50.198332", "lvl": "target", "msg": "# not ok 21 /virtio_mmio@a000600"}
- {"dt": "2024-09-20T10:53:50.388943", "lvl": "target", "msg": "# not ok 22 /virtio_mmio@a000800"}
- {"dt": "2024-09-20T10:53:50.550732", "lvl": "target", "msg": "# not ok 23 /virtio_mmio@a000a00"}
- {"dt": "2024-09-20T10:53:50.754340", "lvl": "target", "msg": "# not ok 24 /virtio_mmio@a000c00"}
- {"dt": "2024-09-20T10:53:50.890487", "lvl": "target", "msg": "# not ok 25 /virtio_mmio@a000e00"}
- {"dt": "2024-09-20T10:53:51.024743", "lvl": "target", "msg": "# not ok 26 /virtio_mmio@a001000"}
- {"dt": "2024-09-20T10:53:51.201818", "lvl": "target", "msg": "# not ok 27 /virtio_mmio@a001200"}
- {"dt": "2024-09-20T10:53:51.422492", "lvl": "target", "msg": "# not ok 28 /virtio_mmio@a001400"}
- {"dt": "2024-09-20T10:53:51.656163", "lvl": "target", "msg": "# not ok 29 /virtio_mmio@a001600"}
- {"dt": "2024-09-20T10:53:51.867085", "lvl": "target", "msg": "# not ok 30 /virtio_mmio@a001800"}
- {"dt": "2024-09-20T10:53:52.077188", "lvl": "target", "msg": "# not ok 31 /virtio_mmio@a001a00"}
- {"dt": "2024-09-20T10:53:52.249739", "lvl": "target", "msg": "# not ok 32 /virtio_mmio@a001c00"}
- {"dt": "2024-09-20T10:53:52.416713", "lvl": "target", "msg": "# not ok 33 /virtio_mmio@a001e00"}
- {"dt": "2024-09-20T10:53:52.582061", "lvl": "target", "msg": "# not ok 34 /virtio_mmio@a002000"}
- {"dt": "2024-09-20T10:53:52.777633", "lvl": "target", "msg": "# not ok 35 /virtio_mmio@a002200"}
- {"dt": "2024-09-20T10:53:52.954663", "lvl": "target", "msg": "# not ok 36 /virtio_mmio@a002400"}
- {"dt": "2024-09-20T10:53:53.180923", "lvl": "target", "msg": "# not ok 37 /virtio_mmio@a002600"}
- {"dt": "2024-09-20T10:53:53.347238", "lvl": "target", "msg": "# not ok 38 /virtio_mmio@a002800"}
- {"dt": "2024-09-20T10:53:53.549253", "lvl": "target", "msg": "# not ok 39 /virtio_mmio@a002a00"}
- {"dt": "2024-09-20T10:53:53.738823", "lvl": "target", "msg": "# not ok 40 /virtio_mmio@a002c00"}
- {"dt": "2024-09-20T10:53:53.930587", "lvl": "target", "msg": "# not ok 41 /virtio_mmio@a002e00"}
- {"dt": "2024-09-20T10:53:54.121859", "lvl": "target", "msg": "# not ok 42 /virtio_mmio@a003000"}
- {"dt": "2024-09-20T10:53:54.319297", "lvl": "target", "msg": "# not ok 43 /virtio_mmio@a003200"}
- {"dt": "2024-09-20T10:53:54.514364", "lvl": "target", "msg": "# not ok 44 /virtio_mmio@a003400"}
- {"dt": "2024-09-20T10:53:54.707334", "lvl": "target", "msg": "# not ok 45 /virtio_mmio@a003600"}
- {"dt": "2024-09-20T10:53:54.914900", "lvl": "target", "msg": "# not ok 46 /virtio_mmio@a003800"}
- {"dt": "2024-09-20T10:53:55.104599", "lvl": "target", "msg": "# not ok 47 /virtio_mmio@a003a00"}
- {"dt": "2024-09-20T10:53:55.272634", "lvl": "target", "msg": "# not ok 48 /virtio_mmio@a003c00"}
- {"dt": "2024-09-20T10:53:55.315813", "lvl": "target", "msg": "# ok 49 /virtio_mmio@a003e00"}
- {"dt": "2024-09-20T10:53:55.317630", "lvl": "target", "msg": "# # Totals: pass:9 fail:33 xfail:0 xpass:0 skip:7 error:0"}
- {"dt": "2024-09-20T10:53:55.361466", "lvl": "target", "msg": "not ok 1 selftests: dt: test_unprobed_devices.sh # exit=1"}
- {"dt": "2024-09-20T10:53:56.264417", "lvl": "target", "msg": ""}
- {"dt": "2024-09-20T10:53:56.264617", "lvl": "target", "msg": "WARNING: Optional imports not found, TAP 13 output will be"}
- {"dt": "2024-09-20T10:53:56.264962", "lvl": "target", "msg": "    ignored. To parse yaml, see requirements in docs:"}
- {"dt": "2024-09-20T10:53:56.265140", "lvl": "target", "msg": "    https://tappy.readthedocs.io/en/latest/consumers.html#tap-version-13"}
- {"dt": "2024-09-20T10:53:56.265234", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_ skip"}
- {"dt": "2024-09-20T10:53:56.265344", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_apb-pclk skip"}
- {"dt": "2024-09-20T10:53:56.265428", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_cpus_cpu_0 skip"}
- {"dt": "2024-09-20T10:53:56.265534", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_cpus_cpu_1 skip"}
- {"dt": "2024-09-20T10:53:56.265674", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_flash_0 pass"}
- {"dt": "2024-09-20T10:53:56.265875", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_fw-cfg_9020000 fail"}
- {"dt": "2024-09-20T10:53:56.266058", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_gpio-keys pass"}
- {"dt": "2024-09-20T10:53:56.266246", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_intc_8000000 skip"}
- {"dt": "2024-09-20T10:53:56.266492", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_intc_8000000_its_8080000 skip"}
- {"dt": "2024-09-20T10:53:56.266691", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_pcie_10000000 pass"}
- {"dt": "2024-09-20T10:53:56.266889", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_pl011_9000000 pass"}
- {"dt": "2024-09-20T10:53:56.267096", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_pl031_9010000 pass"}
- {"dt": "2024-09-20T10:53:56.267296", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_pl061_9030000 pass"}
- {"dt": "2024-09-20T10:53:56.267532", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_platform-bus_c000000 fail"}
- {"dt": "2024-09-20T10:53:56.267776", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_pmu pass"}
- {"dt": "2024-09-20T10:53:56.267994", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_psci pass"}
- {"dt": "2024-09-20T10:53:56.268209", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_timer skip"}
- {"dt": "2024-09-20T10:53:56.268457", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a000000 fail"}
- {"dt": "2024-09-20T10:53:56.268677", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a000200 fail"}
- {"dt": "2024-09-20T10:53:56.268919", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a000400 fail"}
- {"dt": "2024-09-20T10:53:56.269169", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a000600 fail"}
- {"dt": "2024-09-20T10:53:56.269403", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a000800 fail"}
- {"dt": "2024-09-20T10:53:56.346566", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a000a00 fail"}
- {"dt": "2024-09-20T10:53:56.346916", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a000c00 fail"}
- {"dt": "2024-09-20T10:53:56.347142", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a000e00 fail"}
- {"dt": "2024-09-20T10:53:56.347467", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a001000 fail"}
- {"dt": "2024-09-20T10:53:56.347776", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a001200 fail"}
- {"dt": "2024-09-20T10:53:56.348167", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a001400 fail"}
- {"dt": "2024-09-20T10:53:56.348584", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a001600 fail"}
- {"dt": "2024-09-20T10:53:56.349032", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a001800 fail"}
- {"dt": "2024-09-20T10:53:56.349475", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a001a00 fail"}
- {"dt": "2024-09-20T10:53:56.349932", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a001c00 fail"}
- {"dt": "2024-09-20T10:53:56.350363", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a001e00 fail"}
- {"dt": "2024-09-20T10:53:56.350818", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a002000 fail"}
- {"dt": "2024-09-20T10:53:56.351256", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a002200 fail"}
- {"dt": "2024-09-20T10:53:56.351774", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a002400 fail"}
- {"dt": "2024-09-20T10:53:56.352179", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a002600 fail"}
- {"dt": "2024-09-20T10:53:56.352320", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a002800 fail"}
- {"dt": "2024-09-20T10:53:56.352589", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a002a00 fail"}
- {"dt": "2024-09-20T10:53:56.352811", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a002c00 fail"}
- {"dt": "2024-09-20T10:53:56.353065", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a002e00 fail"}
- {"dt": "2024-09-20T10:53:56.353266", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a003000 fail"}
- {"dt": "2024-09-20T10:53:56.353489", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a003200 fail"}
- {"dt": "2024-09-20T10:53:56.353724", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a003400 fail"}
- {"dt": "2024-09-20T10:53:56.353945", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a003600 fail"}
- {"dt": "2024-09-20T10:53:56.354308", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a003800 fail"}
- {"dt": "2024-09-20T10:53:56.354410", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a003a00 fail"}
- {"dt": "2024-09-20T10:53:56.354621", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a003c00 fail"}
- {"dt": "2024-09-20T10:53:56.354849", "lvl": "target", "msg": "dt_test_unprobed_devices_sh_virtio_mmio_a003e00 pass"}
- {"dt": "2024-09-20T10:53:56.354983", "lvl": "target", "msg": "dt_test_unprobed_devices_sh fail"}
- {"dt": "2024-09-20T10:53:56.357475", "lvl": "target", "msg": "+ ../../utils/send-to-lava.sh ./output/result.txt"}
- {"dt": "2024-09-20T10:53:56.526312", "lvl": "target", "msg": "<8>[   40.395584] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=breakpoints:breakpoint_test_arm64 RESULT=skip>"}
- {"dt": "2024-09-20T10:53:56.526720", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=breakpoints:breakpoint_test_arm64 RESULT=skip"}
- {"dt": "2024-09-20T10:53:56.526886", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "breakpoints:breakpoint_test_arm64", "result": "skip", "starttc": ! "971", "endtc": ! "971"}}
- {"dt": "2024-09-20T10:53:56.669122", "lvl": "target", "msg": "<8>[   40.540366] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=breakpoints:step_after_suspend_test RESULT=skip>"}
- {"dt": "2024-09-20T10:53:56.669482", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=breakpoints:step_after_suspend_test RESULT=skip"}
- {"dt": "2024-09-20T10:53:56.669615", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "breakpoints:step_after_suspend_test", "result": "skip", "starttc": ! "974", "endtc": ! "974"}}
- {"dt": "2024-09-20T10:53:56.820084", "lvl": "target", "msg": "<8>[   40.691179] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=kvm:get-reg-list RESULT=skip>"}
- {"dt": "2024-09-20T10:53:56.820461", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=kvm:get-reg-list RESULT=skip"}
- {"dt": "2024-09-20T10:53:56.820609", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "kvm:get-reg-list", "result": "skip", "starttc": ! "977", "endtc": ! "977"}}
- {"dt": "2024-09-20T10:53:56.973675", "lvl": "target", "msg": "<8>[   40.844935] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=kvm:kvm_create_max_vcpus RESULT=skip>"}
- {"dt": "2024-09-20T10:53:56.974019", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=kvm:kvm_create_max_vcpus RESULT=skip"}
- {"dt": "2024-09-20T10:53:56.974144", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "kvm:kvm_create_max_vcpus", "result": "skip", "starttc": ! "980", "endtc": ! "980"}}
- {"dt": "2024-09-20T10:53:57.119753", "lvl": "target", "msg": "<8>[   40.990951] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=mqueue:mq_open_tests RESULT=skip>"}
- {"dt": "2024-09-20T10:53:57.120105", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=mqueue:mq_open_tests RESULT=skip"}
- {"dt": "2024-09-20T10:53:57.120238", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "mqueue:mq_open_tests", "result": "skip", "starttc": ! "983", "endtc": ! "983"}}
- {"dt": "2024-09-20T10:53:57.266021", "lvl": "target", "msg": "<8>[   41.137264] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=mqueue:mq_perf_tests RESULT=skip>"}
- {"dt": "2024-09-20T10:53:57.266360", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=mqueue:mq_perf_tests RESULT=skip"}
- {"dt": "2024-09-20T10:53:57.266484", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "mqueue:mq_perf_tests", "result": "skip", "starttc": ! "986", "endtc": ! "986"}}
- {"dt": "2024-09-20T10:53:57.404008", "lvl": "target", "msg": "<8>[   41.275079] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:fib_nexthops.sh RESULT=skip>"}
- {"dt": "2024-09-20T10:53:57.404360", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:fib_nexthops.sh RESULT=skip"}
- {"dt": "2024-09-20T10:53:57.404493", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "net:fib_nexthops.sh", "result": "skip", "starttc": ! "989", "endtc": ! "989"}}
- {"dt": "2024-09-20T10:53:57.546542", "lvl": "target", "msg": "<8>[   41.417843] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:msg_zerocopy.sh RESULT=skip>"}
- {"dt": "2024-09-20T10:53:57.546875", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:msg_zerocopy.sh RESULT=skip"}
- {"dt": "2024-09-20T10:53:57.547010", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "net:msg_zerocopy.sh", "result": "skip", "starttc": ! "992", "endtc": ! "992"}}
- {"dt": "2024-09-20T10:53:57.684531", "lvl": "target", "msg": "<8>[   41.555684] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:rtnetlink.sh RESULT=skip>"}
- {"dt": "2024-09-20T10:53:57.684881", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:rtnetlink.sh RESULT=skip"}
- {"dt": "2024-09-20T10:53:57.685077", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "net:rtnetlink.sh", "result": "skip", "starttc": ! "995", "endtc": ! "995"}}
- {"dt": "2024-09-20T10:53:57.817550", "lvl": "target", "msg": "<8>[   41.688855] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:run_afpackettests RESULT=skip>"}
- {"dt": "2024-09-20T10:53:57.817915", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:run_afpackettests RESULT=skip"}
- {"dt": "2024-09-20T10:53:57.818051", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "net:run_afpackettests", "result": "skip", "starttc": ! "998", "endtc": ! "998"}}
- {"dt": "2024-09-20T10:53:57.948306", "lvl": "target", "msg": "<8>[   41.819625] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:tls RESULT=skip>"}
- {"dt": "2024-09-20T10:53:57.948659", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:tls RESULT=skip"}
- {"dt": "2024-09-20T10:53:57.948808", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "net:tls", "result": "skip", "starttc": ! "1001", "endtc": ! "1001"}}
- {"dt": "2024-09-20T10:53:58.083146", "lvl": "target", "msg": "<8>[   41.954467] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:udpgro.sh RESULT=skip>"}
- {"dt": "2024-09-20T10:53:58.083468", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:udpgro.sh RESULT=skip"}
- {"dt": "2024-09-20T10:53:58.083585", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "net:udpgro.sh", "result": "skip", "starttc": ! "1004", "endtc": ! "1004"}}
- {"dt": "2024-09-20T10:53:58.215835", "lvl": "target", "msg": "<8>[   42.087122] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=net:xfrm_policy.sh RESULT=skip>"}
- {"dt": "2024-09-20T10:53:58.216232", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=net:xfrm_policy.sh RESULT=skip"}
- {"dt": "2024-09-20T10:53:58.216372", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "net:xfrm_policy.sh", "result": "skip", "starttc": ! "1007", "endtc": ! "1007"}}
- {"dt": "2024-09-20T10:53:58.347083", "lvl": "target", "msg": "<8>[   42.218266] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=pidfd:pidfd_wait RESULT=skip>"}
- {"dt": "2024-09-20T10:53:58.347429", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=pidfd:pidfd_wait RESULT=skip"}
- {"dt": "2024-09-20T10:53:58.347576", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "pidfd:pidfd_wait", "result": "skip", "starttc": ! "1010", "endtc": ! "1010"}}
- {"dt": "2024-09-20T10:53:58.487258", "lvl": "target", "msg": "<8>[   42.358565] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=proc:proc-pid-vm RESULT=skip>"}
- {"dt": "2024-09-20T10:53:58.487589", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=proc:proc-pid-vm RESULT=skip"}
- {"dt": "2024-09-20T10:53:58.487709", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "proc:proc-pid-vm", "result": "skip", "starttc": ! "1013", "endtc": ! "1013"}}
- {"dt": "2024-09-20T10:53:58.619229", "lvl": "target", "msg": "<8>[   42.490492] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=ptrace:vmaccess RESULT=skip>"}
- {"dt": "2024-09-20T10:53:58.619586", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=ptrace:vmaccess RESULT=skip"}
- {"dt": "2024-09-20T10:53:58.619713", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "ptrace:vmaccess", "result": "skip", "starttc": ! "1016", "endtc": ! "1016"}}
- {"dt": "2024-09-20T10:53:58.758273", "lvl": "target", "msg": "<8>[   42.629420] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=rseq:run_param_test.sh RESULT=skip>"}
- {"dt": "2024-09-20T10:53:58.758630", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=rseq:run_param_test.sh RESULT=skip"}
- {"dt": "2024-09-20T10:53:58.758758", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "rseq:run_param_test.sh", "result": "skip", "starttc": ! "1019", "endtc": ! "1019"}}
- {"dt": "2024-09-20T10:53:58.868900", "lvl": "target", "msg": "<8>[   42.740317] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=sync:sync_test RESULT=skip>"}
- {"dt": "2024-09-20T10:53:58.869278", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=sync:sync_test RESULT=skip"}
- {"dt": "2024-09-20T10:53:58.869396", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "sync:sync_test", "result": "skip", "starttc": ! "1022", "endtc": ! "1022"}}
- {"dt": "2024-09-20T10:53:58.989016", "lvl": "target", "msg": "<8>[   42.860300] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=zram:zram.sh RESULT=skip>"}
- {"dt": "2024-09-20T10:53:58.989346", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=zram:zram.sh RESULT=skip"}
- {"dt": "2024-09-20T10:53:58.989463", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "zram:zram.sh", "result": "skip", "starttc": ! "1025", "endtc": ! "1025"}}
- {"dt": "2024-09-20T10:53:59.114294", "lvl": "target", "msg": "<8>[   42.985599] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=shardfile-dt RESULT=pass>"}
- {"dt": "2024-09-20T10:53:59.114651", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=shardfile-dt RESULT=pass"}
- {"dt": "2024-09-20T10:53:59.114794", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "shardfile-dt", "result": "pass", "starttc": ! "1028", "endtc": ! "1028"}}
- {"dt": "2024-09-20T10:53:59.781049", "lvl": "target", "msg": "<8>[   43.652312] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_ RESULT=skip>"}
- {"dt": "2024-09-20T10:53:59.781473", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_ RESULT=skip"}
- {"dt": "2024-09-20T10:53:59.781612", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_", "result": "skip", "starttc": ! "1031", "endtc": ! "1031"}}
- {"dt": "2024-09-20T10:53:59.923051", "lvl": "target", "msg": "<8>[   43.794306] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_apb-pclk RESULT=skip>"}
- {"dt": "2024-09-20T10:53:59.923418", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_apb-pclk RESULT=skip"}
- {"dt": "2024-09-20T10:53:59.923570", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_apb-pclk", "result": "skip", "starttc": ! "1034", "endtc": ! "1034"}}
- {"dt": "2024-09-20T10:54:00.061126", "lvl": "target", "msg": "<8>[   43.932473] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_cpus_cpu_0 RESULT=skip>"}
- {"dt": "2024-09-20T10:54:00.061490", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_cpus_cpu_0 RESULT=skip"}
- {"dt": "2024-09-20T10:54:00.061636", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_cpus_cpu_0", "result": "skip", "starttc": ! "1037", "endtc": ! "1037"}}
- {"dt": "2024-09-20T10:54:00.195257", "lvl": "target", "msg": "<8>[   44.066578] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_cpus_cpu_1 RESULT=skip>"}
- {"dt": "2024-09-20T10:54:00.195616", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_cpus_cpu_1 RESULT=skip"}
- {"dt": "2024-09-20T10:54:00.195750", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_cpus_cpu_1", "result": "skip", "starttc": ! "1040", "endtc": ! "1040"}}
- {"dt": "2024-09-20T10:54:00.322634", "lvl": "target", "msg": "<8>[   44.193838] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_flash_0 RESULT=pass>"}
- {"dt": "2024-09-20T10:54:00.322964", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_flash_0 RESULT=pass"}
- {"dt": "2024-09-20T10:54:00.323086", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_flash_0", "result": "pass", "starttc": ! "1043", "endtc": ! "1043"}}
- {"dt": "2024-09-20T10:54:00.451444", "lvl": "target", "msg": "<8>[   44.322629] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_fw-cfg_9020000 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:00.451793", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_fw-cfg_9020000 RESULT=fail"}
- {"dt": "2024-09-20T10:54:00.451927", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_fw-cfg_9020000", "result": "fail", "starttc": ! "1046", "endtc": ! "1046"}}
- {"dt": "2024-09-20T10:54:00.584708", "lvl": "target", "msg": "<8>[   44.456035] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_gpio-keys RESULT=pass>"}
- {"dt": "2024-09-20T10:54:00.585028", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_gpio-keys RESULT=pass"}
- {"dt": "2024-09-20T10:54:00.585173", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_gpio-keys", "result": "pass", "starttc": ! "1049", "endtc": ! "1049"}}
- {"dt": "2024-09-20T10:54:00.708085", "lvl": "target", "msg": "<8>[   44.579252] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_intc_8000000 RESULT=skip>"}
- {"dt": "2024-09-20T10:54:00.708438", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_intc_8000000 RESULT=skip"}
- {"dt": "2024-09-20T10:54:00.708575", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_intc_8000000", "result": "skip", "starttc": ! "1052", "endtc": ! "1052"}}
- {"dt": "2024-09-20T10:54:00.839348", "lvl": "target", "msg": "<8>[   44.710521] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_intc_8000000_its_8080000 RESULT=skip>"}
- {"dt": "2024-09-20T10:54:00.839701", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_intc_8000000_its_8080000 RESULT=skip"}
- {"dt": "2024-09-20T10:54:00.839838", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_intc_8000000_its_8080000", "result": "skip", "starttc": ! "1055", "endtc": ! "1055"}}
- {"dt": "2024-09-20T10:54:00.971483", "lvl": "target", "msg": "<8>[   44.842627] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_pcie_10000000 RESULT=pass>"}
- {"dt": "2024-09-20T10:54:00.971811", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_pcie_10000000 RESULT=pass"}
- {"dt": "2024-09-20T10:54:00.971931", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_pcie_10000000", "result": "pass", "starttc": ! "1058", "endtc": ! "1058"}}
- {"dt": "2024-09-20T10:54:01.107564", "lvl": "target", "msg": "<8>[   44.978948] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_pl011_9000000 RESULT=pass>"}
- {"dt": "2024-09-20T10:54:01.107888", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_pl011_9000000 RESULT=pass"}
- {"dt": "2024-09-20T10:54:01.107967", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_pl011_9000000", "result": "pass", "starttc": ! "1061", "endtc": ! "1061"}}
- {"dt": "2024-09-20T10:54:01.214011", "lvl": "target", "msg": "<8>[   45.085450] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_pl031_9010000 RESULT=pass>"}
- {"dt": "2024-09-20T10:54:01.214338", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_pl031_9010000 RESULT=pass"}
- {"dt": "2024-09-20T10:54:01.214421", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_pl031_9010000", "result": "pass", "starttc": ! "1064", "endtc": ! "1064"}}
- {"dt": "2024-09-20T10:54:01.340666", "lvl": "target", "msg": "<8>[   45.212116] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_pl061_9030000 RESULT=pass>"}
- {"dt": "2024-09-20T10:54:01.340968", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_pl061_9030000 RESULT=pass"}
- {"dt": "2024-09-20T10:54:01.341063", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_pl061_9030000", "result": "pass", "starttc": ! "1067", "endtc": ! "1067"}}
- {"dt": "2024-09-20T10:54:01.474483", "lvl": "target", "msg": "<8>[   45.345645] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_platform-bus_c000000 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:01.474777", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_platform-bus_c000000 RESULT=fail"}
- {"dt": "2024-09-20T10:54:01.474852", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_platform-bus_c000000", "result": "fail", "starttc": ! "1070", "endtc": ! "1070"}}
- {"dt": "2024-09-20T10:54:01.588944", "lvl": "target", "msg": "<8>[   45.460386] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_pmu RESULT=pass>"}
- {"dt": "2024-09-20T10:54:01.589279", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_pmu RESULT=pass"}
- {"dt": "2024-09-20T10:54:01.589363", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_pmu", "result": "pass", "starttc": ! "1073", "endtc": ! "1073"}}
- {"dt": "2024-09-20T10:54:01.688481", "lvl": "target", "msg": "<8>[   45.559934] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_psci RESULT=pass>"}
- {"dt": "2024-09-20T10:54:01.688782", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_psci RESULT=pass"}
- {"dt": "2024-09-20T10:54:01.688864", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_psci", "result": "pass", "starttc": ! "1076", "endtc": ! "1076"}}
- {"dt": "2024-09-20T10:54:01.790225", "lvl": "target", "msg": "<8>[   45.661680] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_timer RESULT=skip>"}
- {"dt": "2024-09-20T10:54:01.790519", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_timer RESULT=skip"}
- {"dt": "2024-09-20T10:54:01.790601", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_timer", "result": "skip", "starttc": ! "1079", "endtc": ! "1079"}}
- {"dt": "2024-09-20T10:54:01.921516", "lvl": "target", "msg": "<8>[   45.792694] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000000 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:01.921813", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000000 RESULT=fail"}
- {"dt": "2024-09-20T10:54:01.921888", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a000000", "result": "fail", "starttc": ! "1082", "endtc": ! "1082"}}
- {"dt": "2024-09-20T10:54:02.098174", "lvl": "target", "msg": "<8>[   45.969317] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000200 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:02.098565", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000200 RESULT=fail"}
- {"dt": "2024-09-20T10:54:02.098697", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a000200", "result": "fail", "starttc": ! "1085", "endtc": ! "1085"}}
- {"dt": "2024-09-20T10:54:02.235794", "lvl": "target", "msg": "<8>[   46.106877] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000400 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:02.236179", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000400 RESULT=fail"}
- {"dt": "2024-09-20T10:54:02.236305", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a000400", "result": "fail", "starttc": ! "1088", "endtc": ! "1088"}}
- {"dt": "2024-09-20T10:54:02.366217", "lvl": "target", "msg": "<8>[   46.237640] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000600 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:02.366570", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000600 RESULT=fail"}
- {"dt": "2024-09-20T10:54:02.366698", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a000600", "result": "fail", "starttc": ! "1091", "endtc": ! "1091"}}
- {"dt": "2024-09-20T10:54:02.473138", "lvl": "target", "msg": "<8>[   46.344398] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000800 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:02.473490", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000800 RESULT=fail"}
- {"dt": "2024-09-20T10:54:02.473615", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a000800", "result": "fail", "starttc": ! "1094", "endtc": ! "1094"}}
- {"dt": "2024-09-20T10:54:02.601920", "lvl": "target", "msg": "<8>[   46.473131] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000a00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:02.602285", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000a00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:02.602408", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a000a00", "result": "fail", "starttc": ! "1097", "endtc": ! "1097"}}
- {"dt": "2024-09-20T10:54:02.728728", "lvl": "target", "msg": "<8>[   46.599944] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000c00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:02.729099", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000c00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:02.729224", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a000c00", "result": "fail", "starttc": ! "1100", "endtc": ! "1100"}}
- {"dt": "2024-09-20T10:54:02.870308", "lvl": "target", "msg": "<8>[   46.741580] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000e00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:02.870692", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a000e00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:02.870828", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a000e00", "result": "fail", "starttc": ! "1103", "endtc": ! "1103"}}
- {"dt": "2024-09-20T10:54:03.014848", "lvl": "target", "msg": "<8>[   46.885939] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001000 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:03.015237", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001000 RESULT=fail"}
- {"dt": "2024-09-20T10:54:03.015373", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a001000", "result": "fail", "starttc": ! "1106", "endtc": ! "1106"}}
- {"dt": "2024-09-20T10:54:03.171104", "lvl": "target", "msg": "<8>[   47.042208] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001200 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:03.171477", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001200 RESULT=fail"}
- {"dt": "2024-09-20T10:54:03.171613", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a001200", "result": "fail", "starttc": ! "1109", "endtc": ! "1109"}}
- {"dt": "2024-09-20T10:54:03.282548", "lvl": "target", "msg": "<8>[   47.153969] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001400 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:03.282898", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001400 RESULT=fail"}
- {"dt": "2024-09-20T10:54:03.283017", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a001400", "result": "fail", "starttc": ! "1112", "endtc": ! "1112"}}
- {"dt": "2024-09-20T10:54:03.383434", "lvl": "target", "msg": "<8>[   47.254651] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001600 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:03.383789", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001600 RESULT=fail"}
- {"dt": "2024-09-20T10:54:03.383907", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a001600", "result": "fail", "starttc": ! "1115", "endtc": ! "1115"}}
- {"dt": "2024-09-20T10:54:03.485120", "lvl": "target", "msg": "<8>[   47.356417] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001800 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:03.485478", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001800 RESULT=fail"}
- {"dt": "2024-09-20T10:54:03.485603", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a001800", "result": "fail", "starttc": ! "1118", "endtc": ! "1118"}}
- {"dt": "2024-09-20T10:54:03.614707", "lvl": "target", "msg": "<8>[   47.485921] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001a00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:03.615057", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001a00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:03.615177", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a001a00", "result": "fail", "starttc": ! "1121", "endtc": ! "1121"}}
- {"dt": "2024-09-20T10:54:03.751374", "lvl": "target", "msg": "<8>[   47.622448] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001c00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:03.751730", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001c00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:03.751864", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a001c00", "result": "fail", "starttc": ! "1124", "endtc": ! "1124"}}
- {"dt": "2024-09-20T10:54:03.893297", "lvl": "target", "msg": "<8>[   47.764382] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001e00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:03.893640", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a001e00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:03.893749", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a001e00", "result": "fail", "starttc": ! "1127", "endtc": ! "1127"}}
- {"dt": "2024-09-20T10:54:04.028241", "lvl": "target", "msg": "<8>[   47.899288] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002000 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.028573", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002000 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.028686", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a002000", "result": "fail", "starttc": ! "1130", "endtc": ! "1130"}}
- {"dt": "2024-09-20T10:54:04.160983", "lvl": "target", "msg": "<8>[   48.032075] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002200 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.161351", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002200 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.161472", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a002200", "result": "fail", "starttc": ! "1133", "endtc": ! "1133"}}
- {"dt": "2024-09-20T10:54:04.276942", "lvl": "target", "msg": "<8>[   48.148398] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002400 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.277298", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002400 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.277407", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a002400", "result": "fail", "starttc": ! "1136", "endtc": ! "1136"}}
- {"dt": "2024-09-20T10:54:04.378813", "lvl": "target", "msg": "<8>[   48.250277] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002600 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.379140", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002600 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.379248", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a002600", "result": "fail", "starttc": ! "1139", "endtc": ! "1139"}}
- {"dt": "2024-09-20T10:54:04.479207", "lvl": "target", "msg": "<8>[   48.350673] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002800 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.479540", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002800 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.479672", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a002800", "result": "fail", "starttc": ! "1142", "endtc": ! "1142"}}
- {"dt": "2024-09-20T10:54:04.579054", "lvl": "target", "msg": "<8>[   48.450522] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002a00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.579376", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002a00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.579481", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a002a00", "result": "fail", "starttc": ! "1145", "endtc": ! "1145"}}
- {"dt": "2024-09-20T10:54:04.679449", "lvl": "target", "msg": "<8>[   48.550919] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002c00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.679785", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002c00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.679899", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a002c00", "result": "fail", "starttc": ! "1148", "endtc": ! "1148"}}
- {"dt": "2024-09-20T10:54:04.779830", "lvl": "target", "msg": "<8>[   48.651015] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002e00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.780151", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a002e00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.780252", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a002e00", "result": "fail", "starttc": ! "1151", "endtc": ! "1151"}}
- {"dt": "2024-09-20T10:54:04.903776", "lvl": "target", "msg": "<8>[   48.774861] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003000 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:04.904106", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003000 RESULT=fail"}
- {"dt": "2024-09-20T10:54:04.904213", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a003000", "result": "fail", "starttc": ! "1154", "endtc": ! "1154"}}
- {"dt": "2024-09-20T10:54:05.026865", "lvl": "target", "msg": "<8>[   48.898330] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003200 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:05.027204", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003200 RESULT=fail"}
- {"dt": "2024-09-20T10:54:05.027310", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a003200", "result": "fail", "starttc": ! "1157", "endtc": ! "1157"}}
- {"dt": "2024-09-20T10:54:05.155344", "lvl": "target", "msg": "<8>[   49.026552] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003400 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:05.155682", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003400 RESULT=fail"}
- {"dt": "2024-09-20T10:54:05.155797", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a003400", "result": "fail", "starttc": ! "1160", "endtc": ! "1160"}}
- {"dt": "2024-09-20T10:54:05.285021", "lvl": "target", "msg": "<8>[   49.156129] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003600 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:05.285403", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003600 RESULT=fail"}
- {"dt": "2024-09-20T10:54:05.285536", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a003600", "result": "fail", "starttc": ! "1163", "endtc": ! "1163"}}
- {"dt": "2024-09-20T10:54:05.429260", "lvl": "target", "msg": "<8>[   49.300540] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003800 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:05.429616", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003800 RESULT=fail"}
- {"dt": "2024-09-20T10:54:05.429736", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a003800", "result": "fail", "starttc": ! "1166", "endtc": ! "1166"}}
- {"dt": "2024-09-20T10:54:05.565043", "lvl": "target", "msg": "<8>[   49.436208] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003a00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:05.565398", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003a00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:05.565526", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a003a00", "result": "fail", "starttc": ! "1169", "endtc": ! "1169"}}
- {"dt": "2024-09-20T10:54:05.713184", "lvl": "target", "msg": "<8>[   49.584263] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003c00 RESULT=fail>"}
- {"dt": "2024-09-20T10:54:05.713583", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003c00 RESULT=fail"}
- {"dt": "2024-09-20T10:54:05.713721", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a003c00", "result": "fail", "starttc": ! "1172", "endtc": ! "1172"}}
- {"dt": "2024-09-20T10:54:05.861213", "lvl": "target", "msg": "<8>[   49.732289] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003e00 RESULT=pass>"}
- {"dt": "2024-09-20T10:54:05.861624", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh_virtio_mmio_a003e00 RESULT=pass"}
- {"dt": "2024-09-20T10:54:05.861759", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh_virtio_mmio_a003e00", "result": "pass", "starttc": ! "1175", "endtc": ! "1175"}}
- {"dt": "2024-09-20T10:54:06.011121", "lvl": "target", "msg": "<8>[   49.882228] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=dt_test_unprobed_devices_sh RESULT=fail>"}
- {"dt": "2024-09-20T10:54:06.011505", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=dt_test_unprobed_devices_sh RESULT=fail"}
- {"dt": "2024-09-20T10:54:06.011641", "lvl": "results", "msg": {"definition": "0_kselftest-dt", "case": "dt_test_unprobed_devices_sh", "result": "fail", "starttc": ! "1178", "endtc": ! "1178"}}
- {"dt": "2024-09-20T10:54:06.017937", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2024-09-20T10:54:06.023775", "lvl": "target", "msg": "<8>[   49.895008] <LAVA_SIGNAL_ENDRUN 0_kselftest-dt 1_1.1.3.1>"}
- {"dt": "2024-09-20T10:54:06.024111", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_kselftest-dt 1_1.1.3.1"}
- {"dt": "2024-09-20T10:54:06.024251", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2024-09-20T10:54:06.024368", "lvl": "info", "msg": "Ending test lava.0_kselftest-dt (1_1.1.3.1), duration 34.39"}
- {"dt": "2024-09-20T10:54:06.024491", "lvl": "results", "msg": {"definition": "lava", "case": "0_kselftest-dt", "uuid": "1_1.1.3.1", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2024.07.tar.zst", "path": "automated/linux/kselftest/kselftest.yaml", "duration": "34.39", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2024-09-20T10:54:06.048936", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2024-09-20T10:54:06.049725", "lvl": "debug", "msg": "breakpoints:breakpoint_test_arm64: skip\nbreakpoints:step_after_suspend_test: skip\nkvm:get-reg-list: skip\nkvm:kvm_create_max_vcpus: skip\nmqueue:mq_open_tests: skip\nmqueue:mq_perf_tests: skip\nnet:fib_nexthops.sh: skip\nnet:msg_zerocopy.sh: skip\nnet:rtnetlink.sh: skip\nnet:run_afpackettests: skip\nnet:tls: skip\nnet:udpgro.sh: skip\nnet:xfrm_policy.sh: skip\npidfd:pidfd_wait: skip\nproc:proc-pid-vm: skip\nptrace:vmaccess: skip\nrseq:run_param_test.sh: skip\nsync:sync_test: skip\nzram:zram.sh: skip\nshardfile-dt: pass\ndt_test_unprobed_devices_sh_: skip\ndt_test_unprobed_devices_sh_apb-pclk: skip\ndt_test_unprobed_devices_sh_cpus_cpu_0: skip\ndt_test_unprobed_devices_sh_cpus_cpu_1: skip\ndt_test_unprobed_devices_sh_flash_0: pass\ndt_test_unprobed_devices_sh_fw-cfg_9020000: fail\ndt_test_unprobed_devices_sh_gpio-keys: pass\ndt_test_unprobed_devices_sh_intc_8000000: skip\ndt_test_unprobed_devices_sh_intc_8000000_its_8080000: skip\ndt_test_unprobed_devices_sh_pcie_10000000: pass\ndt_test_unprobed_devices_sh_pl011_9000000: pass\ndt_test_unprobed_devices_sh_pl031_9010000: pass\ndt_test_unprobed_devices_sh_pl061_9030000: pass\ndt_test_unprobed_devices_sh_platform-bus_c000000: fail\ndt_test_unprobed_devices_sh_pmu: pass\ndt_test_unprobed_devices_sh_psci: pass\ndt_test_unprobed_devices_sh_timer: skip\ndt_test_unprobed_devices_sh_virtio_mmio_a000000: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a000200: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a000400: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a000600: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a000800: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a000a00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a000c00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a000e00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a001000: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a001200: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a001400: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a001600: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a001800: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a001a00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a001c00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a001e00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a002000: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a002200: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a002400: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a002600: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a002800: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a002a00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a002c00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a002e00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a003000: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a003200: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a003400: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a003600: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a003800: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a003a00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a003c00: fail\ndt_test_unprobed_devices_sh_virtio_mmio_a003e00: pass\ndt_test_unprobed_devices_sh: fail\n"}
- {"dt": "2024-09-20T10:54:06.049929", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:35) [common]"}
- {"dt": "2024-09-20T10:54:06.050078", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:35) [common]"}
- {"dt": "2024-09-20T10:54:06.050225", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:54:06.050370", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2024-09-20T10:54:06.050511", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-09-20T10:54:06.050644", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2024-09-20T10:54:06.050879", "lvl": "feedback", "msg": "<LAVA_TEST_RUNNER EXIT>", "ns": "common"}
- {"dt": "2024-09-20T10:54:06.051198", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-09-20T10:54:06.076851", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-09-20T10:54:06.081016", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-09-20T10:54:07.052199", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-09-20T10:54:07.052423", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2024-09-20T10:54:07.153132", "lvl": "input", "msg": "poweroff"}
- {"dt": "2024-09-20T10:54:07.153674", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-09-20T10:54:07.153799", "lvl": "target", "msg": "[?2004hroot@2f9bfa2d0ac1:~# poweroff"}
- {"dt": "2024-09-20T10:54:07.254141", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-09-20T10:54:07.254279", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2024-09-20T10:54:07.254410", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2024-09-20T10:54:07.254570", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-09-20T10:54:07.254728", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/url-repo-action-o3qxkhav/url_repo"}
- {"dt": "2024-09-20T10:54:07.269540", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/kernel"}
- {"dt": "2024-09-20T10:54:07.279500", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs"}
- {"dt": "2024-09-20T10:54:07.390782", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs.kselftest"}
- {"dt": "2024-09-20T10:54:07.403048", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1/deployimages-kdx7r9ps/rootfs.modules"}
- {"dt": "2024-09-20T10:54:07.411780", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-0q4uu3e5-1"}
- {"dt": "2024-09-20T10:54:07.447039", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2024-09-20T10:54:07.447123", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
