- {"dt": "2025-08-26T11:56:52.856224", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2025.06.dev0075"}
- {"dt": "2025-08-26T11:56:52.856525", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2025-08-26T11:56:52.856697", "lvl": "info", "msg": "Start time: 2025-08-26 11:56:52.856684+00:00 (UTC)"}
- {"dt": "2025-08-26T11:56:52.858147", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar.zst exists"}
- {"dt": "2025-08-26T11:56:52.858760", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/594/zImage exists"}
- {"dt": "2025-08-26T11:56:52.858999", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_20250722_trixie_armhf_rootfs.ext4.xz exists"}
- {"dt": "2025-08-26T11:56:52.859210", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/594/kselftest.tar.xz exists"}
- {"dt": "2025-08-26T11:56:52.859432", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/594/modules.tar.xz exists"}
- {"dt": "2025-08-26T11:56:52.877001", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:10.0.2+ds-1, host architecture: amd64"}
- {"dt": "2025-08-26T11:56:52.877519", "lvl": "warning", "msg": "Missing image arg for url_repo"}
- {"dt": "2025-08-26T11:56:52.877724", "lvl": "warning", "msg": "Missing image arg for rootfs.kselftest"}
- {"dt": "2025-08-26T11:56:52.878023", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"}
- {"dt": "2025-08-26T11:56:52.878347", "lvl": "info", "msg": "validate duration: 0.02"}
- {"dt": "2025-08-26T11:56:52.878514", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2025-08-26T11:56:52.878798", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:15:00) [common]"}
- {"dt": "2025-08-26T11:56:52.878985", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-08-26T11:56:52.879398", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c"}
- {"dt": "2025-08-26T11:56:52.879806", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin"}
- {"dt": "2025-08-26T11:56:52.880050", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/tests"}
- {"dt": "2025-08-26T11:56:52.880277", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/results"}
- {"dt": "2025-08-26T11:56:52.880481", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-add-keys"}
- {"dt": "2025-08-26T11:56:52.880775", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-add-sources"}
- {"dt": "2025-08-26T11:56:52.881023", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-background-process-start"}
- {"dt": "2025-08-26T11:56:52.881264", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-background-process-stop"}
- {"dt": "2025-08-26T11:56:52.881515", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-common-functions"}
- {"dt": "2025-08-26T11:56:52.881741", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2025-08-26T11:56:52.881977", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-install-packages"}
- {"dt": "2025-08-26T11:56:52.882187", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-installed-packages"}
- {"dt": "2025-08-26T11:56:52.882396", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-os-build"}
- {"dt": "2025-08-26T11:56:52.882599", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-probe-channel"}
- {"dt": "2025-08-26T11:56:52.882800", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-probe-ip"}
- {"dt": "2025-08-26T11:56:52.883006", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-target-ip"}
- {"dt": "2025-08-26T11:56:52.883212", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-target-mac"}
- {"dt": "2025-08-26T11:56:52.883418", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-target-storage"}
- {"dt": "2025-08-26T11:56:52.883628", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-test-case"}
- {"dt": "2025-08-26T11:56:52.883838", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-test-event"}
- {"dt": "2025-08-26T11:56:52.884050", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-test-feedback"}
- {"dt": "2025-08-26T11:56:52.884261", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-test-raise"}
- {"dt": "2025-08-26T11:56:52.884474", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-test-reference"}
- {"dt": "2025-08-26T11:56:52.884709", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-test-runner"}
- {"dt": "2025-08-26T11:56:52.884967", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-test-set"}
- {"dt": "2025-08-26T11:56:52.885203", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-test-shell"}
- {"dt": "2025-08-26T11:56:52.885453", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2025-08-26T11:56:52.885752", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2025-08-26T11:56:52.885980", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/environment"}
- {"dt": "2025-08-26T11:56:52.886178", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2025-08-26T11:56:52.886309", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2025-08-26T11:56:52.886430", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2025-08-26T11:56:52.886560", "lvl": "debug", "msg": "- LAVA_DISPATCHER_PREFIX=tuxrun-quizq7h_-"}
- {"dt": "2025-08-26T11:56:52.886789", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-08-26T11:56:52.886916", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2025-08-26T11:56:52.887071", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:56:52.887229", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-08-26T11:56:52.887300", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2025-08-26T11:56:52.887442", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:56:52.887597", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:15:00) [common]"}
- {"dt": "2025-08-26T11:56:52.887749", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2025-08-26T11:56:52.887923", "lvl": "debug", "msg": "start: 1.1.3.1 url-repo-action (timeout 00:15:00) [common]"}
- {"dt": "2025-08-26T11:56:52.888085", "lvl": "debug", "msg": "start: 1.1.3.1.1 download-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-08-26T11:56:52.888234", "lvl": "debug", "msg": "start: 1.1.3.1.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-08-26T11:56:52.888558", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar.zst"}
- {"dt": "2025-08-26T11:56:52.888694", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/url-repo-action-ac22kw9k/url_repo/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar"}
- {"dt": "2025-08-26T11:56:52.888818", "lvl": "debug", "msg": "total size: 25092386 (23\u202FMB)"}
- {"dt": "2025-08-26T11:56:52.888937", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2025-08-26T11:56:52.989664", "lvl": "debug", "msg": "progress  67\u202F% (16\u202FMB)"}
- {"dt": "2025-08-26T11:56:53.054946", "lvl": "info", "msg": "23\u202FMB downloaded in 0.17\u202Fs (144.07\u202FMB/s)"}
- {"dt": "2025-08-26T11:56:53.055553", "lvl": "debug", "msg": "end: 1.1.3.1.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:56:53.055677", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.1.3.1.1.1", "duration": "0.17", "result": "pass", "extra": {"label": "url_repo", "size": ! "25092386", "sha256sum": "1a163fcec0b7bb8413673f053754cc809869a6471025c3430de2dab1cc0aa8d7"}}}
- {"dt": "2025-08-26T11:56:53.055904", "lvl": "debug", "msg": "end: 1.1.3.1.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:56:53.056014", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2025-08-26T11:56:53.056299", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/0/tests/0_kselftest-futex"}
- {"dt": "2025-08-26T11:56:53.056473", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/url-repo-action-ac22kw9k/url_repo/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/0/tests/0_kselftest-futex"}
- {"dt": "2025-08-26T11:56:53.427590", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/0/tests/0_kselftest-futex/automated/linux/kselftest/kselftest.yaml"}
- {"dt": "2025-08-26T11:56:53.428437", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2025-08-26T11:56:53.428660", "lvl": "debug", "msg": "end: 1.1.3.1 url-repo-action (duration 00:00:01) [common]"}
- {"dt": "2025-08-26T11:56:53.428822", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:14:59) [common]"}
- {"dt": "2025-08-26T11:56:53.429952", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:56:53.430097", "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-futex", "path": "automated/linux/kselftest/kselftest.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar.zst"}}}
- {"dt": "2025-08-26T11:56:53.430347", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:14:59) [common]"}
- {"dt": "2025-08-26T11:56:53.431827", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:56:53.431971", "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-futex", "path": "automated/linux/kselftest/kselftest.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar.zst", "skipped test-install-overlay": "1_1.1.3.1"}}}
- {"dt": "2025-08-26T11:56:53.432237", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:14:59) [common]"}
- {"dt": "2025-08-26T11:56:53.433614", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/0/tests/0_kselftest-futex test_uuid 1_1.1.3.1"}
- {"dt": "2025-08-26T11:56:53.433775", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2025-08-26T11:56:53.433919", "lvl": "debug", "msg": "TST_CMDFILES='futex'"}
- {"dt": "2025-08-26T11:56:53.434050", "lvl": "debug", "msg": "KSELFTEST_PATH='/opt/kselftests/default-in-kernel'"}
- {"dt": "2025-08-26T11:56:53.434182", "lvl": "debug", "msg": "SKIPFILE='skipfile-lkft.yaml'"}
- {"dt": "2025-08-26T11:56:53.434298", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2025-08-26T11:56:53.434417", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2025-08-26T11:56:53.434530", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2025-08-26T11:56:53.434840", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:56:53.434988", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-runscript-overlay", "level": "1.1.3.4", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "kselftest-futex", "path": "automated/linux/kselftest/kselftest.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar.zst", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/0/tests/0_kselftest-futex/run.sh"}}}
- {"dt": "2025-08-26T11:56:53.435230", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2025-08-26T11:56:53.435355", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/lava-overlay-gyodn00c/lava-1/0 for stage 0"}
- {"dt": "2025-08-26T11:56:53.435550", "lvl": "debug", "msg": "- 0_kselftest-futex"}
- {"dt": "2025-08-26T11:56:53.435731", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:01) [common]"}
- {"dt": "2025-08-26T11:56:53.435886", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:14:59) [common]"}
- {"dt": "2025-08-26T11:57:01.310616", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:08) [common]"}
- {"dt": "2025-08-26T11:57:01.310941", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:08) [common]"}
- {"dt": "2025-08-26T11:57:01.311089", "lvl": "debug", "msg": "start: 1.2 deploy-device-env (timeout 00:14:52) [common]"}
- {"dt": "2025-08-26T11:57:01.311219", "lvl": "debug", "msg": "end: 1.2 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:01.311351", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 00:14:52) [common]"}
- {"dt": "2025-08-26T11:57:01.311488", "lvl": "debug", "msg": "start: 1.3.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-08-26T11:57:01.311840", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/594/zImage"}
- {"dt": "2025-08-26T11:57:01.313264", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/kernel/zImage"}
- {"dt": "2025-08-26T11:57:01.313416", "lvl": "debug", "msg": "total size: 12026368 (11\u202FMB)"}
- {"dt": "2025-08-26T11:57:01.313564", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-08-26T11:57:01.335516", "lvl": "info", "msg": "11\u202FMB downloaded in 0.02\u202Fs (519.80\u202FMB/s)"}
- {"dt": "2025-08-26T11:57:01.336074", "lvl": "debug", "msg": "end: 1.3.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:01.336349", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.3.1", "duration": "0.02", "result": "pass", "extra": {"label": "kernel", "size": ! "12026368", "sha256sum": "dcbe2c276e58a7ec84f2629c4168d7971f8d80b78d6ad1275e0e0e6b038e8f8e"}}}
- {"dt": "2025-08-26T11:57:01.336680", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:01.336888", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:14:52) [common]"}
- {"dt": "2025-08-26T11:57:01.337073", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-08-26T11:57:01.337490", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_debian_20250722_trixie_armhf_rootfs.ext4.xz"}
- {"dt": "2025-08-26T11:57:01.337642", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs/https___storage.tuxboot.com_debian_20250722_trixie_armhf_rootfs.ext4"}
- {"dt": "2025-08-26T11:57:01.337780", "lvl": "debug", "msg": "total size: 110735860 (105\u202FMB)"}
- {"dt": "2025-08-26T11:57:01.337934", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-08-26T11:57:01.438110", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2025-08-26T11:57:01.630027", "lvl": "debug", "msg": "progress   5\u202F% (5\u202FMB)"}
- {"dt": "2025-08-26T11:57:01.894336", "lvl": "debug", "msg": "progress  10\u202F% (10\u202FMB)"}
- {"dt": "2025-08-26T11:57:02.154748", "lvl": "debug", "msg": "progress  15\u202F% (15\u202FMB)"}
- {"dt": "2025-08-26T11:57:02.421888", "lvl": "debug", "msg": "progress  20\u202F% (21\u202FMB)"}
- {"dt": "2025-08-26T11:57:02.692850", "lvl": "debug", "msg": "progress  25\u202F% (26\u202FMB)"}
- {"dt": "2025-08-26T11:57:02.968784", "lvl": "debug", "msg": "progress  30\u202F% (31\u202FMB)"}
- {"dt": "2025-08-26T11:57:03.221014", "lvl": "debug", "msg": "progress  35\u202F% (36\u202FMB)"}
- {"dt": "2025-08-26T11:57:03.470814", "lvl": "debug", "msg": "progress  40\u202F% (42\u202FMB)"}
- {"dt": "2025-08-26T11:57:03.760399", "lvl": "debug", "msg": "progress  45\u202F% (47\u202FMB)"}
- {"dt": "2025-08-26T11:57:04.030392", "lvl": "debug", "msg": "progress  50\u202F% (52\u202FMB)"}
- {"dt": "2025-08-26T11:57:04.300081", "lvl": "debug", "msg": "progress  55\u202F% (58\u202FMB)"}
- {"dt": "2025-08-26T11:57:04.572887", "lvl": "debug", "msg": "progress  60\u202F% (63\u202FMB)"}
- {"dt": "2025-08-26T11:57:04.862763", "lvl": "debug", "msg": "progress  65\u202F% (68\u202FMB)"}
- {"dt": "2025-08-26T11:57:05.129267", "lvl": "debug", "msg": "progress  70\u202F% (73\u202FMB)"}
- {"dt": "2025-08-26T11:57:05.411360", "lvl": "debug", "msg": "progress  75\u202F% (79\u202FMB)"}
- {"dt": "2025-08-26T11:57:05.678799", "lvl": "debug", "msg": "progress  80\u202F% (84\u202FMB)"}
- {"dt": "2025-08-26T11:57:05.949449", "lvl": "debug", "msg": "progress  85\u202F% (89\u202FMB)"}
- {"dt": "2025-08-26T11:57:06.220062", "lvl": "debug", "msg": "progress  90\u202F% (95\u202FMB)"}
- {"dt": "2025-08-26T11:57:06.488280", "lvl": "debug", "msg": "progress  95\u202F% (100\u202FMB)"}
- {"dt": "2025-08-26T11:57:09.687928", "lvl": "debug", "msg": "progress 100\u202F% (105\u202FMB)"}
- {"dt": "2025-08-26T11:57:10.815295", "lvl": "info", "msg": "105\u202FMB downloaded in 9.48\u202Fs (11.14\u202FMB/s)"}
- {"dt": "2025-08-26T11:57:10.815846", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:09) [common]"}
- {"dt": "2025-08-26T11:57:10.816395", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.1", "duration": "9.48", "result": "pass", "extra": {"label": "rootfs", "size": ! "110735860", "sha256sum": "f7bdb5a452b06061b1127f954ff4eab6db5ca709dcb27bd0d1d3c02d18440333"}}}
- {"dt": "2025-08-26T11:57:10.825442", "lvl": "debug", "msg": "start: 1.4.2 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-08-26T11:57:10.825738", "lvl": "debug", "msg": "start: 1.4.2.1 file-download (timeout 00:01:40) [common]"}
- {"dt": "2025-08-26T11:57:10.826306", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/594/kselftest.tar.xz"}
- {"dt": "2025-08-26T11:57:10.826464", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs.kselftest/kselftest.tar"}
- {"dt": "2025-08-26T11:57:10.826581", "lvl": "debug", "msg": "total size: 4976316 (4\u202FMB)"}
- {"dt": "2025-08-26T11:57:10.826694", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-08-26T11:57:10.991468", "lvl": "info", "msg": "4\u202FMB downloaded in 0.16\u202Fs (28.79\u202FMB/s)"}
- {"dt": "2025-08-26T11:57:10.991881", "lvl": "debug", "msg": "end: 1.4.2.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:10.991995", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.2.1", "duration": "0.17", "result": "pass", "extra": {"label": "rootfs.kselftest", "size": ! "4976316", "sha256sum": "b903ddb04daadb6011acd185b4960011765f3322885e0ea14ecfee691e0f0c4b"}}}
- {"dt": "2025-08-26T11:57:10.992190", "lvl": "debug", "msg": "end: 1.4.2 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:10.992318", "lvl": "debug", "msg": "start: 1.4.3 download-retry (timeout 00:05:00) [common]"}
- {"dt": "2025-08-26T11:57:10.992431", "lvl": "debug", "msg": "start: 1.4.3.1 file-download (timeout 00:01:40) [common]"}
- {"dt": "2025-08-26T11:57:10.992708", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/594/modules.tar.xz"}
- {"dt": "2025-08-26T11:57:10.992800", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs.modules/modules.tar"}
- {"dt": "2025-08-26T11:57:10.992882", "lvl": "debug", "msg": "total size: 6737888 (6\u202FMB)"}
- {"dt": "2025-08-26T11:57:10.992960", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2025-08-26T11:57:11.283798", "lvl": "info", "msg": "6\u202FMB downloaded in 0.29\u202Fs (22.09\u202FMB/s)"}
- {"dt": "2025-08-26T11:57:11.284231", "lvl": "debug", "msg": "end: 1.4.3.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:11.284335", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.3.1", "duration": "0.29", "result": "pass", "extra": {"label": "rootfs.modules", "size": ! "6737888", "sha256sum": "e5f89e552035463e25f1935eb6ef19d9514bc7705726d6200b57402af45be13d"}}}
- {"dt": "2025-08-26T11:57:11.284540", "lvl": "debug", "msg": "end: 1.4.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:11.284667", "lvl": "debug", "msg": "start: 1.4.4 append-overlays (timeout 00:05:00) [common]"}
- {"dt": "2025-08-26T11:57:11.284771", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs/https___storage.tuxboot.com_debian_20250722_trixie_armhf_rootfs.ext4'"}
- {"dt": "2025-08-26T11:57:13.150600", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2025-08-26T11:57:13.152151", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/compress-overlay-0h5bwo4u/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2025-08-26T11:57:13.759059", "lvl": "debug", "msg": "- rootfs.kselftest: '/var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs.kselftest/kselftest.tar' to '/opt/kselftests/default-in-kernel/'"}
- {"dt": "2025-08-26T11:57:14.142221", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs.modules/modules.tar' to '/usr/'"}
- {"dt": "2025-08-26T11:57:14.922316", "lvl": "debug", "msg": "end: 1.4.4 append-overlays (duration 00:00:04) [common]"}
- {"dt": "2025-08-26T11:57:14.923072", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:14) [common]"}
- {"dt": "2025-08-26T11:57:14.923378", "lvl": "debug", "msg": "start: 1.5 apply-overlay-guest (timeout 00:14:38) [common]"}
- {"dt": "2025-08-26T11:57:14.923605", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2025-08-26T11:57:14.923796", "lvl": "debug", "msg": "end: 1.5 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:14.923982", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:22) [common]"}
- {"dt": "2025-08-26T11:57:14.924135", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:30:00) [common]"}
- {"dt": "2025-08-26T11:57:14.924297", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:30:00) [common]"}
- {"dt": "2025-08-26T11:57:14.924446", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:30:00) [common]"}
- {"dt": "2025-08-26T11:57:14.924932", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-arm -cpu cortex-a15 -machine virt,gic-version=3 -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/kernel/zImage -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-quizq7h_-1/deployimages-cymoydup/rootfs/https___storage.tuxboot.com_debian_20250722_trixie_armhf_rootfs.ext4,format=raw,id=hd0,if=virtio"}
- {"dt": "2025-08-26T11:57:14.935010", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2025-08-26T11:57:14.936316", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:14.936604", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.01", "result": "pass", "extra": {"qemu_version": "1:10.0.2+ds-1", "host_arch": "amd64", "job_arch": "arm"}}}
- {"dt": "2025-08-26T11:57:14.936989", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:57:14.937221", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:30:00) [common]"}
- {"dt": "2025-08-26T11:57:14.937456", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2025-08-26T11:57:14.937627", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:30:00)"}
- {"dt": "2025-08-26T11:57:15.530681", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0"}
- {"dt": "2025-08-26T11:57:15.531594", "lvl": "target", "msg": "[    0.000000] Linux version 6.17.0-rc3-next-20250825 (tuxmake@tuxmake) (arm-linux-gnueabihf-gcc (Debian 13.4.0-1) 13.4.0, GNU ld (GNU Binutils for Debian) 2.45) #1 SMP @1756208870"}
- {"dt": "2025-08-26T11:57:15.532191", "lvl": "target", "msg": "[    0.000000] CPU: ARMv7 Processor [414fc0f0] revision 0 (ARMv7), cr=30c5387d"}
- {"dt": "2025-08-26T11:57:15.532514", "lvl": "target", "msg": "[    0.000000] CPU: div instructions available: patching division code"}
- {"dt": "2025-08-26T11:57:15.533429", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:29:59) [common]"}
- {"dt": "2025-08-26T11:57:15.533601", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2025-08-26T11:57:15.533731", "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', 'U-Boot SPL 20[0-9][0-9]']"}
- {"dt": "2025-08-26T11:57:15.533885", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2025-08-26T11:57:15.533970", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2025-08-26T11:57:15.534076", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2025-08-26T11:57:15.534163", "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", "U-Boot SPL 20[0-9][0-9]", "root@(.*):[/~]#", "login:", "Login incorrect"]}
- {"dt": "2025-08-26T11:57:15.534332", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:29:59)"}
- {"dt": "2025-08-26T11:57:15.534442", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2025-08-26T11:57:15.535270", "lvl": "target", "msg": "[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache"}
- {"dt": "2025-08-26T11:57:15.535377", "lvl": "target", "msg": "[    0.000000] OF: fdt: Machine model: linux,dummy-virt"}
- {"dt": "2025-08-26T11:57:15.535462", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2025-08-26T11:57:15.535538", "lvl": "target", "msg": "[    0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2025-08-26T11:57:15.535639", "lvl": "target", "msg": "[    0.000000] printk: legacy bootconsole [pl11] enabled"}
- {"dt": "2025-08-26T11:57:15.538791", "lvl": "target", "msg": "[    0.000000] Memory policy: Data cache writealloc"}
- {"dt": "2025-08-26T11:57:15.539015", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2025-08-26T11:57:15.542047", "lvl": "target", "msg": "[    0.000000] cma: Reserved 64 MiB at 0x00000000fb800000"}
- {"dt": "2025-08-26T11:57:15.546251", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2025-08-26T11:57:15.547836", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000040000000-0x000000006fffffff]"}
- {"dt": "2025-08-26T11:57:15.548352", "lvl": "target", "msg": "[    0.000000]   Normal   empty"}
- {"dt": "2025-08-26T11:57:15.549270", "lvl": "target", "msg": "[    0.000000]   HighMem  [mem 0x0000000070000000-0x000000013fffffff]"}
- {"dt": "2025-08-26T11:57:15.549670", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2025-08-26T11:57:15.550185", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2025-08-26T11:57:15.551052", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-08-26T11:57:15.552086", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-08-26T11:57:15.725131", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT"}
- {"dt": "2025-08-26T11:57:15.732368", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2025-08-26T11:57:15.732934", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2025-08-26T11:57:15.733540", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2025-08-26T11:57:15.740878", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS migration not required"}
- {"dt": "2025-08-26T11:57:15.741331", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.0"}
- {"dt": "2025-08-26T11:57:15.744941", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 17 pages/cpu s40524 r8192 d20916 u69632"}
- {"dt": "2025-08-26T11:57:15.746232", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s40524 r8192 d20916 u69632 alloc=17*4096"}
- {"dt": "2025-08-26T11:57:15.746773", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2025-08-26T11:57:15.749284", "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": "2025-08-26T11:57:15.751411", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2025-08-26T11:57:15.752338", "lvl": "target", "msg": "<6>[    0.000000] printk: log buffer data + meta data: 131072 + 409600 = 540672 bytes"}
- {"dt": "2025-08-26T11:57:15.753479", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)"}
- {"dt": "2025-08-26T11:57:15.754264", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-08-26T11:57:15.754789", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2025-08-26T11:57:15.846489", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x0000000069f0e000-0x000000006df0e000] (64MB)"}
- {"dt": "2025-08-26T11:57:15.847477", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576"}
- {"dt": "2025-08-26T11:57:15.848457", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2025-08-26T11:57:15.897556", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2025-08-26T11:57:15.914700", "lvl": "target", "msg": "<6>[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2025-08-26T11:57:15.916725", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2025-08-26T11:57:15.917020", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=2."}
- {"dt": "2025-08-26T11:57:15.917335", "lvl": "target", "msg": "<6>[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2025-08-26T11:57:15.917499", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies."}
- {"dt": "2025-08-26T11:57:15.917635", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2025-08-26T11:57:15.925769", "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": "2025-08-26T11:57:15.995339", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16"}
- {"dt": "2025-08-26T11:57:16.011758", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 256 SPIs implemented"}
- {"dt": "2025-08-26T11:57:16.012048", "lvl": "target", "msg": "<6>[    0.000000] GICv3: 0 Extended SPIs implemented"}
- {"dt": "2025-08-26T11:57:16.014342", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2025-08-26T11:57:16.014737", "lvl": "target", "msg": "<6>[    0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0"}
- {"dt": "2025-08-26T11:57:16.016147", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2025-08-26T11:57:16.024442", "lvl": "target", "msg": "<6>[    0.000000] ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2025-08-26T11:57:16.026366", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @428b0000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-08-26T11:57:16.027132", "lvl": "target", "msg": "<6>[    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @428c0000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-08-26T11:57:16.028669", "lvl": "target", "msg": "<6>[    0.000000] GICv3: using LPI property table @0x00000000428d0000"}
- {"dt": "2025-08-26T11:57:16.030379", "lvl": "target", "msg": "<6>[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000000428e0000"}
- {"dt": "2025-08-26T11:57:16.033465", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2025-08-26T11:57:16.067292", "lvl": "target", "msg": "<6>[    0.000000] arch_timer: cp15 timer running at 62.50MHz (virt)."}
- {"dt": "2025-08-26T11:57:16.068351", "lvl": "target", "msg": "<6>[    0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"}
- {"dt": "2025-08-26T11:57:16.075341", "lvl": "target", "msg": "<6>[    0.001403] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"}
- {"dt": "2025-08-26T11:57:16.075961", "lvl": "target", "msg": "<6>[    0.004799] Switching to timer-based delay loop, resolution 16ns"}
- {"dt": "2025-08-26T11:57:16.088556", "lvl": "target", "msg": "<6>[    0.017119] Console: colour dummy device 80x30"}
- {"dt": "2025-08-26T11:57:16.091849", "lvl": "target", "msg": "<6>[    0.020302] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=625000)"}
- {"dt": "2025-08-26T11:57:16.093664", "lvl": "target", "msg": "<6>[    0.021193] CPU: Testing write buffer coherency: ok"}
- {"dt": "2025-08-26T11:57:16.094435", "lvl": "target", "msg": "<3>[    0.022970] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2025-08-26T11:57:16.095791", "lvl": "target", "msg": "<6>[    0.024500] CPU0: Spectre BHB: enabling loop workaround for all CPUs"}
- {"dt": "2025-08-26T11:57:16.096482", "lvl": "target", "msg": "<6>[    0.025169] pid_max: default: 32768 minimum: 301"}
- {"dt": "2025-08-26T11:57:16.108085", "lvl": "target", "msg": "<6>[    0.036647] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.108666", "lvl": "target", "msg": "<6>[    0.037380] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.152272", "lvl": "target", "msg": "<3>[    0.080549] /cpus/cpu@0 missing clock-frequency property"}
- {"dt": "2025-08-26T11:57:16.152740", "lvl": "target", "msg": "<3>[    0.081564] /cpus/cpu@1 missing clock-frequency property"}
- {"dt": "2025-08-26T11:57:16.153442", "lvl": "target", "msg": "<6>[    0.082182] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000"}
- {"dt": "2025-08-26T11:57:16.155158", "lvl": "target", "msg": "<4>[    0.083849] cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2025-08-26T11:57:16.178016", "lvl": "target", "msg": "<6>[    0.106600] Setting up static identity map for 0x40400000 - 0x40400184"}
- {"dt": "2025-08-26T11:57:16.183018", "lvl": "target", "msg": "<6>[    0.111729] rcu: Hierarchical SRCU implementation."}
- {"dt": "2025-08-26T11:57:16.183485", "lvl": "target", "msg": "<6>[    0.112250] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2025-08-26T11:57:16.186951", "lvl": "target", "msg": "<6>[    0.115649] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level"}
- {"dt": "2025-08-26T11:57:16.196857", "lvl": "target", "msg": "<6>[    0.124959] EFI services will not be available."}
- {"dt": "2025-08-26T11:57:16.203127", "lvl": "target", "msg": "<6>[    0.131796] smp: Bringing up secondary CPUs ..."}
- {"dt": "2025-08-26T11:57:16.211610", "lvl": "target", "msg": "<6>[    0.138100] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2025-08-26T11:57:16.212275", "lvl": "target", "msg": "<6>[    0.138580] GICv3: CPU1: using allocated LPI pending table @0x00000000428f0000"}
- {"dt": "2025-08-26T11:57:16.212816", "lvl": "target", "msg": "<6>[    0.139356] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001"}
- {"dt": "2025-08-26T11:57:16.213262", "lvl": "target", "msg": "<3>[    0.139895] CPU1: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"}
- {"dt": "2025-08-26T11:57:16.219013", "lvl": "target", "msg": "<6>[    0.147745] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2025-08-26T11:57:16.219351", "lvl": "target", "msg": "<6>[    0.148192] SMP: Total of 2 processors activated (250.00 BogoMIPS)."}
- {"dt": "2025-08-26T11:57:16.219731", "lvl": "target", "msg": "<6>[    0.148700] CPU: All CPU(s) started in SVC mode."}
- {"dt": "2025-08-26T11:57:16.233201", "lvl": "target", "msg": "<6>[    0.160649] Memory: 3994064K/4194304K available (18432K kernel code, 2563K rwdata, 7128K rodata, 2048K init, 400K bss, 132456K reserved, 65536K cma-reserved, 3342336K highmem)"}
- {"dt": "2025-08-26T11:57:16.255461", "lvl": "target", "msg": "<6>[    0.184164] devtmpfs: initialized"}
- {"dt": "2025-08-26T11:57:16.303280", "lvl": "target", "msg": "<6>[    0.223395] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0"}
- {"dt": "2025-08-26T11:57:16.343862", "lvl": "target", "msg": "<6>[    0.270953] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns"}
- {"dt": "2025-08-26T11:57:16.347305", "lvl": "target", "msg": "<6>[    0.275931] posixtimers hash table entries: 1024 (order: 1, 8192 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.348412", "lvl": "target", "msg": "<6>[    0.276932] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear)."}
- {"dt": "2025-08-26T11:57:16.357688", "lvl": "target", "msg": "<6>[    0.286278] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2025-08-26T11:57:16.386247", "lvl": "target", "msg": "<6>[    0.314813] DMI not present or invalid."}
- {"dt": "2025-08-26T11:57:16.398675", "lvl": "target", "msg": "<6>[    0.327064] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2025-08-26T11:57:16.418417", "lvl": "target", "msg": "<6>[    0.346926] DMA: preallocated 256 KiB pool for atomic coherent allocations"}
- {"dt": "2025-08-26T11:57:16.430361", "lvl": "target", "msg": "<6>[    0.357895] thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2025-08-26T11:57:16.430853", "lvl": "target", "msg": "<6>[    0.358743] cpuidle: using governor menu"}
- {"dt": "2025-08-26T11:57:16.432803", "lvl": "target", "msg": "<6>[    0.361678] No ATAGs?"}
- {"dt": "2025-08-26T11:57:16.436217", "lvl": "target", "msg": "<6>[    0.364203] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers."}
- {"dt": "2025-08-26T11:57:16.436944", "lvl": "target", "msg": "<6>[    0.365370] hw-breakpoint: maximum watchpoint size is 8 bytes."}
- {"dt": "2025-08-26T11:57:16.445998", "lvl": "target", "msg": "<6>[    0.374621] Serial: AMBA PL011 UART driver"}
- {"dt": "2025-08-26T11:57:16.517062", "lvl": "target", "msg": "<6>[    0.445572] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 28, base_baud = 0) is a PL011 rev1"}
- {"dt": "2025-08-26T11:57:16.521324", "lvl": "target", "msg": "<6>[    0.449211] printk: console [ttyAMA0] enabled"}
- {"dt": "2025-08-26T11:57:16.522147", "lvl": "target", "msg": "<6>[    0.449211] printk: console [ttyAMA0] enabled"}
- {"dt": "2025-08-26T11:57:16.523428", "lvl": "target", "msg": "<6>[    0.451571] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2025-08-26T11:57:16.530009", "lvl": "target", "msg": "<6>[    0.451571] printk: legacy bootconsole [pl11] disabled"}
- {"dt": "2025-08-26T11:57:16.588788", "lvl": "target", "msg": "<6>[    0.514744] iommu: Default domain type: Translated"}
- {"dt": "2025-08-26T11:57:16.590054", "lvl": "target", "msg": "<6>[    0.515843] iommu: DMA domain TLB invalidation policy: strict mode"}
- {"dt": "2025-08-26T11:57:16.594006", "lvl": "target", "msg": "<5>[    0.522241] SCSI subsystem initialized"}
- {"dt": "2025-08-26T11:57:16.606404", "lvl": "target", "msg": "<7>[    0.524850] libata version 3.00 loaded."}
- {"dt": "2025-08-26T11:57:16.606794", "lvl": "target", "msg": "<6>[    0.527694] usbcore: registered new interface driver usbfs"}
- {"dt": "2025-08-26T11:57:16.607020", "lvl": "target", "msg": "<6>[    0.528234] usbcore: registered new interface driver hub"}
- {"dt": "2025-08-26T11:57:16.610352", "lvl": "target", "msg": "<6>[    0.528645] usbcore: registered new device driver usb"}
- {"dt": "2025-08-26T11:57:16.611132", "lvl": "target", "msg": "<6>[    0.531850] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2025-08-26T11:57:16.611803", "lvl": "target", "msg": "<6>[    0.531968] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2025-08-26T11:57:16.612107", "lvl": "target", "msg": "<6>[    0.532213] PTP clock support registered"}
- {"dt": "2025-08-26T11:57:16.612353", "lvl": "target", "msg": "<6>[    0.532880] EDAC MC: Ver: 3.0.0"}
- {"dt": "2025-08-26T11:57:16.616998", "lvl": "target", "msg": "<6>[    0.542785] scmi_core: SCMI protocol bus registered"}
- {"dt": "2025-08-26T11:57:16.646933", "lvl": "target", "msg": "<6>[    0.574790] vgaarb: loaded"}
- {"dt": "2025-08-26T11:57:16.664196", "lvl": "target", "msg": "<6>[    0.590687] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2025-08-26T11:57:16.791892", "lvl": "target", "msg": "<6>[    0.679816] NET: Registered PF_INET protocol family"}
- {"dt": "2025-08-26T11:57:16.792384", "lvl": "target", "msg": "<6>[    0.681826] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.798246", "lvl": "target", "msg": "<6>[    0.708935] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.799446", "lvl": "target", "msg": "<6>[    0.709226] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.799763", "lvl": "target", "msg": "<6>[    0.709454] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.800685", "lvl": "target", "msg": "<6>[    0.709853] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.801081", "lvl": "target", "msg": "<6>[    0.710292] TCP: Hash tables configured (established 8192 bind 8192)"}
- {"dt": "2025-08-26T11:57:16.801503", "lvl": "target", "msg": "<6>[    0.716134] UDP hash table entries: 512 (order: 2, 28672 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.801918", "lvl": "target", "msg": "<6>[    0.716761] UDP-Lite hash table entries: 512 (order: 2, 28672 bytes, linear)"}
- {"dt": "2025-08-26T11:57:16.802268", "lvl": "target", "msg": "<6>[    0.719042] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2025-08-26T11:57:16.816879", "lvl": "target", "msg": "<6>[    0.736115] RPC: Registered named UNIX socket transport module."}
- {"dt": "2025-08-26T11:57:16.817249", "lvl": "target", "msg": "<6>[    0.736333] RPC: Registered udp transport module."}
- {"dt": "2025-08-26T11:57:16.817571", "lvl": "target", "msg": "<6>[    0.736419] RPC: Registered tcp transport module."}
- {"dt": "2025-08-26T11:57:16.817912", "lvl": "target", "msg": "<6>[    0.736494] RPC: Registered tcp-with-tls transport module."}
- {"dt": "2025-08-26T11:57:16.818308", "lvl": "target", "msg": "<6>[    0.736568] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2025-08-26T11:57:16.818807", "lvl": "target", "msg": "<6>[    0.736750] PCI: CLS 0 bytes, default 64"}
- {"dt": "2025-08-26T11:57:16.820336", "lvl": "target", "msg": "<5>[    0.742483] Initialise system trusted keyrings"}
- {"dt": "2025-08-26T11:57:16.835431", "lvl": "target", "msg": "<6>[    0.758596] workingset: timestamp_bits=30 max_order=20 bucket_order=0"}
- {"dt": "2025-08-26T11:57:16.836005", "lvl": "target", "msg": "<6>[    0.761799] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2025-08-26T11:57:16.841928", "lvl": "target", "msg": "<5>[    0.767171] NFS: Registering the id_resolver key type"}
- {"dt": "2025-08-26T11:57:16.842791", "lvl": "target", "msg": "<5>[    0.768177] Key type id_resolver registered"}
- {"dt": "2025-08-26T11:57:16.843689", "lvl": "target", "msg": "<5>[    0.768318] Key type id_legacy registered"}
- {"dt": "2025-08-26T11:57:16.844696", "lvl": "target", "msg": "<6>[    0.768897] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2025-08-26T11:57:16.845575", "lvl": "target", "msg": "<6>[    0.769103] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2025-08-26T11:57:16.853842", "lvl": "target", "msg": "<5>[    0.779883] Key type asymmetric registered"}
- {"dt": "2025-08-26T11:57:16.855021", "lvl": "target", "msg": "<5>[    0.780187] Asymmetric key parser 'x509' registered"}
- {"dt": "2025-08-26T11:57:16.855947", "lvl": "target", "msg": "<6>[    0.780785] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)"}
- {"dt": "2025-08-26T11:57:16.857862", "lvl": "target", "msg": "<6>[    0.781125] io scheduler mq-deadline registered"}
- {"dt": "2025-08-26T11:57:16.858052", "lvl": "target", "msg": "<6>[    0.781314] io scheduler kyber registered"}
- {"dt": "2025-08-26T11:57:16.858181", "lvl": "target", "msg": "<6>[    0.781613] io scheduler bfq registered"}
- {"dt": "2025-08-26T11:57:16.916585", "lvl": "target", "msg": "<6>[    0.833377] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"}
- {"dt": "2025-08-26T11:57:16.962075", "lvl": "target", "msg": "<6>[    0.858276] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2025-08-26T11:57:16.963112", "lvl": "target", "msg": "<6>[    0.867285] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2025-08-26T11:57:16.964176", "lvl": "target", "msg": "<6>[    0.868811] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2025-08-26T11:57:16.965136", "lvl": "target", "msg": "<6>[    0.869816] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2025-08-26T11:57:16.966360", "lvl": "target", "msg": "<6>[    0.870018] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2025-08-26T11:57:16.967695", "lvl": "target", "msg": "<4>[    0.871019] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2025-08-26T11:57:16.969138", "lvl": "target", "msg": "<6>[    0.871810] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2025-08-26T11:57:16.970124", "lvl": "target", "msg": "<6>[    0.874543] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2025-08-26T11:57:16.971338", "lvl": "target", "msg": "<6>[    0.875104] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2025-08-26T11:57:16.972379", "lvl": "target", "msg": "<6>[    0.875297] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2025-08-26T11:57:16.973496", "lvl": "target", "msg": "<6>[    0.875478] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2025-08-26T11:57:16.974868", "lvl": "target", "msg": "<6>[    0.875583] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2025-08-26T11:57:16.976120", "lvl": "target", "msg": "<6>[    0.877562] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint"}
- {"dt": "2025-08-26T11:57:16.977427", "lvl": "target", "msg": "<6>[    0.883379] pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint"}
- {"dt": "2025-08-26T11:57:16.977968", "lvl": "target", "msg": "<6>[    0.883993] pci 0000:00:01.0: BAR 0 [io  0x0000-0x007f]"}
- {"dt": "2025-08-26T11:57:16.979069", "lvl": "target", "msg": "<6>[    0.884183] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]"}
- {"dt": "2025-08-26T11:57:16.980267", "lvl": "target", "msg": "<6>[    0.884316] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2025-08-26T11:57:16.980915", "lvl": "target", "msg": "<6>[    0.886708] PCI: bus0: Fast back to back transfers disabled"}
- {"dt": "2025-08-26T11:57:16.981933", "lvl": "target", "msg": "<6>[    0.890272] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned"}
- {"dt": "2025-08-26T11:57:16.982452", "lvl": "target", "msg": "<6>[    0.900106] pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned"}
- {"dt": "2025-08-26T11:57:16.983125", "lvl": "target", "msg": "<6>[    0.901872] pci 0000:00:01.0: BAR 0 [io  0x1000-0x107f]: assigned"}
- {"dt": "2025-08-26T11:57:16.983447", "lvl": "target", "msg": "<6>[    0.902571] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]"}
- {"dt": "2025-08-26T11:57:16.983955", "lvl": "target", "msg": "<6>[    0.903925] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]"}
- {"dt": "2025-08-26T11:57:16.984350", "lvl": "target", "msg": "<6>[    0.904388] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2025-08-26T11:57:17.032649", "lvl": "target", "msg": "<6>[    0.950759] virtio-pci 0000:00:01.0: enabling device (0100 -> 0103)"}
- {"dt": "2025-08-26T11:57:17.205004", "lvl": "target", "msg": "<6>[    1.129749] Serial: 8250/16550 driver, 5 ports, IRQ sharing enabled"}
- {"dt": "2025-08-26T11:57:17.217426", "lvl": "target", "msg": "<6>[    1.144297] msm_serial: driver initialized"}
- {"dt": "2025-08-26T11:57:17.218018", "lvl": "target", "msg": "<6>[    1.145597] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2025-08-26T11:57:17.218766", "lvl": "target", "msg": "<6>[    1.146511] STMicroelectronics ASC driver initialized"}
- {"dt": "2025-08-26T11:57:17.219586", "lvl": "target", "msg": "<6>[    1.147469] STM32 USART driver initialized"}
- {"dt": "2025-08-26T11:57:17.310628", "lvl": "target", "msg": "<6>[    1.230780] brd: module loaded"}
- {"dt": "2025-08-26T11:57:17.323447", "lvl": "target", "msg": "<6>[    1.250786] loop: module loaded"}
- {"dt": "2025-08-26T11:57:17.336389", "lvl": "target", "msg": "<6>[    1.252765] virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2025-08-26T11:57:17.350469", "lvl": "target", "msg": "<5>[    1.274029] virtio_blk virtio0: [vda] 5109504 512-byte logical blocks (2.62 GB/2.44 GiB)"}
- {"dt": "2025-08-26T11:57:17.378007", "lvl": "target", "msg": "<5>[    1.302860] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"}
- {"dt": "2025-08-26T11:57:17.378878", "lvl": "target", "msg": "<6>[    1.305464] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2025-08-26T11:57:17.379642", "lvl": "target", "msg": "<6>[    1.306076] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2025-08-26T11:57:17.379997", "lvl": "target", "msg": "<6>[    1.307522] Using buffer write method"}
- {"dt": "2025-08-26T11:57:17.380443", "lvl": "target", "msg": "<7>[    1.308550] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2025-08-26T11:57:17.398050", "lvl": "target", "msg": "<5>[    1.311139] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"}
- {"dt": "2025-08-26T11:57:17.398940", "lvl": "target", "msg": "<6>[    1.312310] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"}
- {"dt": "2025-08-26T11:57:17.399923", "lvl": "target", "msg": "<6>[    1.312476] Intel/Sharp Extended Query Table at 0x0031"}
- {"dt": "2025-08-26T11:57:17.400505", "lvl": "target", "msg": "<6>[    1.313487] Using buffer write method"}
- {"dt": "2025-08-26T11:57:17.400862", "lvl": "target", "msg": "<7>[    1.313601] erase region 0: offset=0x0,size=0x40000,blocks=256"}
- {"dt": "2025-08-26T11:57:17.401271", "lvl": "target", "msg": "<5>[    1.313832] Concatenating MTD devices:"}
- {"dt": "2025-08-26T11:57:17.401579", "lvl": "target", "msg": "<5>[    1.313941] (0): \"0.flash\""}
- {"dt": "2025-08-26T11:57:17.401890", "lvl": "target", "msg": "<5>[    1.314028] (1): \"0.flash\""}
- {"dt": "2025-08-26T11:57:17.402259", "lvl": "target", "msg": "<5>[    1.314117] into device \"0.flash\""}
- {"dt": "2025-08-26T11:57:17.438404", "lvl": "target", "msg": "<6>[    1.364488] CAN device driver interface"}
- {"dt": "2025-08-26T11:57:17.438967", "lvl": "target", "msg": "<6>[    1.367365] bgmac_bcma: Broadcom 47xx GBit MAC driver loaded"}
- {"dt": "2025-08-26T11:57:17.452538", "lvl": "target", "msg": "<6>[    1.373245] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2025-08-26T11:57:17.453521", "lvl": "target", "msg": "<6>[    1.373957] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2025-08-26T11:57:17.453762", "lvl": "target", "msg": "<6>[    1.374417] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2025-08-26T11:57:17.453984", "lvl": "target", "msg": "<6>[    1.374619] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2025-08-26T11:57:17.467727", "lvl": "target", "msg": "<6>[    1.388565] pegasus: Pegasus/Pegasus II USB Ethernet driver"}
- {"dt": "2025-08-26T11:57:17.468409", "lvl": "target", "msg": "<6>[    1.389092] usbcore: registered new interface driver pegasus"}
- {"dt": "2025-08-26T11:57:17.468671", "lvl": "target", "msg": "<6>[    1.389466] usbcore: registered new interface driver asix"}
- {"dt": "2025-08-26T11:57:17.468914", "lvl": "target", "msg": "<6>[    1.389780] usbcore: registered new interface driver ax88179_178a"}
- {"dt": "2025-08-26T11:57:17.469805", "lvl": "target", "msg": "<6>[    1.390076] usbcore: registered new interface driver cdc_ether"}
- {"dt": "2025-08-26T11:57:17.470371", "lvl": "target", "msg": "<6>[    1.390395] usbcore: registered new interface driver smsc75xx"}
- {"dt": "2025-08-26T11:57:17.470686", "lvl": "target", "msg": "<6>[    1.390691] usbcore: registered new interface driver smsc95xx"}
- {"dt": "2025-08-26T11:57:17.471097", "lvl": "target", "msg": "<6>[    1.390999] usbcore: registered new interface driver net1080"}
- {"dt": "2025-08-26T11:57:17.471523", "lvl": "target", "msg": "<6>[    1.391350] usbcore: registered new interface driver cdc_subset"}
- {"dt": "2025-08-26T11:57:17.471886", "lvl": "target", "msg": "<6>[    1.391645] usbcore: registered new interface driver zaurus"}
- {"dt": "2025-08-26T11:57:17.472252", "lvl": "target", "msg": "<6>[    1.391985] usbcore: registered new interface driver cdc_ncm"}
- {"dt": "2025-08-26T11:57:17.484939", "lvl": "target", "msg": "<6>[    1.404333] usbcore: registered new interface driver usb-storage"}
- {"dt": "2025-08-26T11:57:17.497812", "lvl": "target", "msg": "<6>[    1.422635] rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2025-08-26T11:57:17.498417", "lvl": "target", "msg": "<6>[    1.424072] rtc-pl031 9010000.pl031: setting system clock to 2025-08-26T11:57:17 UTC (1756209437)"}
- {"dt": "2025-08-26T11:57:17.524919", "lvl": "target", "msg": "<6>[    1.429351] i2c_dev: i2c /dev entries driver"}
- {"dt": "2025-08-26T11:57:17.538347", "lvl": "target", "msg": "<6>[    1.458706] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2025-08-26T11:57:17.540973", "lvl": "target", "msg": "<6>[    1.458911] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2025-08-26T11:57:17.542098", "lvl": "target", "msg": "<6>[    1.462339] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2025-08-26T11:57:17.546398", "lvl": "target", "msg": "<6>[    1.465632] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2025-08-26T11:57:17.560659", "lvl": "target", "msg": "<6>[    1.484146] usbcore: registered new interface driver usbhid"}
- {"dt": "2025-08-26T11:57:17.561061", "lvl": "target", "msg": "<6>[    1.484430] usbhid: USB HID core driver"}
- {"dt": "2025-08-26T11:57:17.587275", "lvl": "target", "msg": "<6>[    1.505238] NET: Registered PF_INET6 protocol family"}
- {"dt": "2025-08-26T11:57:17.608497", "lvl": "target", "msg": "<6>[    1.519681] Segment Routing with IPv6"}
- {"dt": "2025-08-26T11:57:17.608962", "lvl": "target", "msg": "<6>[    1.520341] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2025-08-26T11:57:17.609070", "lvl": "target", "msg": "<6>[    1.521080] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2025-08-26T11:57:17.610227", "lvl": "target", "msg": "<6>[    1.530810] NET: Registered PF_PACKET protocol family"}
- {"dt": "2025-08-26T11:57:17.611128", "lvl": "target", "msg": "<6>[    1.531387] can: controller area network core"}
- {"dt": "2025-08-26T11:57:17.611917", "lvl": "target", "msg": "<6>[    1.532157] NET: Registered PF_CAN protocol family"}
- {"dt": "2025-08-26T11:57:17.612453", "lvl": "target", "msg": "<6>[    1.532347] can: raw protocol"}
- {"dt": "2025-08-26T11:57:17.613186", "lvl": "target", "msg": "<6>[    1.532650] can: broadcast manager protocol"}
- {"dt": "2025-08-26T11:57:17.613896", "lvl": "target", "msg": "<6>[    1.532978] can: netlink gateway - max_hops=1"}
- {"dt": "2025-08-26T11:57:17.614374", "lvl": "target", "msg": "<5>[    1.534928] Key type dns_resolver registered"}
- {"dt": "2025-08-26T11:57:17.615105", "lvl": "target", "msg": "<6>[    1.536086] ThumbEE CPU extension supported."}
- {"dt": "2025-08-26T11:57:17.615652", "lvl": "target", "msg": "<5>[    1.536265] Registering SWP/SWPB emulation handler"}
- {"dt": "2025-08-26T11:57:17.651603", "lvl": "target", "msg": "<5>[    1.568900] Loading compiled-in X.509 certificates"}
- {"dt": "2025-08-26T11:57:17.708487", "lvl": "target", "msg": "<6>[    1.625101] input: gpio-keys as /devices/platform/gpio-keys/input/input0"}
- {"dt": "2025-08-26T11:57:18.602985", "lvl": "target", "msg": "<6>[    2.526689] clk: Disabling unused clocks"}
- {"dt": "2025-08-26T11:57:18.608448", "lvl": "target", "msg": "<4>[    2.536237] check access for rdinit=/init failed: -2, ignoring"}
- {"dt": "2025-08-26T11:57:18.687911", "lvl": "target", "msg": "<6>[    2.611995] EXT4-fs (vda): mounted filesystem 65e4f6e7-bfaa-4cbf-b6d3-0109b98c5cfa r/w with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-08-26T11:57:18.688331", "lvl": "target", "msg": "<6>[    2.614960] VFS: Mounted root (ext4 filesystem) on device 254:0."}
- {"dt": "2025-08-26T11:57:18.736318", "lvl": "target", "msg": "<6>[    2.621732] devtmpfs: mounted"}
- {"dt": "2025-08-26T11:57:18.969063", "lvl": "target", "msg": "<6>[    2.817418] Freeing unused kernel image (initmem) memory: 2048K"}
- {"dt": "2025-08-26T11:57:18.970122", "lvl": "target", "msg": "<6>[    2.898628] Run /sbin/init as init process"}
- {"dt": "2025-08-26T11:57:18.970359", "lvl": "target", "msg": "<7>[    2.898793]   with arguments:"}
- {"dt": "2025-08-26T11:57:18.970670", "lvl": "target", "msg": "<7>[    2.898908]     /sbin/init"}
- {"dt": "2025-08-26T11:57:18.970877", "lvl": "target", "msg": "<7>[    2.898943]     verbose"}
- {"dt": "2025-08-26T11:57:18.971099", "lvl": "target", "msg": "<7>[    2.898967]   with environment:"}
- {"dt": "2025-08-26T11:57:18.971270", "lvl": "target", "msg": "<7>[    2.898991]     HOME=/"}
- {"dt": "2025-08-26T11:57:18.971769", "lvl": "target", "msg": "<7>[    2.899228]     TERM=linux"}
- {"dt": "2025-08-26T11:57:19.915712", "lvl": "target", "msg": "[!p]104\a[?7h"}
- {"dt": "2025-08-26T11:57:19.916538", "lvl": "target", "msg": "[0;1;39mWelcome to [0m[1mDebian GNU/Linux 13 (trixie)[0m[0;1;39m![0m"}
- {"dt": "2025-08-26T11:57:19.916687", "lvl": "target", "msg": "\r"}
- {"dt": "2025-08-26T11:57:25.028425", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty.\r"}
- {"dt": "2025-08-26T11:57:25.047501", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe.\r"}
- {"dt": "2025-08-26T11:57:25.066253", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-serial\\x2dget\u2026slice[0m - Slice /system/serial-getty.\r"}
- {"dt": "2025-08-26T11:57:25.086384", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39msystem-xfs_scrub.sli\u2026[0mxfs_scrub background service slice.\r"}
- {"dt": "2025-08-26T11:57:25.100281", "lvl": "target", "msg": "[[0;32m  OK  [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice.\r"}
- {"dt": "2025-08-26T11:57:25.110111", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-conso\u2026[0mequests to Console Directory Watch.\r"}
- {"dt": "2025-08-26T11:57:25.117947", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-ask-password-wall.\u2026[0md Requests to Wall Directory Watch.\r"}
- {"dt": "2025-08-26T11:57:25.126050", "lvl": "target", "msg": "         Expecting device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0...\r"}
- {"dt": "2025-08-26T11:57:25.131962", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork.target[0m - Network.\r"}
- {"dt": "2025-08-26T11:57:25.135425", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online.\r"}
- {"dt": "2025-08-26T11:57:25.141874", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnss-lookup.target[0m - Host and Network Name Lookups.\r"}
- {"dt": "2025-08-26T11:57:25.142134", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mpaths.target[0m - Path Units.\r"}
- {"dt": "2025-08-26T11:57:25.145034", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mslices.target[0m - Slice Units.\r"}
- {"dt": "2025-08-26T11:57:25.147916", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mswap.target[0m - Swaps.\r"}
- {"dt": "2025-08-26T11:57:25.166715", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mrpcbind.socket[0m - RPCbind Server Activation Socket.\r"}
- {"dt": "2025-08-26T11:57:25.198276", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-creds.socket[0m - Credential Encryption/Decryption.\r"}
- {"dt": "2025-08-26T11:57:25.206316", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-initctl.socke\u2026[0m- initctl Compatibility Named Pipe.\r"}
- {"dt": "2025-08-26T11:57:25.216177", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald-dev-\u2026socket[0m - Journal Socket (/dev/log).\r"}
- {"dt": "2025-08-26T11:57:25.225056", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Sockets.\r"}
- {"dt": "2025-08-26T11:57:25.235160", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket.\r"}
- {"dt": "2025-08-26T11:57:25.240567", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket.\r"}
- {"dt": "2025-08-26T11:57:25.318786", "lvl": "target", "msg": "         Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System...\r"}
- {"dt": "2025-08-26T11:57:25.364383", "lvl": "target", "msg": "         Mounting [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock...\r"}
- {"dt": "2025-08-26T11:57:25.424757", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System...\r"}
- {"dt": "2025-08-26T11:57:25.487699", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System...\r"}
- {"dt": "2025-08-26T11:57:25.618448", "lvl": "target", "msg": "         Mounting [0;1;39mtmp.mount[0m - Temporary Directory /tmp...\r"}
- {"dt": "2025-08-26T11:57:25.768099", "lvl": "target", "msg": "         Starting [0;1;39mkmod-static-nodes.service[0m\u2026eate List of Static Device Nodes...\r"}
- {"dt": "2025-08-26T11:57:25.956227", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs...\r"}
- {"dt": "2025-08-26T11:57:26.095876", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm...\r"}
- {"dt": "2025-08-26T11:57:26.166689", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@efi_pstore.servi\u2026[0m - Load Kernel Module efi_pstore...\r"}
- {"dt": "2025-08-26T11:57:26.218358", "lvl": "target", "msg": "         Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse...\r"}
- {"dt": "2025-08-26T11:57:26.243776", "lvl": "target", "msg": "<28>[   10.169705] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling."}
- {"dt": "2025-08-26T11:57:26.244443", "lvl": "target", "msg": "<28>[   10.172099] systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.)"}
- {"dt": "2025-08-26T11:57:26.308766", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journald.service[0m - Journal Service...\r"}
- {"dt": "2025-08-26T11:57:26.384540", "lvl": "target", "msg": "         Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules...\r"}
- {"dt": "2025-08-26T11:57:26.439360", "lvl": "target", "msg": "         Starting [0;1;39msystemd-remount-fs.servic\u2026[0munt Root and Kernel File Systems...\r"}
- {"dt": "2025-08-26T11:57:26.508783", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-load-credent\u2026[0mLoad udev Rules from Credentials...\r"}
- {"dt": "2025-08-26T11:57:26.578230", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices...\r"}
- {"dt": "2025-08-26T11:57:26.700612", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System.\r"}
- {"dt": "2025-08-26T11:57:26.708772", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock.\r"}
- {"dt": "2025-08-26T11:57:26.716644", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System.\r"}
- {"dt": "2025-08-26T11:57:26.724996", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System.\r"}
- {"dt": "2025-08-26T11:57:26.732272", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mtmp.mount[0m - Temporary Directory /tmp.\r"}
- {"dt": "2025-08-26T11:57:27.133818", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mkmod-static-nodes.service[0m\u2026Create List of Static Device Nodes.\r"}
- {"dt": "2025-08-26T11:57:27.299208", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0m Device Nodes in /dev gracefully...\r"}
- {"dt": "2025-08-26T11:57:27.621047", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-remount-fs.servic\u2026[0mmount Root and Kernel File Systems.\r"}
- {"dt": "2025-08-26T11:57:27.704413", "lvl": "target", "msg": "         Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed...\r"}
- {"dt": "2025-08-26T11:57:27.805821", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules.\r"}
- {"dt": "2025-08-26T11:57:27.855903", "lvl": "target", "msg": "         Mounting [0;1;39mproc-fs-nfsd.mount[0m - NFSD configuration filesystem...\r"}
- {"dt": "2025-08-26T11:57:27.919910", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables...\r"}
- {"dt": "2025-08-26T11:57:28.318108", "lvl": "target", "msg": "<28>[   12.245893] systemd[1]: proc-fs-nfsd.mount: Failed with result 'exit-code'."}
- {"dt": "2025-08-26T11:57:28.332434", "lvl": "target", "msg": "<27>[   12.260489] systemd[1]: Failed to mount proc-fs-nfsd.mount - NFSD configuration filesystem."}
- {"dt": "2025-08-26T11:57:28.334120", "lvl": "target", "msg": "[[0;1;31mFAILED[0m] Failed to mount [0;1;39mproc-fs-nfsd.mount[0m - NFSD configuration filesystem.\r"}
- {"dt": "2025-08-26T11:57:28.336223", "lvl": "target", "msg": "See 'systemctl status proc-fs-nfsd.mount' for details.\r"}
- {"dt": "2025-08-26T11:57:28.341931", "lvl": "target", "msg": "<28>[   12.270035] systemd[1]: Dependency failed for nfs-server.service - NFS server and services."}
- {"dt": "2025-08-26T11:57:28.343061", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-server.service[0m - NFS server and services.\r"}
- {"dt": "2025-08-26T11:57:28.345660", "lvl": "target", "msg": "<28>[   12.273975] systemd[1]: Dependency failed for nfs-mountd.service - NFS Mount Daemon."}
- {"dt": "2025-08-26T11:57:28.346909", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-mountd.service[0m - NFS Mount Daemon.\r"}
- {"dt": "2025-08-26T11:57:28.350626", "lvl": "target", "msg": "<28>[   12.278811] systemd[1]: Dependency failed for nfs-idmapd.service - NFSv4 ID-name mapping service."}
- {"dt": "2025-08-26T11:57:28.352001", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfs-idmapd.s\u2026ce[0m - NFSv4 ID-name mapping service.\r"}
- {"dt": "2025-08-26T11:57:28.361847", "lvl": "target", "msg": "<28>[   12.289873] systemd[1]: Dependency failed for nfsdcld.service - NFSv4 Client Tracking Daemon."}
- {"dt": "2025-08-26T11:57:28.363033", "lvl": "target", "msg": "[[0;1;38:5:185mDEPEND[0m] Dependency failed for [0;1;39mnfsdcld.service[0m - NFSv4 Client Tracking Daemon.\r"}
- {"dt": "2025-08-26T11:57:28.699976", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-load-credent\u2026[0m- Load udev Rules from Credentials.\r"}
- {"dt": "2025-08-26T11:57:29.156288", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables.\r"}
- {"dt": "2025-08-26T11:57:29.214800", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed.\r"}
- {"dt": "2025-08-26T11:57:29.285219", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm.\r"}
- {"dt": "2025-08-26T11:57:29.377924", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore.\r"}
- {"dt": "2025-08-26T11:57:29.513148", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse.\r"}
- {"dt": "2025-08-26T11:57:29.596984", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs.\r"}
- {"dt": "2025-08-26T11:57:29.656998", "lvl": "target", "msg": "         Mounting [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System...\r"}
- {"dt": "2025-08-26T11:57:29.859519", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mic Device Nodes in /dev gracefully.\r"}
- {"dt": "2025-08-26T11:57:29.982310", "lvl": "target", "msg": "         Starting [0;1;39msystemd-sysusers.service[0m - Create System Users...\r"}
- {"dt": "2025-08-26T11:57:30.018492", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System.\r"}
- {"dt": "2025-08-26T11:57:30.557826", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service.\r"}
- {"dt": "2025-08-26T11:57:30.643490", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-flush.ser\u2026[0msh Journal to Persistent Storage...\r"}
- {"dt": "2025-08-26T11:57:31.082592", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users.\r"}
- {"dt": "2025-08-26T11:57:31.353434", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup-de\u2026[0meate Static Device Nodes in /dev...\r"}
- {"dt": "2025-08-26T11:57:31.954312", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-flush.ser\u2026[0mlush Journal to Persistent Storage.\r"}
- {"dt": "2025-08-26T11:57:32.201957", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup-de\u2026[0mCreate Static Device Nodes in /dev.\r"}
- {"dt": "2025-08-26T11:57:32.227832", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs-pre.target[0m\u2026Preparation for Local File Systems.\r"}
- {"dt": "2025-08-26T11:57:32.232468", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems.\r"}
- {"dt": "2025-08-26T11:57:32.282322", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-sysext.socket[0m\u2026 System Extension Image Management.\r"}
- {"dt": "2025-08-26T11:57:32.324355", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd-notify.service[0m - Notify NFS peers of a restart...\r"}
- {"dt": "2025-08-26T11:57:32.403996", "lvl": "target", "msg": "         Starting [0;1;39msystemd-tmpfiles-setup.se\u2026[0mate System Files and Directories...\r"}
- {"dt": "2025-08-26T11:57:32.439266", "lvl": "target", "msg": "         Starting [0;1;39msystemd-udevd.service[0m - R\u2026ager for Device Events and Files...\r"}
- {"dt": "2025-08-26T11:57:33.117928", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd-notify.service[0m - Notify NFS peers of a restart.\r"}
- {"dt": "2025-08-26T11:57:34.345062", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-udevd.service[0m - Ru\u2026anager for Device Events and Files.\r"}
- {"dt": "2025-08-26T11:57:34.494364", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-tmpfiles-setup.se\u2026[0mreate System Files and Directories.\r"}
- {"dt": "2025-08-26T11:57:34.557830", "lvl": "target", "msg": "         Mounting [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System...\r"}
- {"dt": "2025-08-26T11:57:34.616798", "lvl": "target", "msg": "         Starting [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache...\r"}
- {"dt": "2025-08-26T11:57:34.679545", "lvl": "target", "msg": "         Starting [0;1;39mrpcbind.service[0m - RPC bind portmap service...\r"}
- {"dt": "2025-08-26T11:57:34.761829", "lvl": "target", "msg": "         Starting [0;1;39msystemd-journal-catalog-u\u2026ervice[0m - Rebuild Journal Catalog...\r"}
- {"dt": "2025-08-26T11:57:35.416267", "lvl": "target", "msg": "[[0;32m  OK  [0m] Mounted [0;1;39mrun-rpc_pipefs.mount[0m - RPC Pipe File System.\r"}
- {"dt": "2025-08-26T11:57:35.453545", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpc_pipefs.target[0m.\r"}
- {"dt": "2025-08-26T11:57:35.496686", "lvl": "target", "msg": "         Starting [0;1;39mnfs-blkmap.service[0m - pNFS block layout mapping daemon...\r"}
- {"dt": "2025-08-26T11:57:35.506452", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mnfs-client.target[0m - NFS client services.\r"}
- {"dt": "2025-08-26T11:57:35.617932", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpcbind.service[0m - RPC bind portmap service.\r"}
- {"dt": "2025-08-26T11:57:35.624532", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs-pre.targe\u2026[0mreparation for Remote File Systems.\r"}
- {"dt": "2025-08-26T11:57:35.628120", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems.\r"}
- {"dt": "2025-08-26T11:57:35.632065", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mrpcbind.target[0m - RPC Port Mapper.\r"}
- {"dt": "2025-08-26T11:57:35.685418", "lvl": "target", "msg": "         Starting [0;1;39mrpc-statd.service[0m - NFS s\u2026tus monitor for NFSv2/3 locking....\r"}
- {"dt": "2025-08-26T11:57:36.318572", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mnfs-blkmap.service[0m - pNFS block layout mapping daemon.\r"}
- {"dt": "2025-08-26T11:57:37.023541", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mrpc-statd.service[0m - NFS status monitor for NFSv2/3 locking..\r"}
- {"dt": "2025-08-26T11:57:37.508187", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-journal-catalog-u\u2026.service[0m - Rebuild Journal Catalog.\r"}
- {"dt": "2025-08-26T11:57:38.234548", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39mldconfig.service[0m - Rebuild Dynamic Linker Cache.\r"}
- {"dt": "2025-08-26T11:57:38.286393", "lvl": "target", "msg": "         Starting [0;1;39msystemd-update-done.service[0m - Update is Completed...\r"}
- {"dt": "2025-08-26T11:57:38.784832", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-update-done.service[0m - Update is Completed.\r"}
- {"dt": "2025-08-26T11:57:40.878132", "lvl": "target", "msg": "[[0m[0;31m*     [0m] (1 of 2) Job dev-ttyAMA0.device/start running (16s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:41.372313", "lvl": "target", "msg": "M\r[K[[0;1;31m*[0m[0;31m*    [0m] (1 of 2) Job dev-ttyAMA0.device/start running (16s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:41.877061", "lvl": "target", "msg": "M\r[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] (1 of 2) Job dev-ttyAMA0.device/start running (17s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:42.373430", "lvl": "target", "msg": "M\r[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (17s / no limit)\r"}
- {"dt": "2025-08-26T11:57:42.872202", "lvl": "target", "msg": "M\r[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (18s / no limit)\r"}
- {"dt": "2025-08-26T11:57:43.372419", "lvl": "target", "msg": "M\r[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (18s / no limit)\r"}
- {"dt": "2025-08-26T11:57:43.872909", "lvl": "target", "msg": "M\r[K[    [0;31m*[0;1;31m*[0m] (1 of 2) Job dev-ttyAMA0.device/start running (19s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:44.372684", "lvl": "target", "msg": "M\r[K[     [0;31m*[0m] (1 of 2) Job dev-ttyAMA0.device/start running (19s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:44.872454", "lvl": "target", "msg": "M\r[K[    [0;31m*[0;1;31m*[0m] (1 of 2) Job dev-ttyAMA0.device/start running (20s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:45.372924", "lvl": "target", "msg": "M\r[K[   [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (20s / no limit)\r"}
- {"dt": "2025-08-26T11:57:45.872031", "lvl": "target", "msg": "M\r[K[  [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (21s / no limit)\r"}
- {"dt": "2025-08-26T11:57:46.372268", "lvl": "target", "msg": "M\r[K[ [0;31m*[0;1;31m*[0m[0;31m*  [0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (21s / no limit)\r"}
- {"dt": "2025-08-26T11:57:46.870840", "lvl": "target", "msg": "M\r[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] (1 of 2) Job dev-ttyAMA0.device/start running (22s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:47.370598", "lvl": "target", "msg": "M\r[K[[0;1;31m*[0m[0;31m*    [0m] (1 of 2) Job dev-ttyAMA0.device/start running (22s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:47.870657", "lvl": "target", "msg": "M\r[K[[0m[0;31m*     [0m] (1 of 2) Job dev-ttyAMA0.device/start running (23s / 13min 30s)\r"}
- {"dt": "2025-08-26T11:57:48.223100", "lvl": "target", "msg": "M\r[K[[0;1;31m*[0m[0;31m*    [0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (23s / no limit)\r"}
- {"dt": "2025-08-26T11:57:48.620434", "lvl": "target", "msg": "M\r[K[[0;31m*[0;1;31m*[0m[0;31m*   [0m] (2 of 2) Job systemd-udev-trigger.s\u2026ice/start running (23s / no limit)\r"}
- {"dt": "2025-08-26T11:57:48.720043", "lvl": "target", "msg": "M\r[K[[0;32m  OK  [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices.\r"}
- {"dt": "2025-08-26T11:57:48.766719", "lvl": "target", "msg": "[K[[0;32m  OK  [0m] Reached target [0;1;39msysinit.target[0m - System Initialization.\r"}
- {"dt": "2025-08-26T11:57:48.775270", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities.\r"}
- {"dt": "2025-08-26T11:57:48.781323", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - \u2026y apt upgrade and clean activities.\r"}
- {"dt": "2025-08-26T11:57:48.792131", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer.\r"}
- {"dt": "2025-08-26T11:57:48.796486", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39me2scrub_all.timer[0m - Period\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2025-08-26T11:57:48.801044", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week.\r"}
- {"dt": "2025-08-26T11:57:48.803450", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files.\r"}
- {"dt": "2025-08-26T11:57:48.805475", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-tmpfiles-clean.tim\u2026[0my Cleanup of Temporary Directories.\r"}
- {"dt": "2025-08-26T11:57:48.809896", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mxfs_scrub_all.timer[0m - Peri\u2026Metadata Check for All Filesystems.\r"}
- {"dt": "2025-08-26T11:57:48.811153", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mtimers.target[0m - Timer Units.\r"}
- {"dt": "2025-08-26T11:57:48.813475", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket.\r"}
- {"dt": "2025-08-26T11:57:48.815682", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39msystemd-hostnamed.socket[0m - Hostname Service Socket.\r"}
- {"dt": "2025-08-26T11:57:48.817588", "lvl": "target", "msg": "[[0;32m  OK  [0m] Listening on [0;1;39muuidd.socket[0m - UUID daemon activation socket.\r"}
- {"dt": "2025-08-26T11:57:48.818793", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39msockets.target[0m - Socket Units.\r"}
- {"dt": "2025-08-26T11:57:48.820721", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mbasic.target[0m - Basic System.\r"}
- {"dt": "2025-08-26T11:57:48.841304", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon.\r"}
- {"dt": "2025-08-26T11:57:48.871447", "lvl": "target", "msg": "         Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus...\r"}
- {"dt": "2025-08-26T11:57:48.921885", "lvl": "target", "msg": "         Starting [0;1;39me2scrub_reap.service[0m - Re\u2026ne ext4 Metadata Check Snapshots...\r"}
- {"dt": "2025-08-26T11:57:48.951457", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mfsidd.service[0m - NFS FSID Daemon.\r"}
- {"dt": "2025-08-26T11:57:48.981410", "lvl": "target", "msg": "         Starting [0;1;39msystemd-logind.service[0m - User Login Management...\r"}
- {"dt": "2025-08-26T11:57:49.031464", "lvl": "target", "msg": "         Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions...\r"}
- {"dt": "2025-08-26T11:57:49.527459", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions.\r"}
- {"dt": "2025-08-26T11:57:49.552622", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1.\r"}
- {"dt": "2025-08-26T11:57:49.647775", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus.\r"}
- {"dt": "2025-08-26T11:57:49.918238", "lvl": "target", "msg": "[[0;32m  OK  [0m] Finished [0;1;39me2scrub_reap.service[0m - Re\u2026line ext4 Metadata Check Snapshots.\r"}
- {"dt": "2025-08-26T11:57:51.271048", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management.\r"}
- {"dt": "2025-08-26T11:57:51.315695", "lvl": "target", "msg": "[[0;32m  OK  [0m] Found device [0;1;39mdev-ttyAMA0.device[0m - /dev/ttyAMA0.\r"}
- {"dt": "2025-08-26T11:57:51.373489", "lvl": "target", "msg": "[[0;32m  OK  [0m] Started [0;1;39mserial-getty@ttyAMA0.service[0m - Serial Getty on ttyAMA0.\r"}
- {"dt": "2025-08-26T11:57:51.383948", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts.\r"}
- {"dt": "2025-08-26T11:57:51.389249", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System.\r"}
- {"dt": "2025-08-26T11:57:51.394632", "lvl": "target", "msg": "[[0;32m  OK  [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface.\r"}
- {"dt": "2025-08-26T11:57:53.604946", "lvl": "target", "msg": "[!p]104\a[?7h[6n[32766;32766H[6n[!p]104\a[?7h[6n[32766;32766H[6n\r"}
- {"dt": "2025-08-26T11:57:53.605860", "lvl": "target", "msg": "Debian GNU/Linux 13 runner-j1aldqxsn-project-40964107-concurrent-0 ttyAMA0"}
- {"dt": "2025-08-26T11:57:53.606139", "lvl": "target", "msg": ""}
- {"dt": "2025-08-26T11:57:53.607118", "lvl": "debug", "msg": "Matched prompt #12: login:"}
- {"dt": "2025-08-26T11:57:53.607296", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "38.07", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2025-08-26T11:57:53.607509", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2025-08-26T11:57:53.607623", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2025-08-26T11:57:53.708100", "lvl": "input", "msg": "root"}
- {"dt": "2025-08-26T11:57:53.708539", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-08-26T11:57:53.708755", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:29:21)"}
- {"dt": "2025-08-26T11:57:53.708904", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:14:41)"}
- {"dt": "2025-08-26T11:57:53.716590", "lvl": "target", "msg": "runner-j1aldqxsn-project-40964107-concurrent-0 login: root\r"}
- {"dt": "2025-08-26T11:57:54.706297", "lvl": "target", "msg": "Linux runner-j1aldqxsn-project-40964107-concurrent-0 6.17.0-rc3-next-20250825 #1 SMP @1756208870 armv7l"}
- {"dt": "2025-08-26T11:57:54.720123", "lvl": "target", "msg": ""}
- {"dt": "2025-08-26T11:57:54.720824", "lvl": "target", "msg": "The programs included with the Debian GNU/Linux system are free software;"}
- {"dt": "2025-08-26T11:57:54.721336", "lvl": "target", "msg": "the exact distribution terms for each program are described in the"}
- {"dt": "2025-08-26T11:57:54.721809", "lvl": "target", "msg": "individual files in /usr/share/doc/*/copyright."}
- {"dt": "2025-08-26T11:57:54.722052", "lvl": "target", "msg": ""}
- {"dt": "2025-08-26T11:57:54.722306", "lvl": "target", "msg": "Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent"}
- {"dt": "2025-08-26T11:57:54.722550", "lvl": "target", "msg": "permitted by applicable law."}
- {"dt": "2025-08-26T11:58:03.352593", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-08-26T11:58:03.352828", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:48) [common]"}
- {"dt": "2025-08-26T11:58:03.352928", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "47.82", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2025-08-26T11:58:03.353100", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:48) [common]"}
- {"dt": "2025-08-26T11:58:03.353213", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:29:12) [common]"}
- {"dt": "2025-08-26T11:58:03.353312", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-08-26T11:58:03.353403", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2025-08-26T11:58:03.353489", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2025-08-26T11:58:03.403675", "lvl": "input", "msg": ""}
- {"dt": "2025-08-26T11:58:03.403957", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:30:00)"}
- {"dt": "2025-08-26T11:58:03.404086", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:15:00)"}
- {"dt": "2025-08-26T11:58:03.405121", "lvl": "target", "msg": "[?2004hroot@runner-j1aldqxsn-project-40964107-concurrent-0:~# "}
- {"dt": "2025-08-26T11:58:03.408623", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:58:03.408734", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:29:12) [common]"}
- {"dt": "2025-08-26T11:58:03.408828", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:58:03.408917", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:48) [common]"}
- {"dt": "2025-08-26T11:58:03.409003", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:10:00) [common]"}
- {"dt": "2025-08-26T11:58:03.409083", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:10:00) [common]"}
- {"dt": "2025-08-26T11:58:03.409152", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2025-08-26T11:58:03.409228", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2025-08-26T11:58:03.509491", "lvl": "input", "msg": "#"}
- {"dt": "2025-08-26T11:58:03.509741", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:10:00)"}
- {"dt": "2025-08-26T11:58:03.510327", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-j1aldqxsn-project-40964107-concurrent-0:~# #"}
- {"dt": "2025-08-26T11:58:03.512760", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2025-08-26T11:58:03.512865", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2025-08-26T11:58:03.613139", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2025-08-26T11:58:03.614099", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-j1aldqxsn-project-40964107-concurrent-0:~# export SHELL=/bin/sh"}
- {"dt": "2025-08-26T11:58:03.616728", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2025-08-26T11:58:03.717103", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2025-08-26T11:58:03.718179", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-j1aldqxsn-project-40964107-concurrent-0:~# . /lava-1/environment"}
- {"dt": "2025-08-26T11:58:03.722672", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2025-08-26T11:58:03.822965", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-08-26T11:58:03.823229", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2025-08-26T11:58:03.823736", "lvl": "target", "msg": "[?2004l\r[?2004hroot@runner-j1aldqxsn-project-40964107-concurrent-0:~# /lava-1/bin/lava-test-runn\rner /lava-1/0"}
- {"dt": "2025-08-26T11:58:04.126488", "lvl": "target", "msg": "[?2004l\r+ export TESTRUN_ID=0_kselftest-futex"}
- {"dt": "2025-08-26T11:58:04.126904", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_kselftest-futex"}
- {"dt": "2025-08-26T11:58:04.146851", "lvl": "target", "msg": "+ cat uuid"}
- {"dt": "2025-08-26T11:58:04.161340", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2025-08-26T11:58:04.161978", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-08-26T11:58:04.163110", "lvl": "target", "msg": "<8>[   48.091862] <LAVA_SIGNAL_STARTRUN 0_kselftest-futex 1_1.1.3.1>"}
- {"dt": "2025-08-26T11:58:04.163370", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_kselftest-futex 1_1.1.3.1"}
- {"dt": "2025-08-26T11:58:04.163445", "lvl": "info", "msg": "Starting test lava.0_kselftest-futex (1_1.1.3.1)"}
- {"dt": "2025-08-26T11:58:04.163535", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2025-08-26T11:58:04.163629", "lvl": "target", "msg": "+ cd ./automated/linux/kselftest/"}
- {"dt": "2025-08-26T11:58:04.170369", "lvl": "target", "msg": "+ ./kselftest.sh -c futex -T  -t kselftest_armhf.tar.gz -s true -u  -L  -S skipfile-lkft.yaml -b  -g  -e production -p /opt/kselftests/default-in-kernel -n 1 -i 1 -E "}
- {"dt": "2025-08-26T11:58:04.278901", "lvl": "target", "msg": "INFO: Generating a skipfile based on /lava-1/0/tests/0_kselftest-futex/automated/linux/kselftest/skipfile-lkft.yaml"}
- {"dt": "2025-08-26T11:58:04.651338", "lvl": "target", "msg": "INFO: Using the following generated skipfile contents (until EOF):"}
- {"dt": "2025-08-26T11:58:04.672408", "lvl": "target", "msg": "bpf:test_btf"}
- {"dt": "2025-08-26T11:58:04.672620", "lvl": "target", "msg": "bpf:test_progs"}
- {"dt": "2025-08-26T11:58:04.672721", "lvl": "target", "msg": "breakpoints:breakpoint_test"}
- {"dt": "2025-08-26T11:58:04.672812", "lvl": "target", "msg": "breakpoints:breakpoint_test_arm64"}
- {"dt": "2025-08-26T11:58:04.673154", "lvl": "target", "msg": "breakpoints:step_after_suspend_test"}
- {"dt": "2025-08-26T11:58:04.673253", "lvl": "target", "msg": "kmod:test_kmod.sh"}
- {"dt": "2025-08-26T11:58:04.673340", "lvl": "target", "msg": "kvm:get-reg-list"}
- {"dt": "2025-08-26T11:58:04.673475", "lvl": "target", "msg": "kvm:kvm_create_max_vcpus"}
- {"dt": "2025-08-26T11:58:04.673932", "lvl": "target", "msg": "mqueue:mq_open_tests"}
- {"dt": "2025-08-26T11:58:04.674184", "lvl": "target", "msg": "mqueue:mq_perf_tests"}
- {"dt": "2025-08-26T11:58:04.674566", "lvl": "target", "msg": "net:fib_nexthops.sh"}
- {"dt": "2025-08-26T11:58:04.674857", "lvl": "target", "msg": "net:msg_zerocopy.sh"}
- {"dt": "2025-08-26T11:58:04.675351", "lvl": "target", "msg": "net:rtnetlink.sh"}
- {"dt": "2025-08-26T11:58:04.675768", "lvl": "target", "msg": "net:run_afpackettests"}
- {"dt": "2025-08-26T11:58:04.675971", "lvl": "target", "msg": "net:tls"}
- {"dt": "2025-08-26T11:58:04.676112", "lvl": "target", "msg": "net:udpgro.sh"}
- {"dt": "2025-08-26T11:58:04.676285", "lvl": "target", "msg": "net:xfrm_policy.sh"}
- {"dt": "2025-08-26T11:58:04.676522", "lvl": "target", "msg": "netfilter:bridge_brouter.sh"}
- {"dt": "2025-08-26T11:58:04.676758", "lvl": "target", "msg": "netfilter:conntrack_icmp_related.sh"}
- {"dt": "2025-08-26T11:58:04.677090", "lvl": "target", "msg": "netfilter:nft_flowtable.sh"}
- {"dt": "2025-08-26T11:58:04.677191", "lvl": "target", "msg": "netfilter:nft_nat.sh"}
- {"dt": "2025-08-26T11:58:04.677276", "lvl": "target", "msg": "netfilter:nft_trans_stress.sh"}
- {"dt": "2025-08-26T11:58:04.677360", "lvl": "target", "msg": "pidfd:pidfd_wait"}
- {"dt": "2025-08-26T11:58:04.677469", "lvl": "target", "msg": "proc:proc-pid-vm"}
- {"dt": "2025-08-26T11:58:04.677558", "lvl": "target", "msg": "ptrace:vmaccess"}
- {"dt": "2025-08-26T11:58:04.677664", "lvl": "target", "msg": "rseq:run_param_test.sh"}
- {"dt": "2025-08-26T11:58:04.677752", "lvl": "target", "msg": "sync:sync_test"}
- {"dt": "2025-08-26T11:58:04.677856", "lvl": "target", "msg": "vm:run_vmtests"}
- {"dt": "2025-08-26T11:58:04.677941", "lvl": "target", "msg": "zram:zram.sh"}
- {"dt": "2025-08-26T11:58:04.687098", "lvl": "target", "msg": "INFO: EOF"}
- {"dt": "2025-08-26T11:58:05.011978", "lvl": "target", "msg": "INFO: install_deps skipped"}
- {"dt": "2025-08-26T11:58:05.015135", "lvl": "target", "msg": "kselftests found on rootfs"}
- {"dt": "2025-08-26T11:58:05.220667", "lvl": "target", "msg": "skiplist:"}
- {"dt": "2025-08-26T11:58:05.220858", "lvl": "target", "msg": "========================================"}
- {"dt": "2025-08-26T11:58:05.255001", "lvl": "target", "msg": "========================================"}
- {"dt": "2025-08-26T11:58:05.412661", "lvl": "target", "msg": "futex:run.sh"}
- {"dt": "2025-08-26T11:58:05.428873", "lvl": "target", "msg": "============== Tests to run ==============="}
- {"dt": "2025-08-26T11:58:05.446606", "lvl": "target", "msg": "futex:run.sh"}
- {"dt": "2025-08-26T11:58:05.447838", "lvl": "target", "msg": "===========End Tests to run ==============="}
- {"dt": "2025-08-26T11:58:05.479570", "lvl": "target", "msg": "shardfile-futex pass"}
- {"dt": "2025-08-26T11:58:05.766278", "lvl": "target", "msg": "<12>[   49.694974] kselftest: Running tests in futex"}
- {"dt": "2025-08-26T11:58:05.839286", "lvl": "target", "msg": "TAP version 13"}
- {"dt": "2025-08-26T11:58:05.870600", "lvl": "target", "msg": "1..1"}
- {"dt": "2025-08-26T11:58:05.960009", "lvl": "target", "msg": "# timeout set to 45"}
- {"dt": "2025-08-26T11:58:05.962213", "lvl": "target", "msg": "# selftests: futex: run.sh"}
- {"dt": "2025-08-26T11:58:06.909998", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:06.922640", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:06.922747", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:06.938990", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:06.941319", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=0 owner=0 timeout=0ns"}
- {"dt": "2025-08-26T11:58:06.956877", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=0 owner=0 timeout=0ns"}
- {"dt": "2025-08-26T11:58:06.964021", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:06.969057", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:06.978941", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:06.979269", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:06.984489", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=0 owner=0 timeout=0ns"}
- {"dt": "2025-08-26T11:58:06.995941", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=0 owner=0 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.019048", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.019193", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.019316", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.019428", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.028903", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=1 owner=0 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.048971", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=1 owner=0 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.049101", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.052737", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.055108", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.064554", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.076268", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=0 owner=1 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.089177", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=0 owner=1 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.089321", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.091793", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.094132", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.109003", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.118973", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=1 owner=0 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.129201", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=1 owner=0 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.129801", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.130991", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.131288", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.135227", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.139803", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=0 owner=1 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.145000", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=0 owner=1 timeout=0ns"}
- {"dt": "2025-08-26T11:58:07.169110", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.169245", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.169352", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.169472", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.169565", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=1 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.169679", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=1 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.169767", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.169856", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.169910", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.172967", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.177622", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=1 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.199220", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=1 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.199338", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.199431", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.199502", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.199579", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.199925", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=1 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.200810", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=1 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.205066", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.206347", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.206655", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.229100", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.229219", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=1 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.230176", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=1 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.234488", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.235757", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.236003", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.259284", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.259384", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=0 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.259695", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=0 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.259800", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.259941", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.260032", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.260115", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.272459", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=0 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.277807", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=0 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.292270", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.293526", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.293939", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.297719", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.312601", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=0 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.317974", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=0 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.332407", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.333608", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.334058", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.337670", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.352536", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=0 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.357743", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=0 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.382425", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.383522", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.383921", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.387642", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.399102", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=0 owner=1 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.399213", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=0 owner=1 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.412019", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.414239", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.414890", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.418468", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.433557", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=1 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.454874", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=1 owner=0 timeout=5000ns"}
- {"dt": "2025-08-26T11:58:07.455265", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.455342", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.455410", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.469369", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.474324", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=0 owner=1 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.490285", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=0 owner=1 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.494981", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.496310", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.496797", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.519480", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.519647", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=1 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.532208", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=1 owner=0 timeout=500000ns"}
- {"dt": "2025-08-26T11:58:07.536874", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.538154", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.538603", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.552832", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.558271", "lvl": "target", "msg": "# # \tArguments: broadcast=1 locked=1 owner=0 timeout=2000000000ns"}
- {"dt": "2025-08-26T11:58:07.579519", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=1 locked=1 owner=0 timeout=2000000000ns"}
- {"dt": "2025-08-26T11:58:07.579694", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.579948", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.580034", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.584166", "lvl": "target", "msg": "# # futex_requeue_pi: Test requeue functionality"}
- {"dt": "2025-08-26T11:58:07.600054", "lvl": "target", "msg": "# # \tArguments: broadcast=0 locked=1 owner=0 timeout=2000000000ns"}
- {"dt": "2025-08-26T11:58:07.609307", "lvl": "target", "msg": "# ok 1 futex-requeue-pi broadcast=0 locked=1 owner=0 timeout=2000000000ns"}
- {"dt": "2025-08-26T11:58:07.620367", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:07.620539", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:07.621919", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:07.622606", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:07.639093", "lvl": "target", "msg": "# # futex_requeue_pi_mismatched_ops: Detect mismatched requeue_pi operations"}
- {"dt": "2025-08-26T11:58:08.096653", "lvl": "target", "msg": "# ok 1 futex-requeue-pi-mismatched-ops"}
- {"dt": "2025-08-26T11:58:08.111306", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:08.111468", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:08.139250", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:08.139390", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:08.139468", "lvl": "target", "msg": "# # futex_requeue_pi_signal_restart: Test signal handling during requeue_pi"}
- {"dt": "2025-08-26T11:58:08.139527", "lvl": "target", "msg": "# # \tArguments: <none>"}
- {"dt": "2025-08-26T11:58:08.147327", "lvl": "target", "msg": "# ok 1 futex-requeue-pi-signal-restart"}
- {"dt": "2025-08-26T11:58:08.162476", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:08.162643", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:08.187067", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:08.187580", "lvl": "target", "msg": "# 1..9"}
- {"dt": "2025-08-26T11:58:08.193651", "lvl": "target", "msg": "# # futex_wait_timeout: Block on a futex and wait for timeout"}
- {"dt": "2025-08-26T11:58:08.196367", "lvl": "target", "msg": "# # \tArguments: timeout=100000ns"}
- {"dt": "2025-08-26T11:58:08.229939", "lvl": "target", "msg": "# ok 1 futex_wait relative succeeds"}
- {"dt": "2025-08-26T11:58:08.233284", "lvl": "target", "msg": "# ok 2 futex_wait_bitset realtime succeeds"}
- {"dt": "2025-08-26T11:58:08.236684", "lvl": "target", "msg": "# ok 3 futex_wait_bitset monotonic succeeds"}
- {"dt": "2025-08-26T11:58:08.253135", "lvl": "target", "msg": "# ok 4 futex_wait_requeue_pi realtime succeeds"}
- {"dt": "2025-08-26T11:58:08.256779", "lvl": "target", "msg": "# ok 5 futex_wait_requeue_pi monotonic succeeds"}
- {"dt": "2025-08-26T11:58:08.269995", "lvl": "target", "msg": "# ok 6 futex_lock_pi realtime succeeds"}
- {"dt": "2025-08-26T11:58:08.273589", "lvl": "target", "msg": "# ok 7 futex_lock_pi invalid timeout flag succeeds"}
- {"dt": "2025-08-26T11:58:08.276451", "lvl": "target", "msg": "# ok 8 futex_waitv monotonic succeeds"}
- {"dt": "2025-08-26T11:58:08.289711", "lvl": "target", "msg": "# ok 9 futex_waitv realtime succeeds"}
- {"dt": "2025-08-26T11:58:08.294176", "lvl": "target", "msg": "# # Totals: pass:9 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:08.294331", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:08.295477", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:08.295887", "lvl": "target", "msg": "# 1..2"}
- {"dt": "2025-08-26T11:58:08.321818", "lvl": "target", "msg": "# # futex_wait_wouldblock: Test the unexpected futex value in FUTEX_WAIT"}
- {"dt": "2025-08-26T11:58:08.323008", "lvl": "target", "msg": "# ok 1 futex_wait"}
- {"dt": "2025-08-26T11:58:08.324267", "lvl": "target", "msg": "# ok 2 futex_waitv"}
- {"dt": "2025-08-26T11:58:08.329040", "lvl": "target", "msg": "# # Totals: pass:2 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:08.329147", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:08.330279", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:08.330690", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:08.337091", "lvl": "target", "msg": "# # futex_wait_uninitialized_heap: Test the uninitialized futex value in FUTEX_WAIT"}
- {"dt": "2025-08-26T11:58:13.277364", "lvl": "target", "msg": "# ok 1 futex-wait-uninitialized-heap"}
- {"dt": "2025-08-26T11:58:13.277545", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:13.285820", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:13.287149", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:13.294714", "lvl": "target", "msg": "# # futex_wait_private_mapped_file: Test the futex value of private file mappings in FUTEX_WAIT"}
- {"dt": "2025-08-26T11:58:16.296182", "lvl": "target", "msg": "# ok 1 futex-wait-private-mapped-file"}
- {"dt": "2025-08-26T11:58:16.296367", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:16.296481", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:16.304721", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:16.306273", "lvl": "target", "msg": "# 1..3"}
- {"dt": "2025-08-26T11:58:16.308413", "lvl": "target", "msg": "# # futex_wait: Test futex_wait"}
- {"dt": "2025-08-26T11:58:16.320500", "lvl": "target", "msg": "# ok 1 futex_wake private succeeds"}
- {"dt": "2025-08-26T11:58:16.332939", "lvl": "target", "msg": "# ok 2 futex_wake shared (page anon) succeeds"}
- {"dt": "2025-08-26T11:58:16.345932", "lvl": "target", "msg": "# ok 3 futex_wake shared (file backed) succeeds"}
- {"dt": "2025-08-26T11:58:16.357495", "lvl": "target", "msg": "# # Totals: pass:3 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:16.357613", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:16.358110", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:16.358391", "lvl": "target", "msg": "# 1..2"}
- {"dt": "2025-08-26T11:58:16.361688", "lvl": "target", "msg": "# # futex_requeue: Test futex_requeue"}
- {"dt": "2025-08-26T11:58:16.372097", "lvl": "target", "msg": "# ok 1 futex_requeue simple succeeds"}
- {"dt": "2025-08-26T11:58:16.390604", "lvl": "target", "msg": "# ok 2 futex_requeue many succeeds"}
- {"dt": "2025-08-26T11:58:16.394820", "lvl": "target", "msg": "# # Totals: pass:2 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:16.395065", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:16.404546", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:16.406261", "lvl": "target", "msg": "# 1..7"}
- {"dt": "2025-08-26T11:58:16.408390", "lvl": "target", "msg": "# # futex_waitv: Test FUTEX_WAITV"}
- {"dt": "2025-08-26T11:58:16.418464", "lvl": "target", "msg": "# ok 1 futex_waitv private"}
- {"dt": "2025-08-26T11:58:16.434449", "lvl": "target", "msg": "# ok 2 futex_waitv shared"}
- {"dt": "2025-08-26T11:58:16.440791", "lvl": "target", "msg": "# ok 3 futex_waitv without FUTEX_32"}
- {"dt": "2025-08-26T11:58:16.445929", "lvl": "target", "msg": "# ok 4 futex_waitv with an unaligned address"}
- {"dt": "2025-08-26T11:58:16.590835", "lvl": "target", "msg": "# ok 5 futex_waitv NULL address in waitv.uaddr"}
- {"dt": "2025-08-26T11:58:16.590983", "lvl": "target", "msg": "# ok 6 futex_waitv NULL address in *waiters"}
- {"dt": "2025-08-26T11:58:16.591053", "lvl": "target", "msg": "# ok 7 futex_waitv invalid clockid"}
- {"dt": "2025-08-26T11:58:16.591113", "lvl": "target", "msg": "# # Totals: pass:7 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:16.591171", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:16.591226", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:16.591279", "lvl": "target", "msg": "# 1..21"}
- {"dt": "2025-08-26T11:58:16.591331", "lvl": "target", "msg": "# ok 1 Basic get slots and immutable status."}
- {"dt": "2025-08-26T11:58:16.591383", "lvl": "target", "msg": "# ok 2 Automatic hash bucket init on thread creation."}
- {"dt": "2025-08-26T11:58:16.591433", "lvl": "target", "msg": "# # Online CPUs: 2"}
- {"dt": "2025-08-26T11:58:16.591483", "lvl": "target", "msg": "# ok 3 # SKIP Automatic increase with more than 16 CPUs"}
- {"dt": "2025-08-26T11:58:16.591533", "lvl": "target", "msg": "# ok 4 SET and GET slots 2 passed"}
- {"dt": "2025-08-26T11:58:16.613580", "lvl": "target", "msg": "# ok 5 SET and GET slots 4 passed"}
- {"dt": "2025-08-26T11:58:16.693934", "lvl": "target", "msg": "# ok 6 SET and GET slots 8 passed"}
- {"dt": "2025-08-26T11:58:16.773900", "lvl": "target", "msg": "# ok 7 SET and GET slots 32 passed"}
- {"dt": "2025-08-26T11:58:16.855152", "lvl": "target", "msg": "# ok 8 SET and GET slots 16 passed"}
- {"dt": "2025-08-26T11:58:16.855280", "lvl": "target", "msg": "# ok 9 Use 15 slots"}
- {"dt": "2025-08-26T11:58:16.937467", "lvl": "target", "msg": "# ok 10 SET and GET slots 2 passed"}
- {"dt": "2025-08-26T11:58:16.952200", "lvl": "target", "msg": "# ok 11 Created of waited for 64 of 64 threads"}
- {"dt": "2025-08-26T11:58:17.006457", "lvl": "target", "msg": "# ok 12 No more auto-resize after manaul setting, got 2"}
- {"dt": "2025-08-26T11:58:17.038823", "lvl": "target", "msg": "# ok 13 futex_hash_slots_set(536870912)"}
- {"dt": "2025-08-26T11:58:17.038938", "lvl": "target", "msg": "# ok 14 SET and GET slots 4 passed"}
- {"dt": "2025-08-26T11:58:17.179935", "lvl": "target", "msg": "# ok 15 Global hash request"}
- {"dt": "2025-08-26T11:58:17.192524", "lvl": "target", "msg": "# ok 16 futex_hash_slots_set(4)"}
- {"dt": "2025-08-26T11:58:17.192634", "lvl": "target", "msg": "# ok 17 futex_hash_slots_set(8)"}
- {"dt": "2025-08-26T11:58:17.192714", "lvl": "target", "msg": "# ok 18 futex_hash_slots_set(8)"}
- {"dt": "2025-08-26T11:58:17.202863", "lvl": "target", "msg": "# ok 19 futex_hash_slots_set(0)"}
- {"dt": "2025-08-26T11:58:17.207680", "lvl": "target", "msg": "# ok 20 futex_hash_slots_set(6)"}
- {"dt": "2025-08-26T11:58:17.249190", "lvl": "target", "msg": "# ok 21 Continue to use global hash"}
- {"dt": "2025-08-26T11:58:17.249389", "lvl": "target", "msg": "# # 1 skipped test(s) detected. Consider enabling relevant config options to improve coverage."}
- {"dt": "2025-08-26T11:58:17.266460", "lvl": "target", "msg": "# # Totals: pass:20 fail:0 xfail:0 xpass:0 skip:1 error:0"}
- {"dt": "2025-08-26T11:58:17.279055", "lvl": "target", "msg": "# ./futex_priv_hash: invalid option -- 'g'"}
- {"dt": "2025-08-26T11:58:17.279195", "lvl": "target", "msg": "# Usage: futex_priv_hash"}
- {"dt": "2025-08-26T11:58:17.279271", "lvl": "target", "msg": "#   -c    Use color"}
- {"dt": "2025-08-26T11:58:17.309053", "lvl": "target", "msg": "#   -g    Test global hash instead intead local immutable "}
- {"dt": "2025-08-26T11:58:17.314349", "lvl": "target", "msg": "#   -h    Display this help message"}
- {"dt": "2025-08-26T11:58:17.329367", "lvl": "target", "msg": "#   -v L  Verbosity level: 0=QUIET 1=CRITICAL 2=INFO"}
- {"dt": "2025-08-26T11:58:17.338933", "lvl": "target", "msg": "# "}
- {"dt": "2025-08-26T11:58:17.339048", "lvl": "target", "msg": "# TAP version 13"}
- {"dt": "2025-08-26T11:58:17.339145", "lvl": "target", "msg": "# 1..1"}
- {"dt": "2025-08-26T11:58:17.339219", "lvl": "target", "msg": "# # Regular test"}
- {"dt": "2025-08-26T11:58:17.339286", "lvl": "target", "msg": "# # Memory too small"}
- {"dt": "2025-08-26T11:58:17.360556", "lvl": "target", "msg": "# # Memory out of range"}
- {"dt": "2025-08-26T11:58:17.397235", "lvl": "target", "msg": "# # Memory, RO"}
- {"dt": "2025-08-26T11:58:17.443448", "lvl": "target", "msg": "# # Memory, no access"}
- {"dt": "2025-08-26T11:58:17.490437", "lvl": "target", "msg": "# # Memory back to RW"}
- {"dt": "2025-08-26T11:58:17.532337", "lvl": "target", "msg": "# ok 1 NUMA MPOL tests passed"}
- {"dt": "2025-08-26T11:58:17.536603", "lvl": "target", "msg": "# # Totals: pass:1 fail:0 xfail:0 xpass:0 skip:0 error:0"}
- {"dt": "2025-08-26T11:58:17.553756", "lvl": "target", "msg": "ok 1 selftests: futex: run.sh"}
- {"dt": "2025-08-26T11:58:19.394821", "lvl": "target", "msg": ""}
- {"dt": "2025-08-26T11:58:19.395208", "lvl": "target", "msg": "WARNING: Optional imports not found, TAP 13 output will be"}
- {"dt": "2025-08-26T11:58:19.395293", "lvl": "target", "msg": "    ignored. To parse yaml, see requirements in docs:"}
- {"dt": "2025-08-26T11:58:19.395363", "lvl": "target", "msg": "    https://tappy.readthedocs.io/en/latest/consumers.html#tap-version-13"}
- {"dt": "2025-08-26T11:58:19.395443", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_0ns pass"}
- {"dt": "2025-08-26T11:58:19.395509", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_0ns pass"}
- {"dt": "2025-08-26T11:58:19.395618", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_0ns pass"}
- {"dt": "2025-08-26T11:58:19.395826", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_0ns pass"}
- {"dt": "2025-08-26T11:58:19.396013", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_0ns pass"}
- {"dt": "2025-08-26T11:58:19.396209", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_1_timeout_0ns pass"}
- {"dt": "2025-08-26T11:58:19.396417", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_5000ns pass"}
- {"dt": "2025-08-26T11:58:19.396626", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns pass"}
- {"dt": "2025-08-26T11:58:19.396852", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_500000ns pass"}
- {"dt": "2025-08-26T11:58:19.397186", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns pass"}
- {"dt": "2025-08-26T11:58:19.397276", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_5000ns pass"}
- {"dt": "2025-08-26T11:58:19.397549", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_5000ns pass"}
- {"dt": "2025-08-26T11:58:19.397714", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_500000ns pass"}
- {"dt": "2025-08-26T11:58:19.397953", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_500000ns pass"}
- {"dt": "2025-08-26T11:58:19.398195", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_5000ns pass"}
- {"dt": "2025-08-26T11:58:19.398493", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns_dup2 pass"}
- {"dt": "2025-08-26T11:58:19.398895", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_500000ns pass"}
- {"dt": "2025-08-26T11:58:19.399171", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns_dup2 pass"}
- {"dt": "2025-08-26T11:58:19.399432", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_2000000000ns pass"}
- {"dt": "2025-08-26T11:58:19.399672", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_2000000000ns pass"}
- {"dt": "2025-08-26T11:58:19.399728", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi-mismatched-ops pass"}
- {"dt": "2025-08-26T11:58:19.399860", "lvl": "target", "msg": "futex_run_sh_futex-requeue-pi-signal-restart pass"}
- {"dt": "2025-08-26T11:58:19.400002", "lvl": "target", "msg": "futex_run_sh_futex_wait_relative_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.400170", "lvl": "target", "msg": "futex_run_sh_futex_wait_bitset_realtime_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.400303", "lvl": "target", "msg": "futex_run_sh_futex_wait_bitset_monotonic_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.400568", "lvl": "target", "msg": "futex_run_sh_futex_wait_requeue_pi_realtime_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.400618", "lvl": "target", "msg": "futex_run_sh_futex_wait_requeue_pi_monotonic_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.400671", "lvl": "target", "msg": "futex_run_sh_futex_lock_pi_realtime_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.400779", "lvl": "target", "msg": "futex_run_sh_futex_lock_pi_invalid_timeout_flag_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.400893", "lvl": "target", "msg": "futex_run_sh_futex_waitv_monotonic_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.400994", "lvl": "target", "msg": "futex_run_sh_futex_waitv_realtime_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.401056", "lvl": "target", "msg": "futex_run_sh_futex_wait pass"}
- {"dt": "2025-08-26T11:58:19.401125", "lvl": "target", "msg": "futex_run_sh_futex_waitv pass"}
- {"dt": "2025-08-26T11:58:19.401244", "lvl": "target", "msg": "futex_run_sh_futex-wait-uninitialized-heap pass"}
- {"dt": "2025-08-26T11:58:19.401348", "lvl": "target", "msg": "futex_run_sh_futex-wait-private-mapped-file pass"}
- {"dt": "2025-08-26T11:58:19.401450", "lvl": "target", "msg": "futex_run_sh_futex_wake_private_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.401581", "lvl": "target", "msg": "futex_run_sh_futex_wake_shared_page_anon_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.401712", "lvl": "target", "msg": "futex_run_sh_futex_wake_shared_file_backed_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.401825", "lvl": "target", "msg": "futex_run_sh_futex_requeue_simple_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.401933", "lvl": "target", "msg": "futex_run_sh_futex_requeue_many_succeeds pass"}
- {"dt": "2025-08-26T11:58:19.402018", "lvl": "target", "msg": "futex_run_sh_futex_waitv_private pass"}
- {"dt": "2025-08-26T11:58:19.402094", "lvl": "target", "msg": "futex_run_sh_futex_waitv_shared pass"}
- {"dt": "2025-08-26T11:58:19.402209", "lvl": "target", "msg": "futex_run_sh_futex_waitv_without_FUTEX_32 pass"}
- {"dt": "2025-08-26T11:58:19.402337", "lvl": "target", "msg": "futex_run_sh_futex_waitv_with_an_unaligned_address pass"}
- {"dt": "2025-08-26T11:58:19.402462", "lvl": "target", "msg": "futex_run_sh_futex_waitv_NULL_address_in_waitv_uaddr pass"}
- {"dt": "2025-08-26T11:58:19.402584", "lvl": "target", "msg": "futex_run_sh_futex_waitv_NULL_address_in_waiters pass"}
- {"dt": "2025-08-26T11:58:19.402679", "lvl": "target", "msg": "futex_run_sh_futex_waitv_invalid_clockid pass"}
- {"dt": "2025-08-26T11:58:19.402785", "lvl": "target", "msg": "futex_run_sh_Basic_get_slots_and_immutable_status pass"}
- {"dt": "2025-08-26T11:58:19.402923", "lvl": "target", "msg": "futex_run_sh_Automatic_hash_bucket_init_on_thread_creation pass"}
- {"dt": "2025-08-26T11:58:19.403048", "lvl": "target", "msg": "futex_run_sh_Automatic_increase_with_more_than_16_CPUs skip"}
- {"dt": "2025-08-26T11:58:19.403155", "lvl": "target", "msg": "futex_run_sh_SET_and_GET_slots_2_passed pass"}
- {"dt": "2025-08-26T11:58:19.403247", "lvl": "target", "msg": "futex_run_sh_SET_and_GET_slots_4_passed pass"}
- {"dt": "2025-08-26T11:58:19.403346", "lvl": "target", "msg": "futex_run_sh_SET_and_GET_slots_8_passed pass"}
- {"dt": "2025-08-26T11:58:19.403460", "lvl": "target", "msg": "futex_run_sh_SET_and_GET_slots_32_passed pass"}
- {"dt": "2025-08-26T11:58:19.403562", "lvl": "target", "msg": "futex_run_sh_SET_and_GET_slots_16_passed pass"}
- {"dt": "2025-08-26T11:58:19.403633", "lvl": "target", "msg": "futex_run_sh_Use_15_slots pass"}
- {"dt": "2025-08-26T11:58:19.403746", "lvl": "target", "msg": "futex_run_sh_SET_and_GET_slots_2_passed_dup2 pass"}
- {"dt": "2025-08-26T11:58:19.403862", "lvl": "target", "msg": "futex_run_sh_Created_of_waited_for_64_of_64_threads pass"}
- {"dt": "2025-08-26T11:58:19.404000", "lvl": "target", "msg": "futex_run_sh_No_more_auto-resize_after_manaul_setting_got_2 pass"}
- {"dt": "2025-08-26T11:58:19.404110", "lvl": "target", "msg": "futex_run_sh_futex_hash_slots_set_536870912 pass"}
- {"dt": "2025-08-26T11:58:19.404230", "lvl": "target", "msg": "futex_run_sh_SET_and_GET_slots_4_passed_dup2 pass"}
- {"dt": "2025-08-26T11:58:19.404322", "lvl": "target", "msg": "futex_run_sh_Global_hash_request pass"}
- {"dt": "2025-08-26T11:58:19.404409", "lvl": "target", "msg": "futex_run_sh_futex_hash_slots_set_4 pass"}
- {"dt": "2025-08-26T11:58:19.404498", "lvl": "target", "msg": "futex_run_sh_futex_hash_slots_set_8 pass"}
- {"dt": "2025-08-26T11:58:19.404606", "lvl": "target", "msg": "futex_run_sh_futex_hash_slots_set_8_dup2 pass"}
- {"dt": "2025-08-26T11:58:19.404844", "lvl": "target", "msg": "futex_run_sh_futex_hash_slots_set_0 pass"}
- {"dt": "2025-08-26T11:58:19.405239", "lvl": "target", "msg": "futex_run_sh_futex_hash_slots_set_6 pass"}
- {"dt": "2025-08-26T11:58:19.405290", "lvl": "target", "msg": "futex_run_sh_Continue_to_use_global_hash pass"}
- {"dt": "2025-08-26T11:58:19.405344", "lvl": "target", "msg": "futex_run_sh_NUMA_MPOL_tests_passed pass"}
- {"dt": "2025-08-26T11:58:19.405400", "lvl": "target", "msg": "futex_run_sh pass"}
- {"dt": "2025-08-26T11:58:19.441727", "lvl": "target", "msg": "+ ../../utils/send-to-lava.sh ./output/result.txt"}
- {"dt": "2025-08-26T11:58:19.510394", "lvl": "target", "msg": "<8>[   63.439219] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=shardfile-futex RESULT=pass>"}
- {"dt": "2025-08-26T11:58:19.510773", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=shardfile-futex RESULT=pass"}
- {"dt": "2025-08-26T11:58:19.510890", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "shardfile-futex", "result": "pass", "starttc": ! "1035", "endtc": ! "1035"}}
- {"dt": "2025-08-26T11:58:19.740513", "lvl": "target", "msg": "<8>[   63.669319] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_0ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:19.740876", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_0ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:19.740981", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_0ns", "result": "pass", "starttc": ! "1038", "endtc": ! "1038"}}
- {"dt": "2025-08-26T11:58:19.783690", "lvl": "target", "msg": "<8>[   63.712482] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_0ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:19.784044", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_0ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:19.784149", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_0ns", "result": "pass", "starttc": ! "1041", "endtc": ! "1041"}}
- {"dt": "2025-08-26T11:58:19.825431", "lvl": "target", "msg": "<8>[   63.753892] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_0ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:19.825768", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_0ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:19.825870", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_0ns", "result": "pass", "starttc": ! "1044", "endtc": ! "1044"}}
- {"dt": "2025-08-26T11:58:19.867225", "lvl": "target", "msg": "<8>[   63.795967] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_0ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:19.867554", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_0ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:19.867645", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_0ns", "result": "pass", "starttc": ! "1047", "endtc": ! "1047"}}
- {"dt": "2025-08-26T11:58:19.909139", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_0ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:19.909297", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_0ns", "result": "pass", "starttc": ! "1049", "endtc": ! "1049"}}
- {"dt": "2025-08-26T11:58:19.909421", "lvl": "target", "msg": "<8>[   63.837291] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_0ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:19.951151", "lvl": "target", "msg": "<8>[   63.879706] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_1_timeout_0ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:19.951497", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_1_timeout_0ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:19.951598", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_1_timeout_0ns", "result": "pass", "starttc": ! "1053", "endtc": ! "1053"}}
- {"dt": "2025-08-26T11:58:19.993659", "lvl": "target", "msg": "<8>[   63.922209] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_5000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:19.994009", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_5000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:19.994117", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_5000ns", "result": "pass", "starttc": ! "1056", "endtc": ! "1056"}}
- {"dt": "2025-08-26T11:58:20.034709", "lvl": "target", "msg": "<8>[   63.963284] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.035068", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.035186", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns", "result": "pass", "starttc": ! "1059", "endtc": ! "1059"}}
- {"dt": "2025-08-26T11:58:20.078737", "lvl": "target", "msg": "<8>[   64.007209] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_500000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.079091", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_500000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.079188", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_500000ns", "result": "pass", "starttc": ! "1062", "endtc": ! "1062"}}
- {"dt": "2025-08-26T11:58:20.122244", "lvl": "target", "msg": "<8>[   64.051002] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.122591", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.122689", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns", "result": "pass", "starttc": ! "1065", "endtc": ! "1065"}}
- {"dt": "2025-08-26T11:58:20.167548", "lvl": "target", "msg": "<8>[   64.095850] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_5000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.167862", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_5000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.167931", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_5000ns", "result": "pass", "starttc": ! "1068", "endtc": ! "1068"}}
- {"dt": "2025-08-26T11:58:20.210226", "lvl": "target", "msg": "<8>[   64.138982] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_5000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.210578", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_5000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.210647", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_5000ns", "result": "pass", "starttc": ! "1071", "endtc": ! "1071"}}
- {"dt": "2025-08-26T11:58:20.253679", "lvl": "target", "msg": "<8>[   64.182152] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_500000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.254049", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_500000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.254145", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_500000ns", "result": "pass", "starttc": ! "1074", "endtc": ! "1074"}}
- {"dt": "2025-08-26T11:58:20.296764", "lvl": "target", "msg": "<8>[   64.225556] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_500000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.297121", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_500000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.297221", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_500000ns", "result": "pass", "starttc": ! "1077", "endtc": ! "1077"}}
- {"dt": "2025-08-26T11:58:20.338128", "lvl": "target", "msg": "<8>[   64.266865] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_5000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.338461", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_5000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.338576", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_5000ns", "result": "pass", "starttc": ! "1080", "endtc": ! "1080"}}
- {"dt": "2025-08-26T11:58:20.382555", "lvl": "target", "msg": "<8>[   64.310577] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns_dup2 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.382896", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns_dup2 RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.382965", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns_dup2", "result": "pass", "starttc": ! "1083", "endtc": ! "1083"}}
- {"dt": "2025-08-26T11:58:20.424338", "lvl": "target", "msg": "<8>[   64.352862] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_500000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.424699", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_500000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.424811", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_500000ns", "result": "pass", "starttc": ! "1086", "endtc": ! "1086"}}
- {"dt": "2025-08-26T11:58:20.466448", "lvl": "target", "msg": "<8>[   64.394934] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns_dup2 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.466797", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns_dup2 RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.466921", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns_dup2", "result": "pass", "starttc": ! "1089", "endtc": ! "1089"}}
- {"dt": "2025-08-26T11:58:20.507772", "lvl": "target", "msg": "<8>[   64.436511] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_2000000000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.508125", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_2000000000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.508237", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_2000000000ns", "result": "pass", "starttc": ! "1092", "endtc": ! "1092"}}
- {"dt": "2025-08-26T11:58:20.550231", "lvl": "target", "msg": "<8>[   64.478822] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_2000000000ns RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.550540", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_2000000000ns RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.550612", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_2000000000ns", "result": "pass", "starttc": ! "1095", "endtc": ! "1095"}}
- {"dt": "2025-08-26T11:58:20.590744", "lvl": "target", "msg": "<8>[   64.519583] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi-mismatched-ops RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.591105", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi-mismatched-ops RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.591207", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi-mismatched-ops", "result": "pass", "starttc": ! "1098", "endtc": ! "1098"}}
- {"dt": "2025-08-26T11:58:20.633278", "lvl": "target", "msg": "<8>[   64.561804] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-requeue-pi-signal-restart RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.633604", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-requeue-pi-signal-restart RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.633672", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-requeue-pi-signal-restart", "result": "pass", "starttc": ! "1101", "endtc": ! "1101"}}
- {"dt": "2025-08-26T11:58:20.673585", "lvl": "target", "msg": "<8>[   64.602356] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wait_relative_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.673949", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wait_relative_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.674067", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wait_relative_succeeds", "result": "pass", "starttc": ! "1104", "endtc": ! "1104"}}
- {"dt": "2025-08-26T11:58:20.715368", "lvl": "target", "msg": "<8>[   64.644197] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wait_bitset_realtime_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.715719", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wait_bitset_realtime_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.715837", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wait_bitset_realtime_succeeds", "result": "pass", "starttc": ! "1107", "endtc": ! "1107"}}
- {"dt": "2025-08-26T11:58:20.757141", "lvl": "target", "msg": "<8>[   64.685802] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wait_bitset_monotonic_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.757497", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wait_bitset_monotonic_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.757600", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wait_bitset_monotonic_succeeds", "result": "pass", "starttc": ! "1110", "endtc": ! "1110"}}
- {"dt": "2025-08-26T11:58:20.798970", "lvl": "target", "msg": "<8>[   64.727363] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wait_requeue_pi_realtime_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.799322", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wait_requeue_pi_realtime_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.799438", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wait_requeue_pi_realtime_succeeds", "result": "pass", "starttc": ! "1113", "endtc": ! "1113"}}
- {"dt": "2025-08-26T11:58:20.841415", "lvl": "target", "msg": "<8>[   64.770273] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wait_requeue_pi_monotonic_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.841766", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wait_requeue_pi_monotonic_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.841873", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wait_requeue_pi_monotonic_succeeds", "result": "pass", "starttc": ! "1116", "endtc": ! "1116"}}
- {"dt": "2025-08-26T11:58:20.881835", "lvl": "target", "msg": "<8>[   64.810646] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_lock_pi_realtime_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.882185", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_lock_pi_realtime_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.882288", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_lock_pi_realtime_succeeds", "result": "pass", "starttc": ! "1119", "endtc": ! "1119"}}
- {"dt": "2025-08-26T11:58:20.925128", "lvl": "target", "msg": "<8>[   64.853656] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_lock_pi_invalid_timeout_flag_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.925520", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_lock_pi_invalid_timeout_flag_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.925671", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_lock_pi_invalid_timeout_flag_succeeds", "result": "pass", "starttc": ! "1122", "endtc": ! "1122"}}
- {"dt": "2025-08-26T11:58:20.965475", "lvl": "target", "msg": "<8>[   64.894299] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_monotonic_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:20.965819", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_monotonic_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:20.965923", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_monotonic_succeeds", "result": "pass", "starttc": ! "1125", "endtc": ! "1125"}}
- {"dt": "2025-08-26T11:58:21.008987", "lvl": "target", "msg": "<8>[   64.937527] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_realtime_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.009325", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_realtime_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.009395", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_realtime_succeeds", "result": "pass", "starttc": ! "1128", "endtc": ! "1128"}}
- {"dt": "2025-08-26T11:58:21.052374", "lvl": "target", "msg": "<8>[   64.981062] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wait RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.052723", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wait RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.052817", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wait", "result": "pass", "starttc": ! "1131", "endtc": ! "1131"}}
- {"dt": "2025-08-26T11:58:21.091817", "lvl": "target", "msg": "<8>[   65.020643] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.092179", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.092272", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv", "result": "pass", "starttc": ! "1134", "endtc": ! "1134"}}
- {"dt": "2025-08-26T11:58:21.135557", "lvl": "target", "msg": "<8>[   65.064167] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-wait-uninitialized-heap RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.135925", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-wait-uninitialized-heap RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.136064", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-wait-uninitialized-heap", "result": "pass", "starttc": ! "1137", "endtc": ! "1137"}}
- {"dt": "2025-08-26T11:58:21.176919", "lvl": "target", "msg": "<8>[   65.105616] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex-wait-private-mapped-file RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.177278", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex-wait-private-mapped-file RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.177408", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex-wait-private-mapped-file", "result": "pass", "starttc": ! "1140", "endtc": ! "1140"}}
- {"dt": "2025-08-26T11:58:21.217491", "lvl": "target", "msg": "<8>[   65.146347] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wake_private_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.217864", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wake_private_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.217970", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wake_private_succeeds", "result": "pass", "starttc": ! "1143", "endtc": ! "1143"}}
- {"dt": "2025-08-26T11:58:21.258872", "lvl": "target", "msg": "<8>[   65.187624] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wake_shared_page_anon_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.259228", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wake_shared_page_anon_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.259327", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wake_shared_page_anon_succeeds", "result": "pass", "starttc": ! "1146", "endtc": ! "1146"}}
- {"dt": "2025-08-26T11:58:21.302126", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_wake_shared_file_backed_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.302333", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_wake_shared_file_backed_succeeds", "result": "pass", "starttc": ! "1148", "endtc": ! "1148"}}
- {"dt": "2025-08-26T11:58:21.302574", "lvl": "target", "msg": "<8>[   65.230473] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_wake_shared_file_backed_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.341926", "lvl": "target", "msg": "<8>[   65.270718] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_requeue_simple_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.342299", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_requeue_simple_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.342440", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_requeue_simple_succeeds", "result": "pass", "starttc": ! "1152", "endtc": ! "1152"}}
- {"dt": "2025-08-26T11:58:21.387229", "lvl": "target", "msg": "<8>[   65.315967] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_requeue_many_succeeds RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.387590", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_requeue_many_succeeds RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.387706", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_requeue_many_succeeds", "result": "pass", "starttc": ! "1155", "endtc": ! "1155"}}
- {"dt": "2025-08-26T11:58:21.427697", "lvl": "target", "msg": "<8>[   65.356087] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_private RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.428062", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_private RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.428182", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_private", "result": "pass", "starttc": ! "1158", "endtc": ! "1158"}}
- {"dt": "2025-08-26T11:58:21.471291", "lvl": "target", "msg": "<8>[   65.400074] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_shared RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.471635", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_shared RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.471745", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_shared", "result": "pass", "starttc": ! "1161", "endtc": ! "1161"}}
- {"dt": "2025-08-26T11:58:21.514331", "lvl": "target", "msg": "<8>[   65.443156] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_without_FUTEX_32 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.514704", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_without_FUTEX_32 RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.514830", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_without_FUTEX_32", "result": "pass", "starttc": ! "1164", "endtc": ! "1164"}}
- {"dt": "2025-08-26T11:58:21.557293", "lvl": "target", "msg": "<8>[   65.486112] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_with_an_unaligned_address RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.557649", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_with_an_unaligned_address RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.557746", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_with_an_unaligned_address", "result": "pass", "starttc": ! "1167", "endtc": ! "1167"}}
- {"dt": "2025-08-26T11:58:21.602874", "lvl": "target", "msg": "<8>[   65.531384] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_NULL_address_in_waitv_uaddr RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.603222", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_NULL_address_in_waitv_uaddr RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.603295", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_NULL_address_in_waitv_uaddr", "result": "pass", "starttc": ! "1170", "endtc": ! "1170"}}
- {"dt": "2025-08-26T11:58:21.643531", "lvl": "target", "msg": "<8>[   65.572360] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_NULL_address_in_waiters RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.643860", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_NULL_address_in_waiters RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.643965", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_NULL_address_in_waiters", "result": "pass", "starttc": ! "1173", "endtc": ! "1173"}}
- {"dt": "2025-08-26T11:58:21.685926", "lvl": "target", "msg": "<8>[   65.614682] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_waitv_invalid_clockid RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.686288", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_waitv_invalid_clockid RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.686387", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_waitv_invalid_clockid", "result": "pass", "starttc": ! "1176", "endtc": ! "1176"}}
- {"dt": "2025-08-26T11:58:21.728164", "lvl": "target", "msg": "<8>[   65.656696] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_Basic_get_slots_and_immutable_status RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.728511", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_Basic_get_slots_and_immutable_status RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.728598", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_Basic_get_slots_and_immutable_status", "result": "pass", "starttc": ! "1179", "endtc": ! "1179"}}
- {"dt": "2025-08-26T11:58:21.768987", "lvl": "target", "msg": "<8>[   65.697639] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_Automatic_hash_bucket_init_on_thread_creation RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.769367", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_Automatic_hash_bucket_init_on_thread_creation RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.769538", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_Automatic_hash_bucket_init_on_thread_creation", "result": "pass", "starttc": ! "1182", "endtc": ! "1182"}}
- {"dt": "2025-08-26T11:58:21.809376", "lvl": "target", "msg": "<8>[   65.738214] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_Automatic_increase_with_more_than_16_CPUs RESULT=skip>"}
- {"dt": "2025-08-26T11:58:21.809726", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_Automatic_increase_with_more_than_16_CPUs RESULT=skip"}
- {"dt": "2025-08-26T11:58:21.809828", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_Automatic_increase_with_more_than_16_CPUs", "result": "skip", "starttc": ! "1185", "endtc": ! "1185"}}
- {"dt": "2025-08-26T11:58:21.849333", "lvl": "target", "msg": "<8>[   65.777713] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_2_passed RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.849678", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_2_passed RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.849766", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_SET_and_GET_slots_2_passed", "result": "pass", "starttc": ! "1188", "endtc": ! "1188"}}
- {"dt": "2025-08-26T11:58:21.890625", "lvl": "target", "msg": "<8>[   65.818858] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_4_passed RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.890981", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_4_passed RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.891084", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_SET_and_GET_slots_4_passed", "result": "pass", "starttc": ! "1191", "endtc": ! "1191"}}
- {"dt": "2025-08-26T11:58:21.934164", "lvl": "target", "msg": "<8>[   65.862852] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_8_passed RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.934527", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_8_passed RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.934629", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_SET_and_GET_slots_8_passed", "result": "pass", "starttc": ! "1194", "endtc": ! "1194"}}
- {"dt": "2025-08-26T11:58:21.974286", "lvl": "target", "msg": "<8>[   65.903082] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_32_passed RESULT=pass>"}
- {"dt": "2025-08-26T11:58:21.974615", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_32_passed RESULT=pass"}
- {"dt": "2025-08-26T11:58:21.974730", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_SET_and_GET_slots_32_passed", "result": "pass", "starttc": ! "1197", "endtc": ! "1197"}}
- {"dt": "2025-08-26T11:58:22.015838", "lvl": "target", "msg": "<8>[   65.944680] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_16_passed RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.016171", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_16_passed RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.016289", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_SET_and_GET_slots_16_passed", "result": "pass", "starttc": ! "1200", "endtc": ! "1200"}}
- {"dt": "2025-08-26T11:58:22.057328", "lvl": "target", "msg": "<8>[   65.986090] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_Use_15_slots RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.057680", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_Use_15_slots RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.057825", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_Use_15_slots", "result": "pass", "starttc": ! "1203", "endtc": ! "1203"}}
- {"dt": "2025-08-26T11:58:22.099394", "lvl": "target", "msg": "<8>[   66.028120] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_2_passed_dup2 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.099748", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_2_passed_dup2 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.099846", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_SET_and_GET_slots_2_passed_dup2", "result": "pass", "starttc": ! "1206", "endtc": ! "1206"}}
- {"dt": "2025-08-26T11:58:22.142934", "lvl": "target", "msg": "<8>[   66.071417] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_Created_of_waited_for_64_of_64_threads RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.143299", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_Created_of_waited_for_64_of_64_threads RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.143423", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_Created_of_waited_for_64_of_64_threads", "result": "pass", "starttc": ! "1209", "endtc": ! "1209"}}
- {"dt": "2025-08-26T11:58:22.183811", "lvl": "target", "msg": "<8>[   66.112315] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_No_more_auto-resize_after_manaul_setting_got_2 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.184171", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_No_more_auto-resize_after_manaul_setting_got_2 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.184284", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_No_more_auto-resize_after_manaul_setting_got_2", "result": "pass", "starttc": ! "1212", "endtc": ! "1212"}}
- {"dt": "2025-08-26T11:58:22.224724", "lvl": "target", "msg": "<8>[   66.153392] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_536870912 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.225089", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_536870912 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.225224", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_hash_slots_set_536870912", "result": "pass", "starttc": ! "1215", "endtc": ! "1215"}}
- {"dt": "2025-08-26T11:58:22.267223", "lvl": "target", "msg": "<8>[   66.195672] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_4_passed_dup2 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.267606", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_SET_and_GET_slots_4_passed_dup2 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.267709", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_SET_and_GET_slots_4_passed_dup2", "result": "pass", "starttc": ! "1218", "endtc": ! "1218"}}
- {"dt": "2025-08-26T11:58:22.307488", "lvl": "target", "msg": "<8>[   66.236319] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_Global_hash_request RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.307846", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_Global_hash_request RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.307957", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_Global_hash_request", "result": "pass", "starttc": ! "1221", "endtc": ! "1221"}}
- {"dt": "2025-08-26T11:58:22.348384", "lvl": "target", "msg": "<8>[   66.276944] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_4 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.348733", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_4 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.348829", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_hash_slots_set_4", "result": "pass", "starttc": ! "1224", "endtc": ! "1224"}}
- {"dt": "2025-08-26T11:58:22.390095", "lvl": "target", "msg": "<8>[   66.318802] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_8 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.390436", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_8 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.390530", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_hash_slots_set_8", "result": "pass", "starttc": ! "1227", "endtc": ! "1227"}}
- {"dt": "2025-08-26T11:58:22.433194", "lvl": "target", "msg": "<8>[   66.361709] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_8_dup2 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.433548", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_8_dup2 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.433667", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_hash_slots_set_8_dup2", "result": "pass", "starttc": ! "1230", "endtc": ! "1230"}}
- {"dt": "2025-08-26T11:58:22.475225", "lvl": "target", "msg": "<8>[   66.403951] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_0 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.475589", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_0 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.475729", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_hash_slots_set_0", "result": "pass", "starttc": ! "1233", "endtc": ! "1233"}}
- {"dt": "2025-08-26T11:58:22.517594", "lvl": "target", "msg": "<8>[   66.446298] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_6 RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.517952", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_futex_hash_slots_set_6 RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.518054", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_futex_hash_slots_set_6", "result": "pass", "starttc": ! "1236", "endtc": ! "1236"}}
- {"dt": "2025-08-26T11:58:22.560880", "lvl": "target", "msg": "<8>[   66.489664] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_Continue_to_use_global_hash RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.561240", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_Continue_to_use_global_hash RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.561372", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_Continue_to_use_global_hash", "result": "pass", "starttc": ! "1239", "endtc": ! "1239"}}
- {"dt": "2025-08-26T11:58:22.602931", "lvl": "target", "msg": "<8>[   66.531766] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh_NUMA_MPOL_tests_passed RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.603291", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh_NUMA_MPOL_tests_passed RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.603425", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh_NUMA_MPOL_tests_passed", "result": "pass", "starttc": ! "1242", "endtc": ! "1242"}}
- {"dt": "2025-08-26T11:58:22.644224", "lvl": "target", "msg": "<8>[   66.572895] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=futex_run_sh RESULT=pass>"}
- {"dt": "2025-08-26T11:58:22.644611", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=futex_run_sh RESULT=pass"}
- {"dt": "2025-08-26T11:58:22.644738", "lvl": "results", "msg": {"definition": "0_kselftest-futex", "case": "futex_run_sh", "result": "pass", "starttc": ! "1245", "endtc": ! "1245"}}
- {"dt": "2025-08-26T11:58:22.646464", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-08-26T11:58:22.647117", "lvl": "target", "msg": "<8>[   66.576043] <LAVA_SIGNAL_ENDRUN 0_kselftest-futex 1_1.1.3.1>"}
- {"dt": "2025-08-26T11:58:22.647389", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_kselftest-futex 1_1.1.3.1"}
- {"dt": "2025-08-26T11:58:22.647482", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2025-08-26T11:58:22.647542", "lvl": "info", "msg": "Ending test lava.0_kselftest-futex (1_1.1.3.1), duration 18.48"}
- {"dt": "2025-08-26T11:58:22.647619", "lvl": "results", "msg": {"definition": "lava", "case": "0_kselftest-futex", "uuid": "1_1.1.3.1", "repository": "file:///home/anders/.cache/tuxrun/assets/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar.zst", "path": "automated/linux/kselftest/kselftest.yaml", "duration": "18.48", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2025-08-26T11:58:22.650298", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2025-08-26T11:58:22.650567", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2025-08-26T11:58:22.651061", "lvl": "debug", "msg": "shardfile-futex: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_0ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_0ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_0ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_0ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_0ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_1_timeout_0ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_5000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_500000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_5000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_5000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_0_timeout_500000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_0_owner_0_timeout_500000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_5000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_5000ns_dup2: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_0_owner_1_timeout_500000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_500000ns_dup2: pass\nfutex_run_sh_futex-requeue-pi_broadcast_1_locked_1_owner_0_timeout_2000000000ns: pass\nfutex_run_sh_futex-requeue-pi_broadcast_0_locked_1_owner_0_timeout_2000000000ns: pass\nfutex_run_sh_futex-requeue-pi-mismatched-ops: pass\nfutex_run_sh_futex-requeue-pi-signal-restart: pass\nfutex_run_sh_futex_wait_relative_succeeds: pass\nfutex_run_sh_futex_wait_bitset_realtime_succeeds: pass\nfutex_run_sh_futex_wait_bitset_monotonic_succeeds: pass\nfutex_run_sh_futex_wait_requeue_pi_realtime_succeeds: pass\nfutex_run_sh_futex_wait_requeue_pi_monotonic_succeeds: pass\nfutex_run_sh_futex_lock_pi_realtime_succeeds: pass\nfutex_run_sh_futex_lock_pi_invalid_timeout_flag_succeeds: pass\nfutex_run_sh_futex_waitv_monotonic_succeeds: pass\nfutex_run_sh_futex_waitv_realtime_succeeds: pass\nfutex_run_sh_futex_wait: pass\nfutex_run_sh_futex_waitv: pass\nfutex_run_sh_futex-wait-uninitialized-heap: pass\nfutex_run_sh_futex-wait-private-mapped-file: pass\nfutex_run_sh_futex_wake_private_succeeds: pass\nfutex_run_sh_futex_wake_shared_page_anon_succeeds: pass\nfutex_run_sh_futex_wake_shared_file_backed_succeeds: pass\nfutex_run_sh_futex_requeue_simple_succeeds: pass\nfutex_run_sh_futex_requeue_many_succeeds: pass\nfutex_run_sh_futex_waitv_private: pass\nfutex_run_sh_futex_waitv_shared: pass\nfutex_run_sh_futex_waitv_without_FUTEX_32: pass\nfutex_run_sh_futex_waitv_with_an_unaligned_address: pass\nfutex_run_sh_futex_waitv_NULL_address_in_waitv_uaddr: pass\nfutex_run_sh_futex_waitv_NULL_address_in_waiters: pass\nfutex_run_sh_futex_waitv_invalid_clockid: pass\nfutex_run_sh_Basic_get_slots_and_immutable_status: pass\nfutex_run_sh_Automatic_hash_bucket_init_on_thread_creation: pass\nfutex_run_sh_Automatic_increase_with_more_than_16_CPUs: skip\nfutex_run_sh_SET_and_GET_slots_2_passed: pass\nfutex_run_sh_SET_and_GET_slots_4_passed: pass\nfutex_run_sh_SET_and_GET_slots_8_passed: pass\nfutex_run_sh_SET_and_GET_slots_32_passed: pass\nfutex_run_sh_SET_and_GET_slots_16_passed: pass\nfutex_run_sh_Use_15_slots: pass\nfutex_run_sh_SET_and_GET_slots_2_passed_dup2: pass\nfutex_run_sh_Created_of_waited_for_64_of_64_threads: pass\nfutex_run_sh_No_more_auto-resize_after_manaul_setting_got_2: pass\nfutex_run_sh_futex_hash_slots_set_536870912: pass\nfutex_run_sh_SET_and_GET_slots_4_passed_dup2: pass\nfutex_run_sh_Global_hash_request: pass\nfutex_run_sh_futex_hash_slots_set_4: pass\nfutex_run_sh_futex_hash_slots_set_8: pass\nfutex_run_sh_futex_hash_slots_set_8_dup2: pass\nfutex_run_sh_futex_hash_slots_set_0: pass\nfutex_run_sh_futex_hash_slots_set_6: pass\nfutex_run_sh_Continue_to_use_global_hash: pass\nfutex_run_sh_NUMA_MPOL_tests_passed: pass\nfutex_run_sh: pass\n"}
- {"dt": "2025-08-26T11:58:22.651210", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:19) [common]"}
- {"dt": "2025-08-26T11:58:22.651287", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:19) [common]"}
- {"dt": "2025-08-26T11:58:22.651373", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2025-08-26T11:58:22.651466", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2025-08-26T11:58:22.651541", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2025-08-26T11:58:22.651612", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2025-08-26T11:58:22.651735", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-08-26T11:58:22.654594", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-08-26T11:58:22.655199", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-08-26T11:58:22.655475", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-08-26T11:58:23.652752", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2025-08-26T11:58:23.652913", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2025-08-26T11:58:23.753144", "lvl": "input", "msg": "poweroff"}
- {"dt": "2025-08-26T11:58:23.753599", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-08-26T11:58:23.753709", "lvl": "target", "msg": "[?2004hroot@runner-j1aldqxsn-project-40964107-concurrent-0:~# poweroff"}
- {"dt": "2025-08-26T11:58:23.854095", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2025-08-26T11:58:23.854278", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-08-26T11:58:23.854380", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2025-08-26T11:58:23.854487", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2025-08-26T11:58:23.854589", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/url-repo-action-ac22kw9k/url_repo/https___github.com_Linaro_test-definitions_releases_download_2025.07.02_2025.07.tar"}
- {"dt": "2025-08-26T11:58:23.862416", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/kernel/zImage"}
- {"dt": "2025-08-26T11:58:23.864567", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs/https___storage.tuxboot.com_debian_20250722_trixie_armhf_rootfs.ext4"}
- {"dt": "2025-08-26T11:58:23.955328", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs.kselftest/kselftest.tar"}
- {"dt": "2025-08-26T11:58:23.962032", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1/deployimages-cymoydup/rootfs.modules/modules.tar"}
- {"dt": "2025-08-26T11:58:23.966851", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-quizq7h_-1"}
- {"dt": "2025-08-26T11:58:24.005414", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2025-08-26T11:58:24.005544", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
