- {"dt": "2025-04-08T12:44:16.738924", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.09.dev0100"}
- {"dt": "2025-04-08T12:44:16.739010", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2025-04-08T12:44:16.739086", "lvl": "info", "msg": "Start time: 2025-04-08 12:44:16.739079+00:00 (UTC)"}
- {"dt": "2025-04-08T12:44:16.739679", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst exists"}
- {"dt": "2025-04-08T12:44:16.739967", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tuxtest-buildroot/builds/riscv64/images/Image exists"}
- {"dt": "2025-04-08T12:44:16.740062", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tuxtest-buildroot/builds/riscv64/images/rootfs.ext2.zst exists"}
- {"dt": "2025-04-08T12:44:16.752702", "lvl": "info", "msg": "qemu, installed at version: 9.2.0, host architecture: x86_64"}
- {"dt": "2025-04-08T12:44:16.752919", "lvl": "warning", "msg": "Missing image arg for url_repo"}
- {"dt": "2025-04-08T12:44:16.753105", "lvl": "info", "msg": "validate duration: 0.01"}
- {"dt": "2025-04-08T12:44:16.753165", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2025-04-08T12:44:16.753273", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:16.753349", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:16.753510", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud"}
- {"dt": "2025-04-08T12:44:16.753655", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin"}
- {"dt": "2025-04-08T12:44:16.753769", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/tests"}
- {"dt": "2025-04-08T12:44:16.753878", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/results"}
- {"dt": "2025-04-08T12:44:16.753961", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-add-keys"}
- {"dt": "2025-04-08T12:44:16.754098", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-add-sources"}
- {"dt": "2025-04-08T12:44:16.754232", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-background-process-start"}
- {"dt": "2025-04-08T12:44:16.754369", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-background-process-stop"}
- {"dt": "2025-04-08T12:44:16.754506", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-common-functions"}
- {"dt": "2025-04-08T12:44:16.754636", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2025-04-08T12:44:16.754770", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-install-packages"}
- {"dt": "2025-04-08T12:44:16.754902", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-installed-packages"}
- {"dt": "2025-04-08T12:44:16.755030", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-os-build"}
- {"dt": "2025-04-08T12:44:16.755161", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-probe-channel"}
- {"dt": "2025-04-08T12:44:16.755290", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-probe-ip"}
- {"dt": "2025-04-08T12:44:16.755426", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-target-ip"}
- {"dt": "2025-04-08T12:44:16.755576", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-target-mac"}
- {"dt": "2025-04-08T12:44:16.755710", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-target-storage"}
- {"dt": "2025-04-08T12:44:16.755829", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-test-case"}
- {"dt": "2025-04-08T12:44:16.755947", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-test-event"}
- {"dt": "2025-04-08T12:44:16.756069", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-test-feedback"}
- {"dt": "2025-04-08T12:44:16.756181", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-test-raise"}
- {"dt": "2025-04-08T12:44:16.756293", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-test-reference"}
- {"dt": "2025-04-08T12:44:16.756404", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-test-runner"}
- {"dt": "2025-04-08T12:44:16.756515", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-test-set"}
- {"dt": "2025-04-08T12:44:16.756627", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-test-shell"}
- {"dt": "2025-04-08T12:44:16.756747", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2025-04-08T12:44:16.756898", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2025-04-08T12:44:16.757007", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/environment"}
- {"dt": "2025-04-08T12:44:16.757095", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2025-04-08T12:44:16.757144", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2025-04-08T12:44:16.757185", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2025-04-08T12:44:16.757258", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:16.757303", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2025-04-08T12:44:16.757354", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:16.757410", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:16.757450", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2025-04-08T12:44:16.757496", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:16.757548", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:16.757595", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2025-04-08T12:44:16.757651", "lvl": "debug", "msg": "start: 1.1.3.1 url-repo-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:16.757703", "lvl": "debug", "msg": "start: 1.1.3.1.1 download-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:16.757766", "lvl": "debug", "msg": "start: 1.1.3.1.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-08T12:44:16.757887", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst"}
- {"dt": "2025-04-08T12:44:16.757936", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/url-repo-action-e1t2c_ox/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar"}
- {"dt": "2025-04-08T12:44:16.757977", "lvl": "debug", "msg": "total size: 13699163 (13\u202FMB)"}
- {"dt": "2025-04-08T12:44:16.758017", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2025-04-08T12:44:16.806666", "lvl": "info", "msg": "13\u202FMB downloaded in 0.05\u202Fs (268.40\u202FMB/s)"}
- {"dt": "2025-04-08T12:44:16.806913", "lvl": "debug", "msg": "end: 1.1.3.1.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:16.807007", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.1.3.1.1.1", "duration": "0.05", "result": "pass", "extra": {"label": "url_repo", "size": ! "13699163", "sha256sum": "c698fbe2d59f0074b6bb5452396ed885d6a88758ddf2e4bb9581b86740287da9"}}}
- {"dt": "2025-04-08T12:44:16.807225", "lvl": "debug", "msg": "end: 1.1.3.1.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:16.807336", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2025-04-08T12:44:16.807478", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-08T12:44:16.807614", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/url-repo-action-e1t2c_ox/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-08T12:44:17.000226", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.yaml"}
- {"dt": "2025-04-08T12:44:17.000527", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2025-04-08T12:44:17.000608", "lvl": "debug", "msg": "end: 1.1.3.1 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:17.000685", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:17.001297", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:17.001351", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-overlay", "level": "1.1.3.2", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "ltp-smoke", "path": "automated/linux/ltp/ltp.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst"}}}
- {"dt": "2025-04-08T12:44:17.001461", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:17.002290", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:17.002346", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-install-overlay", "level": "1.1.3.3", "duration": "0.00", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "ltp-smoke", "path": "automated/linux/ltp/ltp.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst", "skipped test-install-overlay": "1_1.1.3.1"}}}
- {"dt": "2025-04-08T12:44:17.002466", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:17.010630", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/0/tests/0_ltp-smoke test_uuid 1_1.1.3.1"}
- {"dt": "2025-04-08T12:44:17.010693", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2025-04-08T12:44:17.010736", "lvl": "debug", "msg": "TST_CMDFILES='smoketest'"}
- {"dt": "2025-04-08T12:44:17.010776", "lvl": "debug", "msg": "SKIPFILE='/tuxtest/skipfiles/riscv64/ltp-smoke'"}
- {"dt": "2025-04-08T12:44:17.010812", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2025-04-08T12:44:17.010847", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2025-04-08T12:44:17.010884", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2025-04-08T12:44:17.010920", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2025-04-08T12:44:17.010957", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2025-04-08T12:44:17.010992", "lvl": "debug", "msg": "RUNNER=''"}
- {"dt": "2025-04-08T12:44:17.011028", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2025-04-08T12:44:17.011147", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:17.011201", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "test-runscript-overlay", "level": "1.1.3.4", "duration": "0.01", "result": "pass", "extra": {"uuid": "1_1.1.3.1", "name": "ltp-smoke", "path": "automated/linux/ltp/ltp.yaml", "from": "url", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst", "filename": "/var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/0/tests/0_ltp-smoke/run.sh"}}}
- {"dt": "2025-04-08T12:44:17.011297", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2025-04-08T12:44:17.011374", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/lava-overlay-sin52jud/lava-1/0 for stage 0"}
- {"dt": "2025-04-08T12:44:17.011457", "lvl": "debug", "msg": "- 0_ltp-smoke"}
- {"dt": "2025-04-08T12:44:17.011538", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:17.011600", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:22.745448", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:06) [common]"}
- {"dt": "2025-04-08T12:44:22.745579", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:06) [common]"}
- {"dt": "2025-04-08T12:44:22.745652", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:14:54) [common]"}
- {"dt": "2025-04-08T12:44:22.745704", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2025-04-08T12:44:22.745765", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:22.745825", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:14:54) [common]"}
- {"dt": "2025-04-08T12:44:22.745880", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:22.745935", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:14:54) [common]"}
- {"dt": "2025-04-08T12:44:22.745990", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-08T12:44:22.746126", "lvl": "info", "msg": "downloading file:///home/anders/src/tuxtest-buildroot/builds/riscv64/images/Image"}
- {"dt": "2025-04-08T12:44:22.746180", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/deployimages-4csfsum8/kernel/Image"}
- {"dt": "2025-04-08T12:44:22.746223", "lvl": "debug", "msg": "total size: 22008832 (20\u202FMB)"}
- {"dt": "2025-04-08T12:44:22.746265", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-04-08T12:44:22.770504", "lvl": "info", "msg": "20\u202FMB downloaded in 0.02\u202Fs (864.56\u202FMB/s)"}
- {"dt": "2025-04-08T12:44:22.770606", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:22.770660", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.1", "duration": "0.02", "result": "pass", "extra": {"label": "kernel", "size": ! "22008832", "sha256sum": "cbe1a734a172b28caf210a323a917305c8fe057dbfa5d74c16a7f582d7472d92"}}}
- {"dt": "2025-04-08T12:44:22.770757", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:22.770813", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:14:54) [common]"}
- {"dt": "2025-04-08T12:44:22.770885", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-08T12:44:22.770998", "lvl": "info", "msg": "downloading file:///home/anders/src/tuxtest-buildroot/builds/riscv64/images/rootfs.ext2.zst"}
- {"dt": "2025-04-08T12:44:22.771055", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/deployimages-4csfsum8/rootfs/rootfs.ext2"}
- {"dt": "2025-04-08T12:44:22.771097", "lvl": "debug", "msg": "total size: 85573603 (81\u202FMB)"}
- {"dt": "2025-04-08T12:44:22.771139", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2025-04-08T12:44:22.871602", "lvl": "debug", "msg": "progress  27\u202F% (22\u202FMB)"}
- {"dt": "2025-04-08T12:44:22.971684", "lvl": "debug", "msg": "progress  51\u202F% (42\u202FMB)"}
- {"dt": "2025-04-08T12:44:23.072229", "lvl": "debug", "msg": "progress  70\u202F% (57\u202FMB)"}
- {"dt": "2025-04-08T12:44:23.316648", "lvl": "info", "msg": "81\u202FMB downloaded in 0.55\u202Fs (149.59\u202FMB/s)"}
- {"dt": "2025-04-08T12:44:23.316864", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:01) [common]"}
- {"dt": "2025-04-08T12:44:23.316949", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "0.55", "result": "pass", "extra": {"label": "rootfs", "size": ! "85573603", "sha256sum": "a44405c88c7af3b84eca5533c7cd04e54ec849d291ae803b0c5484a320ca25e7"}}}
- {"dt": "2025-04-08T12:44:23.317096", "lvl": "debug", "msg": "start: 1.5.2 append-overlays (timeout 00:05:00) [common]"}
- {"dt": "2025-04-08T12:44:23.317170", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/deployimages-4csfsum8/rootfs/rootfs.ext2'"}
- {"dt": "2025-04-08T12:44:24.930455", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2025-04-08T12:44:24.931294", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/compress-overlay-tnaow_fx/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2025-04-08T12:44:25.457808", "lvl": "debug", "msg": "end: 1.5.2 append-overlays (duration 00:00:02) [common]"}
- {"dt": "2025-04-08T12:44:25.458255", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:03) [common]"}
- {"dt": "2025-04-08T12:44:25.458377", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:09) [common]"}
- {"dt": "2025-04-08T12:44:25.458544", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:25.458705", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:25.458833", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:25.459281", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-riscv64 -cpu rv64 -machine virt -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/deployimages-4csfsum8/kernel/Image -append \"console=ttyS0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/deployimages-4csfsum8/rootfs/rootfs.ext2,format=raw,id=hd0,if=virtio"}
- {"dt": "2025-04-08T12:44:25.462847", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2025-04-08T12:44:25.463284", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:25.463398", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.00", "result": "pass", "extra": {"qemu_version": "9.2.0", "host_arch": "x86_64", "job_arch": "riscv64"}}}
- {"dt": "2025-04-08T12:44:25.463585", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:25.463694", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:25.463805", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2025-04-08T12:44:25.463894", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:15:00)"}
- {"dt": "2025-04-08T12:44:25.504794", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:25.505149", "lvl": "target", "msg": "OpenSBI v1.5"}
- {"dt": "2025-04-08T12:44:25.505226", "lvl": "target", "msg": "   ____                    _____ ____ _____"}
- {"dt": "2025-04-08T12:44:25.505294", "lvl": "target", "msg": "  / __ \\                  / ____|  _ \\_   _|"}
- {"dt": "2025-04-08T12:44:25.505373", "lvl": "target", "msg": " | |  | |_ __   ___ _ __ | (___ | |_) || |"}
- {"dt": "2025-04-08T12:44:25.505430", "lvl": "target", "msg": " | |  | | '_ \\ / _ \\ '_ \\ \\___ \\|  _ < | |"}
- {"dt": "2025-04-08T12:44:25.505499", "lvl": "target", "msg": " | |__| | |_) |  __/ | | |____) | |_) || |_"}
- {"dt": "2025-04-08T12:44:25.505761", "lvl": "target", "msg": "  \\____/| .__/ \\___|_| |_|_____/|____/_____|"}
- {"dt": "2025-04-08T12:44:25.505819", "lvl": "target", "msg": "        | |"}
- {"dt": "2025-04-08T12:44:25.505856", "lvl": "target", "msg": "        |_|"}
- {"dt": "2025-04-08T12:44:25.505893", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:25.515441", "lvl": "target", "msg": "Platform Name             : riscv-virtio,qemu"}
- {"dt": "2025-04-08T12:44:25.515545", "lvl": "target", "msg": "Platform Features         : medeleg"}
- {"dt": "2025-04-08T12:44:25.515617", "lvl": "target", "msg": "Platform HART Count       : 2"}
- {"dt": "2025-04-08T12:44:25.515690", "lvl": "target", "msg": "Platform IPI Device       : aclint-mswi"}
- {"dt": "2025-04-08T12:44:25.515807", "lvl": "target", "msg": "Platform Timer Device     : aclint-mtimer @ 10000000Hz"}
- {"dt": "2025-04-08T12:44:25.515911", "lvl": "target", "msg": "Platform Console Device   : uart8250"}
- {"dt": "2025-04-08T12:44:25.516026", "lvl": "target", "msg": "Platform HSM Device       : ---"}
- {"dt": "2025-04-08T12:44:25.516142", "lvl": "target", "msg": "Platform PMU Device       : ---"}
- {"dt": "2025-04-08T12:44:25.516316", "lvl": "target", "msg": "Platform Reboot Device    : syscon-reboot"}
- {"dt": "2025-04-08T12:44:25.516482", "lvl": "target", "msg": "Platform Shutdown Device  : syscon-poweroff"}
- {"dt": "2025-04-08T12:44:25.516612", "lvl": "target", "msg": "Platform Suspend Device   : ---"}
- {"dt": "2025-04-08T12:44:25.516723", "lvl": "target", "msg": "Platform CPPC Device      : ---"}
- {"dt": "2025-04-08T12:44:25.516855", "lvl": "target", "msg": "Firmware Base             : 0x80000000"}
- {"dt": "2025-04-08T12:44:25.516975", "lvl": "target", "msg": "Firmware Size             : 337 KB"}
- {"dt": "2025-04-08T12:44:25.517097", "lvl": "target", "msg": "Firmware RW Offset        : 0x40000"}
- {"dt": "2025-04-08T12:44:25.517218", "lvl": "target", "msg": "Firmware RW Size          : 81 KB"}
- {"dt": "2025-04-08T12:44:25.517462", "lvl": "target", "msg": "Firmware Heap Offset      : 0x4b000"}
- {"dt": "2025-04-08T12:44:25.517764", "lvl": "target", "msg": "Firmware Heap Size        : 37 KB (total), 2 KB (reserved), 11 KB (used), 23 KB (free)"}
- {"dt": "2025-04-08T12:44:25.517979", "lvl": "target", "msg": "Firmware Scratch Size     : 4096 B (total), 416 B (used), 3680 B (free)"}
- {"dt": "2025-04-08T12:44:25.518103", "lvl": "target", "msg": "Runtime SBI Version       : 2.0"}
- {"dt": "2025-04-08T12:44:25.518153", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:25.518268", "lvl": "target", "msg": "Domain0 Name              : root"}
- {"dt": "2025-04-08T12:44:25.518363", "lvl": "target", "msg": "Domain0 Boot HART         : 0"}
- {"dt": "2025-04-08T12:44:25.518632", "lvl": "target", "msg": "Domain0 HARTs             : 0*,1*"}
- {"dt": "2025-04-08T12:44:25.519007", "lvl": "target", "msg": "Domain0 Region00          : 0x0000000000100000-0x0000000000100fff M: (I,R,W) S/U: (R,W)"}
- {"dt": "2025-04-08T12:44:25.519321", "lvl": "target", "msg": "Domain0 Region01          : 0x0000000010000000-0x0000000010000fff M: (I,R,W) S/U: (R,W)"}
- {"dt": "2025-04-08T12:44:25.519632", "lvl": "target", "msg": "Domain0 Region02          : 0x0000000002000000-0x000000000200ffff M: (I,R,W) S/U: ()"}
- {"dt": "2025-04-08T12:44:25.519925", "lvl": "target", "msg": "Domain0 Region03          : 0x0000000080040000-0x000000008005ffff M: (R,W) S/U: ()"}
- {"dt": "2025-04-08T12:44:25.520275", "lvl": "target", "msg": "Domain0 Region04          : 0x0000000080000000-0x000000008003ffff M: (R,X) S/U: ()"}
- {"dt": "2025-04-08T12:44:25.520473", "lvl": "target", "msg": "Domain0 Region05          : 0x000000000c400000-0x000000000c5fffff M: (I,R,W) S/U: (R,W)"}
- {"dt": "2025-04-08T12:44:25.520734", "lvl": "target", "msg": "Domain0 Region06          : 0x000000000c000000-0x000000000c3fffff M: (I,R,W) S/U: (R,W)"}
- {"dt": "2025-04-08T12:44:25.521118", "lvl": "target", "msg": "Domain0 Region07          : 0x0000000000000000-0xffffffffffffffff M: () S/U: (R,W,X)"}
- {"dt": "2025-04-08T12:44:25.521354", "lvl": "target", "msg": "Domain0 Next Address      : 0x0000000080200000"}
- {"dt": "2025-04-08T12:44:25.521404", "lvl": "target", "msg": "Domain0 Next Arg1         : 0x00000000bfe00000"}
- {"dt": "2025-04-08T12:44:25.521621", "lvl": "target", "msg": "Domain0 Next Mode         : S-mode"}
- {"dt": "2025-04-08T12:44:25.521661", "lvl": "target", "msg": "Domain0 SysReset          : yes"}
- {"dt": "2025-04-08T12:44:25.521741", "lvl": "target", "msg": "Domain0 SysSuspend        : yes"}
- {"dt": "2025-04-08T12:44:25.521795", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:25.521904", "lvl": "target", "msg": "Boot HART ID              : 0"}
- {"dt": "2025-04-08T12:44:25.522145", "lvl": "target", "msg": "Boot HART Domain          : root"}
- {"dt": "2025-04-08T12:44:25.522187", "lvl": "target", "msg": "Boot HART Priv Version    : v1.12"}
- {"dt": "2025-04-08T12:44:25.522283", "lvl": "target", "msg": "Boot HART Base ISA        : rv64imafdch"}
- {"dt": "2025-04-08T12:44:25.522575", "lvl": "target", "msg": "Boot HART ISA Extensions  : sstc,zicntr,zihpm,zicboz,zicbom,sdtrig,svadu"}
- {"dt": "2025-04-08T12:44:25.522815", "lvl": "target", "msg": "Boot HART PMP Count       : 16"}
- {"dt": "2025-04-08T12:44:25.522863", "lvl": "target", "msg": "Boot HART PMP Granularity : 2 bits"}
- {"dt": "2025-04-08T12:44:25.523099", "lvl": "target", "msg": "Boot HART PMP Address Bits: 54"}
- {"dt": "2025-04-08T12:44:25.523140", "lvl": "target", "msg": "Boot HART MHPM Info       : 16 (0x0007fff8)"}
- {"dt": "2025-04-08T12:44:25.523238", "lvl": "target", "msg": "Boot HART Debug Triggers  : 2 triggers"}
- {"dt": "2025-04-08T12:44:25.523413", "lvl": "target", "msg": "Boot HART MIDELEG         : 0x0000000000001666"}
- {"dt": "2025-04-08T12:44:25.523576", "lvl": "target", "msg": "Boot HART MEDELEG         : 0x0000000000f0b509"}
- {"dt": "2025-04-08T12:44:25.538151", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-08T12:44:25.538247", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2025-04-08T12:44:25.538331", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2025-04-08T12:44:25.538415", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2025-04-08T12:44:25.538474", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2025-04-08T12:44:25.538532", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2025-04-08T12:44:25.538582", "lvl": "debug", "msg": ["-\\[ cut here \\]", "Unhandled fault", "BUG: KCSAN:", "BUG: KASAN:", "BUG: KFENCE:", "Oops(?: -|:)", "WARNING:", "(kernel BUG at|BUG:)", "invalid opcode:", "Kernel panic - not syncing", "root@(.*):[/~]#", "login:", "Login incorrect"]}
- {"dt": "2025-04-08T12:44:25.538686", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:15:00)"}
- {"dt": "2025-04-08T12:44:25.538733", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2025-04-08T12:44:25.539097", "lvl": "target", "msg": "[    0.000000] Linux version 6.4.16 (anders@monster) (riscv64-linux-gcc.br_real (Buildroot 2021.11-12449-g1bef613319) 14.2.0, GNU ld (GNU Binutils) 2.42) #1 SMP Tue Apr  8 14:28:42 CEST 2025"}
- {"dt": "2025-04-08T12:44:25.539147", "lvl": "target", "msg": "[    0.000000] random: crng init done"}
- {"dt": "2025-04-08T12:44:25.539186", "lvl": "target", "msg": "[    0.000000] Machine model: riscv-virtio,qemu"}
- {"dt": "2025-04-08T12:44:25.539222", "lvl": "target", "msg": "[    0.000000] earlycon: ns16550a0 at MMIO 0x0000000010000000 (options '')"}
- {"dt": "2025-04-08T12:44:25.539257", "lvl": "target", "msg": "[    0.000000] printk: bootconsole [ns16550a0] enabled"}
- {"dt": "2025-04-08T12:44:25.539291", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2025-04-08T12:44:25.540603", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000080000000..0x000000008003ffff (256 KiB) nomap non-reusable mmode_resv1@80000000"}
- {"dt": "2025-04-08T12:44:25.540967", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: 0x0000000080040000..0x000000008005ffff (128 KiB) nomap non-reusable mmode_resv0@80040000"}
- {"dt": "2025-04-08T12:44:25.586519", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2025-04-08T12:44:25.586924", "lvl": "target", "msg": "[    0.000000]   DMA32    [mem 0x0000000080000000-0x00000000ffffffff]"}
- {"dt": "2025-04-08T12:44:25.587238", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000017fffffff]"}
- {"dt": "2025-04-08T12:44:25.587333", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2025-04-08T12:44:25.587496", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2025-04-08T12:44:25.587752", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000080000000-0x000000008005ffff]"}
- {"dt": "2025-04-08T12:44:25.588036", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000080060000-0x000000017fffffff]"}
- {"dt": "2025-04-08T12:44:25.588458", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x000000017fffffff]"}
- {"dt": "2025-04-08T12:44:25.618413", "lvl": "target", "msg": "[    0.000000] SBI specification v2.0 detected"}
- {"dt": "2025-04-08T12:44:25.618628", "lvl": "target", "msg": "[    0.000000] SBI implementation ID=0x1 Version=0x10005"}
- {"dt": "2025-04-08T12:44:25.618855", "lvl": "target", "msg": "[    0.000000] SBI TIME extension detected"}
- {"dt": "2025-04-08T12:44:25.619003", "lvl": "target", "msg": "[    0.000000] SBI IPI extension detected"}
- {"dt": "2025-04-08T12:44:25.619159", "lvl": "target", "msg": "[    0.000000] SBI RFENCE extension detected"}
- {"dt": "2025-04-08T12:44:25.619413", "lvl": "target", "msg": "[    0.000000] SBI SRST extension detected"}
- {"dt": "2025-04-08T12:44:25.619568", "lvl": "target", "msg": "[    0.000000] SBI HSM extension detected"}
- {"dt": "2025-04-08T12:44:25.620989", "lvl": "target", "msg": "[    0.000000] riscv: base ISA extensions acdfhim"}
- {"dt": "2025-04-08T12:44:25.621096", "lvl": "target", "msg": "[    0.000000] riscv: ELF capabilities acdfim"}
- {"dt": "2025-04-08T12:44:25.623771", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 19 pages/cpu s40056 r8192 d29576 u77824"}
- {"dt": "2025-04-08T12:44:25.624319", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: s40056 r8192 d29576 u77824 alloc=19*4096"}
- {"dt": "2025-04-08T12:44:25.624468", "lvl": "target", "msg": "[    0.000000] pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2025-04-08T12:44:25.625989", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttyS0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning earlycon"}
- {"dt": "2025-04-08T12:44:25.626966", "lvl": "target", "msg": "<5>[    0.000000] Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2025-04-08T12:44:25.628596", "lvl": "target", "msg": "<6>[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.629427", "lvl": "target", "msg": "<6>[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.629953", "lvl": "target", "msg": "<6>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1032192"}
- {"dt": "2025-04-08T12:44:25.630385", "lvl": "target", "msg": "<6>[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2025-04-08T12:44:25.630619", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: area num 2."}
- {"dt": "2025-04-08T12:44:25.658092", "lvl": "target", "msg": "<6>[    0.000000] software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB)"}
- {"dt": "2025-04-08T12:44:25.684551", "lvl": "target", "msg": "<5>[    0.000000] Virtual kernel memory layout:"}
- {"dt": "2025-04-08T12:44:25.684667", "lvl": "target", "msg": "<5>[    0.000000]       fixmap : 0xff1bfffffea00000 - 0xff1bffffff000000   (6144 kB)"}
- {"dt": "2025-04-08T12:44:25.684893", "lvl": "target", "msg": "<5>[    0.000000]       pci io : 0xff1bffffff000000 - 0xff1c000000000000   (  16 MB)"}
- {"dt": "2025-04-08T12:44:25.685134", "lvl": "target", "msg": "<5>[    0.000000]      vmemmap : 0xff1c000000000000 - 0xff20000000000000   (1024 TB)"}
- {"dt": "2025-04-08T12:44:25.685365", "lvl": "target", "msg": "<5>[    0.000000]      vmalloc : 0xff20000000000000 - 0xff60000000000000   (16384 TB)"}
- {"dt": "2025-04-08T12:44:25.685593", "lvl": "target", "msg": "<5>[    0.000000]      modules : 0xffffffff01576000 - 0xffffffff80000000   (2026 MB)"}
- {"dt": "2025-04-08T12:44:25.685791", "lvl": "target", "msg": "<5>[    0.000000]       lowmem : 0xff60000000000000 - 0xff60000100000000   (4096 MB)"}
- {"dt": "2025-04-08T12:44:25.685993", "lvl": "target", "msg": "<5>[    0.000000]       kernel : 0xffffffff80000000 - 0xffffffffffffffff   (2047 MB)"}
- {"dt": "2025-04-08T12:44:25.686400", "lvl": "target", "msg": "<6>[    0.000000] Memory: 4016656K/4194304K available (8714K kernel code, 4961K rwdata, 4096K rodata, 2195K init, 476K bss, 177648K reserved, 0K cma-reserved)"}
- {"dt": "2025-04-08T12:44:25.690186", "lvl": "target", "msg": "<6>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2025-04-08T12:44:25.695491", "lvl": "target", "msg": "<6>[    0.000000] rcu: Hierarchical RCU implementation."}
- {"dt": "2025-04-08T12:44:25.695592", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2."}
- {"dt": "2025-04-08T12:44:25.695745", "lvl": "target", "msg": "<6>[    0.000000] rcu: \tRCU debug extended QS entry/exit."}
- {"dt": "2025-04-08T12:44:25.696007", "lvl": "target", "msg": "<6>[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2025-04-08T12:44:25.696594", "lvl": "target", "msg": "<6>[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2025-04-08T12:44:25.696721", "lvl": "target", "msg": "<6>[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2025-04-08T12:44:25.697726", "lvl": "target", "msg": "<6>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2025-04-08T12:44:25.699596", "lvl": "target", "msg": "<6>[    0.000000] riscv-intc: 64 local interrupts mapped"}
- {"dt": "2025-04-08T12:44:25.704828", "lvl": "target", "msg": "<6>[    0.000000] plic: plic@c000000: mapped 95 interrupts with 2 handlers for 4 contexts."}
- {"dt": "2025-04-08T12:44:25.706422", "lvl": "target", "msg": "<6>[    0.000000] riscv: providing IPIs using SBI IPI extension"}
- {"dt": "2025-04-08T12:44:25.706878", "lvl": "target", "msg": "<6>[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2025-04-08T12:44:25.708457", "lvl": "target", "msg": "<6>[    0.000000] riscv-timer: riscv_timer_init_dt: Registering clocksource cpuid [0] hartid [0]"}
- {"dt": "2025-04-08T12:44:25.708889", "lvl": "target", "msg": "<6>[    0.000000] clocksource: riscv_clocksource: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns"}
- {"dt": "2025-04-08T12:44:25.709507", "lvl": "target", "msg": "<6>[    0.000052] sched_clock: 64 bits at 10MHz, resolution 100ns, wraps every 4398046511100ns"}
- {"dt": "2025-04-08T12:44:25.709858", "lvl": "target", "msg": "<6>[    0.000458] riscv-timer: Timer interrupt in S-mode is available via sstc extension"}
- {"dt": "2025-04-08T12:44:25.714715", "lvl": "target", "msg": "<6>[    0.005307] Console: colour dummy device 80x25"}
- {"dt": "2025-04-08T12:44:25.718141", "lvl": "target", "msg": "<6>[    0.008565] Calibrating delay loop (skipped), value calculated using timer frequency.. 20.00 BogoMIPS (lpj=40000)"}
- {"dt": "2025-04-08T12:44:25.718437", "lvl": "target", "msg": "<6>[    0.009125] pid_max: default: 32768 minimum: 301"}
- {"dt": "2025-04-08T12:44:25.719522", "lvl": "target", "msg": "<6>[    0.010148] LSM: initializing lsm=capability,integrity"}
- {"dt": "2025-04-08T12:44:25.721369", "lvl": "target", "msg": "<6>[    0.012018] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.721568", "lvl": "target", "msg": "<6>[    0.012253] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.745427", "lvl": "target", "msg": "<6>[    0.036011] cblist_init_generic: Setting adjustable number of callback queues."}
- {"dt": "2025-04-08T12:44:25.745652", "lvl": "target", "msg": "<6>[    0.036347] cblist_init_generic: Setting shift to 1 and lim to 1."}
- {"dt": "2025-04-08T12:44:25.746465", "lvl": "target", "msg": "<6>[    0.036882] riscv: ELF compat mode supported"}
- {"dt": "2025-04-08T12:44:25.746654", "lvl": "target", "msg": "<6>[    0.037118] ASID allocator using 16 bits (65536 entries)"}
- {"dt": "2025-04-08T12:44:25.747582", "lvl": "target", "msg": "<6>[    0.038262] rcu: Hierarchical SRCU implementation."}
- {"dt": "2025-04-08T12:44:25.747816", "lvl": "target", "msg": "<6>[    0.038514] rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2025-04-08T12:44:25.749443", "lvl": "target", "msg": "<6>[    0.040102] EFI services will not be available."}
- {"dt": "2025-04-08T12:44:25.751203", "lvl": "target", "msg": "<6>[    0.041875] smp: Bringing up secondary CPUs ..."}
- {"dt": "2025-04-08T12:44:25.760131", "lvl": "target", "msg": "<6>[    0.050770] smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2025-04-08T12:44:25.772034", "lvl": "target", "msg": "<6>[    0.062684] devtmpfs: initialized"}
- {"dt": "2025-04-08T12:44:25.778470", "lvl": "target", "msg": "<6>[    0.069109] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2025-04-08T12:44:25.778781", "lvl": "target", "msg": "<6>[    0.069495] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.780668", "lvl": "target", "msg": "<6>[    0.071351] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2025-04-08T12:44:25.785376", "lvl": "target", "msg": "<6>[    0.076028] NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2025-04-08T12:44:25.788405", "lvl": "target", "msg": "<6>[    0.078969] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2025-04-08T12:44:25.788824", "lvl": "target", "msg": "<6>[    0.079490] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2025-04-08T12:44:25.789159", "lvl": "target", "msg": "<6>[    0.079903] audit: initializing netlink subsys (disabled)"}
- {"dt": "2025-04-08T12:44:25.791609", "lvl": "target", "msg": "<5>[    0.082250] audit: type=2000 audit(0.076:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2025-04-08T12:44:25.792236", "lvl": "target", "msg": "<6>[    0.082911] cpuidle: using governor menu"}
- {"dt": "2025-04-08T12:44:25.803507", "lvl": "target", "msg": "<6>[    0.094149] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2025-04-08T12:44:25.803606", "lvl": "target", "msg": "<6>[    0.094344] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page"}
- {"dt": "2025-04-08T12:44:25.807858", "lvl": "target", "msg": "<6>[    0.098463] iommu: Default domain type: Translated "}
- {"dt": "2025-04-08T12:44:25.807969", "lvl": "target", "msg": "<6>[    0.098621] iommu: DMA domain TLB invalidation policy: strict mode "}
- {"dt": "2025-04-08T12:44:25.809589", "lvl": "target", "msg": "<5>[    0.100268] SCSI subsystem initialized"}
- {"dt": "2025-04-08T12:44:25.810424", "lvl": "target", "msg": "<7>[    0.101102] libata version 3.00 loaded."}
- {"dt": "2025-04-08T12:44:25.811174", "lvl": "target", "msg": "<6>[    0.101855] usbcore: registered new interface driver usbfs"}
- {"dt": "2025-04-08T12:44:25.811505", "lvl": "target", "msg": "<6>[    0.102232] usbcore: registered new interface driver hub"}
- {"dt": "2025-04-08T12:44:25.811779", "lvl": "target", "msg": "<6>[    0.102516] usbcore: registered new device driver usb"}
- {"dt": "2025-04-08T12:44:25.821067", "lvl": "target", "msg": "<6>[    0.111714] vgaarb: loaded"}
- {"dt": "2025-04-08T12:44:25.823716", "lvl": "target", "msg": "<6>[    0.114363] clocksource: Switched to clocksource riscv_clocksource"}
- {"dt": "2025-04-08T12:44:25.845798", "lvl": "target", "msg": "<6>[    0.136395] NET: Registered PF_INET protocol family"}
- {"dt": "2025-04-08T12:44:25.847176", "lvl": "target", "msg": "<6>[    0.137807] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.853279", "lvl": "target", "msg": "<6>[    0.143911] tcp_listen_portaddr_hash hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.853993", "lvl": "target", "msg": "<6>[    0.144351] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.854254", "lvl": "target", "msg": "<6>[    0.144783] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.855078", "lvl": "target", "msg": "<6>[    0.145683] TCP bind hash table entries: 32768 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.857802", "lvl": "target", "msg": "<6>[    0.148445] TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2025-04-08T12:44:25.858798", "lvl": "target", "msg": "<6>[    0.149475] UDP hash table entries: 2048 (order: 5, 196608 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.859295", "lvl": "target", "msg": "<6>[    0.149978] UDP-Lite hash table entries: 2048 (order: 5, 196608 bytes, linear)"}
- {"dt": "2025-04-08T12:44:25.860516", "lvl": "target", "msg": "<6>[    0.151198] NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2025-04-08T12:44:25.862829", "lvl": "target", "msg": "<6>[    0.153477] RPC: Registered named UNIX socket transport module."}
- {"dt": "2025-04-08T12:44:25.862920", "lvl": "target", "msg": "<6>[    0.153684] RPC: Registered udp transport module."}
- {"dt": "2025-04-08T12:44:25.863058", "lvl": "target", "msg": "<6>[    0.153826] RPC: Registered tcp transport module."}
- {"dt": "2025-04-08T12:44:25.863455", "lvl": "target", "msg": "<6>[    0.153967] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2025-04-08T12:44:25.863563", "lvl": "target", "msg": "<6>[    0.154327] PCI: CLS 0 bytes, default 64"}
- {"dt": "2025-04-08T12:44:25.867231", "lvl": "target", "msg": "<6>[    0.157876] workingset: timestamp_bits=46 max_order=20 bucket_order=0"}
- {"dt": "2025-04-08T12:44:25.869715", "lvl": "target", "msg": "<5>[    0.160393] NFS: Registering the id_resolver key type"}
- {"dt": "2025-04-08T12:44:25.870566", "lvl": "target", "msg": "<5>[    0.161207] Key type id_resolver registered"}
- {"dt": "2025-04-08T12:44:25.870634", "lvl": "target", "msg": "<5>[    0.161357] Key type id_legacy registered"}
- {"dt": "2025-04-08T12:44:25.870944", "lvl": "target", "msg": "<6>[    0.161658] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2025-04-08T12:44:25.871236", "lvl": "target", "msg": "<6>[    0.161914] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2025-04-08T12:44:25.872005", "lvl": "target", "msg": "<6>[    0.162689] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2025-04-08T12:44:25.873415", "lvl": "target", "msg": "<6>[    0.164098] NET: Registered PF_ALG protocol family"}
- {"dt": "2025-04-08T12:44:25.873810", "lvl": "target", "msg": "<6>[    0.164492] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)"}
- {"dt": "2025-04-08T12:44:25.874062", "lvl": "target", "msg": "<6>[    0.164799] io scheduler mq-deadline registered"}
- {"dt": "2025-04-08T12:44:25.874194", "lvl": "target", "msg": "<6>[    0.164982] io scheduler kyber registered"}
- {"dt": "2025-04-08T12:44:25.874450", "lvl": "target", "msg": "<6>[    0.165175] io scheduler bfq registered"}
- {"dt": "2025-04-08T12:44:25.877458", "lvl": "target", "msg": "<6>[    0.168048] pci-host-generic 30000000.pci: host bridge /soc/pci@30000000 ranges:"}
- {"dt": "2025-04-08T12:44:25.878159", "lvl": "target", "msg": "<6>[    0.168809] pci-host-generic 30000000.pci:       IO 0x0003000000..0x000300ffff -> 0x0000000000"}
- {"dt": "2025-04-08T12:44:25.878742", "lvl": "target", "msg": "<6>[    0.169364] pci-host-generic 30000000.pci:      MEM 0x0040000000..0x007fffffff -> 0x0040000000"}
- {"dt": "2025-04-08T12:44:25.879045", "lvl": "target", "msg": "<6>[    0.169679] pci-host-generic 30000000.pci:      MEM 0x0400000000..0x07ffffffff -> 0x0400000000"}
- {"dt": "2025-04-08T12:44:25.879760", "lvl": "target", "msg": "<4>[    0.170394] pci-host-generic 30000000.pci: Memory resource size exceeds max for 32 bits"}
- {"dt": "2025-04-08T12:44:25.880493", "lvl": "target", "msg": "<6>[    0.170939] pci-host-generic 30000000.pci: ECAM at [mem 0x30000000-0x3fffffff] for [bus 00-ff]"}
- {"dt": "2025-04-08T12:44:25.881496", "lvl": "target", "msg": "<6>[    0.172164] pci-host-generic 30000000.pci: PCI host bridge to bus 0000:00"}
- {"dt": "2025-04-08T12:44:25.881992", "lvl": "target", "msg": "<6>[    0.172565] pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2025-04-08T12:44:25.882131", "lvl": "target", "msg": "<6>[    0.172807] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2025-04-08T12:44:25.882351", "lvl": "target", "msg": "<6>[    0.173040] pci_bus 0000:00: root bus resource [mem 0x40000000-0x7fffffff]"}
- {"dt": "2025-04-08T12:44:25.882563", "lvl": "target", "msg": "<6>[    0.173256] pci_bus 0000:00: root bus resource [mem 0x400000000-0x7ffffffff]"}
- {"dt": "2025-04-08T12:44:25.883682", "lvl": "target", "msg": "<6>[    0.174363] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000"}
- {"dt": "2025-04-08T12:44:25.886204", "lvl": "target", "msg": "<6>[    0.176838] pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000"}
- {"dt": "2025-04-08T12:44:25.886496", "lvl": "target", "msg": "<6>[    0.177167] pci 0000:00:01.0: reg 0x10: [io  0x0000-0x007f]"}
- {"dt": "2025-04-08T12:44:25.886629", "lvl": "target", "msg": "<6>[    0.177354] pci 0000:00:01.0: reg 0x14: [mem 0x00000000-0x00000fff]"}
- {"dt": "2025-04-08T12:44:25.886917", "lvl": "target", "msg": "<6>[    0.177579] pci 0000:00:01.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2025-04-08T12:44:25.888626", "lvl": "target", "msg": "<6>[    0.179276] pci 0000:00:01.0: BAR 4: assigned [mem 0x400000000-0x400003fff 64bit pref]"}
- {"dt": "2025-04-08T12:44:25.888940", "lvl": "target", "msg": "<6>[    0.179650] pci 0000:00:01.0: BAR 1: assigned [mem 0x40000000-0x40000fff]"}
- {"dt": "2025-04-08T12:44:25.889146", "lvl": "target", "msg": "<6>[    0.179866] pci 0000:00:01.0: BAR 0: assigned [io  0x0080-0x00ff]"}
- {"dt": "2025-04-08T12:44:25.893713", "lvl": "target", "msg": "<6>[    0.184359] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)"}
- {"dt": "2025-04-08T12:44:25.994800", "lvl": "target", "msg": "<6>[    0.285429] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled"}
- {"dt": "2025-04-08T12:44:26.001809", "lvl": "target", "msg": "<6>[    0.292471] printk: console [ttyS0] disabled"}
- {"dt": "2025-04-08T12:44:26.003214", "lvl": "target", "msg": "<6>[    0.293872] 10000000.serial: ttyS0 at MMIO 0x10000000 (irq = 13, base_baud = 230400) is a 16550A"}
- {"dt": "2025-04-08T12:44:26.004883", "lvl": "target", "msg": "<6>[    0.295412] printk: console [ttyS0] enabled"}
- {"dt": "2025-04-08T12:44:26.004965", "lvl": "target", "msg": "<6>[    0.295412] printk: console [ttyS0] enabled"}
- {"dt": "2025-04-08T12:44:26.005157", "lvl": "target", "msg": "<6>[    0.295904] printk: bootconsole [ns16550a0] disabled"}
- {"dt": "2025-04-08T12:44:26.005295", "lvl": "target", "msg": "<6>[    0.295904] printk: bootconsole [ns16550a0] disabled"}
- {"dt": "2025-04-08T12:44:26.007067", "lvl": "target", "msg": "<6>[    0.297698] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2025-04-08T12:44:26.019036", "lvl": "target", "msg": "<6>[    0.309668] loop: module loaded"}
- {"dt": "2025-04-08T12:44:26.019973", "lvl": "target", "msg": "<6>[    0.310596] virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2025-04-08T12:44:26.023181", "lvl": "target", "msg": "<5>[    0.313799] virtio_blk virtio0: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)"}
- {"dt": "2025-04-08T12:44:26.037043", "lvl": "target", "msg": "<6>[    0.327680] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2025-04-08T12:44:26.037130", "lvl": "target", "msg": "<6>[    0.327854] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2025-04-08T12:44:26.038921", "lvl": "target", "msg": "<6>[    0.329594] usbcore: registered new interface driver uas"}
- {"dt": "2025-04-08T12:44:26.039192", "lvl": "target", "msg": "<6>[    0.329900] usbcore: registered new interface driver usb-storage"}
- {"dt": "2025-04-08T12:44:26.040267", "lvl": "target", "msg": "<6>[    0.330944] mousedev: PS/2 mouse device common for all mice"}
- {"dt": "2025-04-08T12:44:26.042563", "lvl": "target", "msg": "<6>[    0.333188] goldfish_rtc 101000.rtc: registered as rtc0"}
- {"dt": "2025-04-08T12:44:26.043435", "lvl": "target", "msg": "<6>[    0.333891] goldfish_rtc 101000.rtc: setting system clock to 2025-04-08T12:44:25 UTC (1744116265)"}
- {"dt": "2025-04-08T12:44:26.045986", "lvl": "target", "msg": "<3>[    0.336331] syscon-poweroff poweroff: pm_power_off already claimed for sbi_srst_power_off"}
- {"dt": "2025-04-08T12:44:26.046205", "lvl": "target", "msg": "<4>[    0.336902] syscon-poweroff: probe of poweroff failed with error -16"}
- {"dt": "2025-04-08T12:44:26.047914", "lvl": "target", "msg": "<6>[    0.338577] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2025-04-08T12:44:26.048000", "lvl": "target", "msg": "<6>[    0.338771] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2025-04-08T12:44:26.048506", "lvl": "target", "msg": "<6>[    0.339184] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2025-04-08T12:44:26.049247", "lvl": "target", "msg": "<6>[    0.339921] usbcore: registered new interface driver usbhid"}
- {"dt": "2025-04-08T12:44:26.049308", "lvl": "target", "msg": "<6>[    0.340101] usbhid: USB HID core driver"}
- {"dt": "2025-04-08T12:44:26.049886", "lvl": "target", "msg": "<6>[    0.340563] riscv-pmu-sbi: SBI PMU extension is available"}
- {"dt": "2025-04-08T12:44:26.050480", "lvl": "target", "msg": "<6>[    0.341158] riscv-pmu-sbi: 16 firmware and 18 hardware counters"}
- {"dt": "2025-04-08T12:44:26.050784", "lvl": "target", "msg": "<6>[    0.341394] riscv-pmu-sbi: Perf sampling/filtering is not supported as sscof extension is not available"}
- {"dt": "2025-04-08T12:44:26.053681", "lvl": "target", "msg": "<6>[    0.344316] NET: Registered PF_INET6 protocol family"}
- {"dt": "2025-04-08T12:44:26.060036", "lvl": "target", "msg": "<6>[    0.350674] Segment Routing with IPv6"}
- {"dt": "2025-04-08T12:44:26.060151", "lvl": "target", "msg": "<6>[    0.350924] In-situ OAM (IOAM) with IPv6"}
- {"dt": "2025-04-08T12:44:26.060600", "lvl": "target", "msg": "<6>[    0.351324] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2025-04-08T12:44:26.063005", "lvl": "target", "msg": "<6>[    0.353639] NET: Registered PF_PACKET protocol family"}
- {"dt": "2025-04-08T12:44:26.064087", "lvl": "target", "msg": "<6>[    0.354751] 9pnet: Installing 9P2000 support"}
- {"dt": "2025-04-08T12:44:26.064358", "lvl": "target", "msg": "<5>[    0.355109] Key type dns_resolver registered"}
- {"dt": "2025-04-08T12:44:26.064865", "lvl": "target", "msg": "<7>[    0.355531] start plist test"}
- {"dt": "2025-04-08T12:44:26.072777", "lvl": "target", "msg": "<7>[    0.363419] end plist test"}
- {"dt": "2025-04-08T12:44:26.095874", "lvl": "target", "msg": "<6>[    0.386479] debug_vm_pgtable: [debug_vm_pgtable         ]: Validating architecture page table helpers"}
- {"dt": "2025-04-08T12:44:26.101095", "lvl": "target", "msg": "<6>[    0.391719] clk: Disabling unused clocks"}
- {"dt": "2025-04-08T12:44:26.132299", "lvl": "target", "msg": "<6>[    0.422892] EXT4-fs (vda): orphan cleanup on readonly fs"}
- {"dt": "2025-04-08T12:44:26.133112", "lvl": "target", "msg": "<6>[    0.423602] EXT4-fs (vda): mounted filesystem e975691a-5fdc-4ac2-b569-9a6f784cf67a ro with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-04-08T12:44:26.133471", "lvl": "target", "msg": "<6>[    0.424174] VFS: Mounted root (ext4 filesystem) readonly on device 254:0."}
- {"dt": "2025-04-08T12:44:26.134854", "lvl": "target", "msg": "<6>[    0.425524] devtmpfs: mounted"}
- {"dt": "2025-04-08T12:44:26.171591", "lvl": "target", "msg": "<6>[    0.462139] Freeing unused kernel image (initmem) memory: 2192K"}
- {"dt": "2025-04-08T12:44:26.180200", "lvl": "target", "msg": "<6>[    0.470810] Run /sbin/init as init process"}
- {"dt": "2025-04-08T12:44:26.180290", "lvl": "target", "msg": "<7>[    0.470949]   with arguments:"}
- {"dt": "2025-04-08T12:44:26.180392", "lvl": "target", "msg": "<7>[    0.471040]     /sbin/init"}
- {"dt": "2025-04-08T12:44:26.180474", "lvl": "target", "msg": "<7>[    0.471120]     verbose"}
- {"dt": "2025-04-08T12:44:26.180551", "lvl": "target", "msg": "<7>[    0.471193]   with environment:"}
- {"dt": "2025-04-08T12:44:26.180666", "lvl": "target", "msg": "<7>[    0.471286]     HOME=/"}
- {"dt": "2025-04-08T12:44:26.180743", "lvl": "target", "msg": "<7>[    0.471363]     TERM=linux"}
- {"dt": "2025-04-08T12:44:26.289735", "lvl": "target", "msg": "<6>[    0.580276] EXT4-fs (vda): re-mounted e975691a-5fdc-4ac2-b569-9a6f784cf67a r/w. Quota mode: disabled."}
- {"dt": "2025-04-08T12:44:26.516539", "lvl": "target", "msg": "Saving 256 bits of creditable seed for next boot"}
- {"dt": "2025-04-08T12:44:26.583627", "lvl": "target", "msg": "Starting syslogd: OK"}
- {"dt": "2025-04-08T12:44:26.628488", "lvl": "target", "msg": "Starting klogd: OK"}
- {"dt": "2025-04-08T12:44:26.717280", "lvl": "target", "msg": "Running sysctl: OK"}
- {"dt": "2025-04-08T12:44:26.938595", "lvl": "target", "msg": "Starting network: OK"}
- {"dt": "2025-04-08T12:44:26.980646", "lvl": "target", "msg": "Starting crond: OK"}
- {"dt": "2025-04-08T12:44:27.096496", "lvl": "target", "msg": "\r"}
- {"dt": "2025-04-08T12:44:27.097386", "lvl": "target", "msg": "Welcome to TuxTest"}
- {"dt": "2025-04-08T12:44:27.097718", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2025-04-08T12:44:27.097797", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "1.56", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2025-04-08T12:44:27.097888", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2025-04-08T12:44:27.097944", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2025-04-08T12:44:27.198169", "lvl": "input", "msg": "root"}
- {"dt": "2025-04-08T12:44:27.198320", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-08T12:44:27.198408", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:14:58)"}
- {"dt": "2025-04-08T12:44:27.198475", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:29)"}
- {"dt": "2025-04-08T12:44:27.198883", "lvl": "target", "msg": "\rtuxtest login: root"}
- {"dt": "2025-04-08T12:44:27.273260", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-08T12:44:27.273437", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:02) [common]"}
- {"dt": "2025-04-08T12:44:27.273519", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "1.74", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2025-04-08T12:44:27.273648", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:02) [common]"}
- {"dt": "2025-04-08T12:44:27.273738", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:14:58) [common]"}
- {"dt": "2025-04-08T12:44:27.273836", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-08T12:44:27.273897", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2025-04-08T12:44:27.273949", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2025-04-08T12:44:27.324060", "lvl": "input", "msg": ""}
- {"dt": "2025-04-08T12:44:27.324212", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:15:00)"}
- {"dt": "2025-04-08T12:44:27.324288", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2025-04-08T12:44:27.325516", "lvl": "target", "msg": "root@tuxtest:~# "}
- {"dt": "2025-04-08T12:44:27.326806", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:27.326904", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:14:58) [common]"}
- {"dt": "2025-04-08T12:44:27.327006", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:27.327105", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:02) [common]"}
- {"dt": "2025-04-08T12:44:27.327176", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:02:00) [common]"}
- {"dt": "2025-04-08T12:44:27.327248", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:02:00) [common]"}
- {"dt": "2025-04-08T12:44:27.327299", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2025-04-08T12:44:27.327346", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2025-04-08T12:44:27.427562", "lvl": "input", "msg": "#"}
- {"dt": "2025-04-08T12:44:27.427734", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:02:00)"}
- {"dt": "2025-04-08T12:44:27.428232", "lvl": "target", "msg": "root@tuxtest:~# #"}
- {"dt": "2025-04-08T12:44:27.429698", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2025-04-08T12:44:27.429822", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2025-04-08T12:44:27.530110", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2025-04-08T12:44:27.530767", "lvl": "target", "msg": "root@tuxtest:~# export SHELL=/bin/sh"}
- {"dt": "2025-04-08T12:44:27.532263", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2025-04-08T12:44:27.632481", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2025-04-08T12:44:27.633092", "lvl": "target", "msg": "root@tuxtest:~# . /lava-1/environment"}
- {"dt": "2025-04-08T12:44:27.635371", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2025-04-08T12:44:27.735597", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-04-08T12:44:27.735702", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2025-04-08T12:44:27.736260", "lvl": "target", "msg": "root@tuxtest:~# /lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-04-08T12:44:27.904385", "lvl": "target", "msg": "+ export TESTRUN_ID=0_ltp-smoke"}
- {"dt": "2025-04-08T12:44:27.904551", "lvl": "target", "msg": "+ TESTRUN_ID=0_ltp-smoke"}
- {"dt": "2025-04-08T12:44:27.904885", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-08T12:44:27.910367", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2025-04-08T12:44:27.920067", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2025-04-08T12:44:27.920335", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-04-08T12:44:27.921222", "lvl": "target", "msg": "<8>[    2.211901] <LAVA_SIGNAL_STARTRUN 0_ltp-smoke 1_1.1.3.1>"}
- {"dt": "2025-04-08T12:44:27.921449", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_ltp-smoke 1_1.1.3.1"}
- {"dt": "2025-04-08T12:44:27.921497", "lvl": "info", "msg": "Starting test lava.0_ltp-smoke (1_1.1.3.1)"}
- {"dt": "2025-04-08T12:44:27.921561", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2025-04-08T12:44:27.921636", "lvl": "target", "msg": "+ cd ./automated/linux/ltp/"}
- {"dt": "2025-04-08T12:44:27.924981", "lvl": "target", "msg": "+ ./ltp.sh -T smoketest -s true -v '' -M 5 -R root -r '' -b '' -d /scratch -g '' -e production -i /opt/ltp/ -S /tuxtest/skipfiles/riscv64/ltp-smoke -p '' -u '' -t false -n 1 -c 1"}
- {"dt": "2025-04-08T12:44:27.943002", "lvl": "target", "msg": "+ . ../../lib/sh-test-lib"}
- {"dt": "2025-04-08T12:44:27.944411", "lvl": "target", "msg": "++ LANG=C"}
- {"dt": "2025-04-08T12:44:27.944497", "lvl": "target", "msg": "++ export LANG"}
- {"dt": "2025-04-08T12:44:27.958205", "lvl": "target", "msg": "++ export PATH=/lava-1/0/../bin:/bin:/sbin:/usr/bin:/usr/sbin:/sbin:/usr/sbin:/usr/local/sbin"}
- {"dt": "2025-04-08T12:44:27.958620", "lvl": "target", "msg": "++ PATH=/lava-1/0/../bin:/bin:/sbin:/usr/bin:/usr/sbin:/sbin:/usr/sbin:/usr/local/sbin"}
- {"dt": "2025-04-08T12:44:27.963570", "lvl": "target", "msg": "++ kernel_config="}
- {"dt": "2025-04-08T12:44:27.971972", "lvl": "target", "msg": "++ pwd"}
- {"dt": "2025-04-08T12:44:27.975178", "lvl": "target", "msg": "+ OUTPUT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-08T12:44:27.976194", "lvl": "target", "msg": "+ RESULT_FILE=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/result.txt"}
- {"dt": "2025-04-08T12:44:27.982604", "lvl": "target", "msg": "++ readlink -f ./ltp.sh"}
- {"dt": "2025-04-08T12:44:27.993877", "lvl": "target", "msg": "+ SCRIPT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.sh"}
- {"dt": "2025-04-08T12:44:28.000487", "lvl": "target", "msg": "++ dirname /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.sh"}
- {"dt": "2025-04-08T12:44:28.010711", "lvl": "target", "msg": "+ SCRIPTPATH=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp"}
- {"dt": "2025-04-08T12:44:28.011504", "lvl": "target", "msg": "+ echo 'Script path is: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp'"}
- {"dt": "2025-04-08T12:44:28.011779", "lvl": "target", "msg": "Script path is: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp"}
- {"dt": "2025-04-08T12:44:28.011834", "lvl": "target", "msg": "+ TST_CMDFILES="}
- {"dt": "2025-04-08T12:44:28.012062", "lvl": "target", "msg": "+ SKIPFILE="}
- {"dt": "2025-04-08T12:44:28.012133", "lvl": "target", "msg": "+ SKIPFILE_YAML="}
- {"dt": "2025-04-08T12:44:28.012209", "lvl": "target", "msg": "+ BOARD="}
- {"dt": "2025-04-08T12:44:28.012265", "lvl": "target", "msg": "+ BRANCH="}
- {"dt": "2025-04-08T12:44:28.012338", "lvl": "target", "msg": "+ ENVIRONMENT="}
- {"dt": "2025-04-08T12:44:28.012420", "lvl": "target", "msg": "+ LTP_VERSION=20180926"}
- {"dt": "2025-04-08T12:44:28.012505", "lvl": "target", "msg": "+ TEST_PROGRAM=ltp"}
- {"dt": "2025-04-08T12:44:28.012581", "lvl": "target", "msg": "+ TEST_GIT_URL="}
- {"dt": "2025-04-08T12:44:28.018177", "lvl": "target", "msg": "++ pwd"}
- {"dt": "2025-04-08T12:44:28.020870", "lvl": "target", "msg": "+ TEST_DIR=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp"}
- {"dt": "2025-04-08T12:44:28.021321", "lvl": "target", "msg": "+ BUILD_FROM_TAR=false"}
- {"dt": "2025-04-08T12:44:28.021367", "lvl": "target", "msg": "+ SHARD_NUMBER=1"}
- {"dt": "2025-04-08T12:44:28.021414", "lvl": "target", "msg": "+ SHARD_INDEX=1"}
- {"dt": "2025-04-08T12:44:28.021460", "lvl": "target", "msg": "+ RUNNER="}
- {"dt": "2025-04-08T12:44:28.021713", "lvl": "target", "msg": "+ LTP_TMPDIR=/ltp-tmp"}
- {"dt": "2025-04-08T12:44:28.021769", "lvl": "target", "msg": "+ LTP_INSTALL_PATH=/opt/ltp"}
- {"dt": "2025-04-08T12:44:28.024025", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.024935", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.025014", "lvl": "target", "msg": "+ TST_CMDFILES=smoketest"}
- {"dt": "2025-04-08T12:44:28.035141", "lvl": "target", "msg": "++ echo smoketest"}
- {"dt": "2025-04-08T12:44:28.036490", "lvl": "target", "msg": "++ sed 's,\\/,_,'"}
- {"dt": "2025-04-08T12:44:28.049593", "lvl": "target", "msg": "+ LOG_FILE=smoketest"}
- {"dt": "2025-04-08T12:44:28.050057", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.050163", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.050652", "lvl": "target", "msg": "+ SKIP_INSTALL=true"}
- {"dt": "2025-04-08T12:44:28.050889", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.050941", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.051156", "lvl": "target", "msg": "+ LTP_VERSION="}
- {"dt": "2025-04-08T12:44:28.051433", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.051663", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.051721", "lvl": "target", "msg": "+ export LTP_TIMEOUT_MUL=5"}
- {"dt": "2025-04-08T12:44:28.051774", "lvl": "target", "msg": "+ LTP_TIMEOUT_MUL=5"}
- {"dt": "2025-04-08T12:44:28.052058", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.052324", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.052386", "lvl": "target", "msg": "+ export PASSWD=root"}
- {"dt": "2025-04-08T12:44:28.052435", "lvl": "target", "msg": "+ PASSWD=root"}
- {"dt": "2025-04-08T12:44:28.052500", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.052557", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.052779", "lvl": "target", "msg": "+ export RUNNER="}
- {"dt": "2025-04-08T12:44:28.052832", "lvl": "target", "msg": "+ RUNNER="}
- {"dt": "2025-04-08T12:44:28.053117", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.053188", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.053240", "lvl": "target", "msg": "+ export BOARD="}
- {"dt": "2025-04-08T12:44:28.053293", "lvl": "target", "msg": "+ BOARD="}
- {"dt": "2025-04-08T12:44:28.053604", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.053657", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.053708", "lvl": "target", "msg": "+ export LTP_TMPDIR=/scratch"}
- {"dt": "2025-04-08T12:44:28.053759", "lvl": "target", "msg": "+ LTP_TMPDIR=/scratch"}
- {"dt": "2025-04-08T12:44:28.054013", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.054063", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.054110", "lvl": "target", "msg": "+ export BRANCH="}
- {"dt": "2025-04-08T12:44:28.054158", "lvl": "target", "msg": "+ BRANCH="}
- {"dt": "2025-04-08T12:44:28.054551", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.054598", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.054824", "lvl": "target", "msg": "+ export ENVIRONMENT=production"}
- {"dt": "2025-04-08T12:44:28.054876", "lvl": "target", "msg": "+ ENVIRONMENT=production"}
- {"dt": "2025-04-08T12:44:28.055129", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.055175", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.055416", "lvl": "target", "msg": "+ export LTP_INSTALL_PATH=/opt/ltp/"}
- {"dt": "2025-04-08T12:44:28.055495", "lvl": "target", "msg": "+ LTP_INSTALL_PATH=/opt/ltp/"}
- {"dt": "2025-04-08T12:44:28.055660", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.055744", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.056676", "lvl": "target", "msg": "+ '[' -z /tuxtest/skipfiles/riscv64/ltp-smoke ']'"}
- {"dt": "2025-04-08T12:44:28.058177", "lvl": "target", "msg": "+ '[' /tuxtest/skipfiles/riscv64/ltp-smoke = yaml ']'"}
- {"dt": "2025-04-08T12:44:28.059719", "lvl": "target", "msg": "+ '[' / == / ']'"}
- {"dt": "2025-04-08T12:44:28.060013", "lvl": "target", "msg": "+ SKIPFILE='-S /tuxtest/skipfiles/riscv64/ltp-smoke'"}
- {"dt": "2025-04-08T12:44:28.060186", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.060651", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.061297", "lvl": "target", "msg": "+ [[ '' != '' ]]"}
- {"dt": "2025-04-08T12:44:28.061558", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.061601", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.061648", "lvl": "target", "msg": "+ [[ '' != '' ]]"}
- {"dt": "2025-04-08T12:44:28.061776", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.061841", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.062058", "lvl": "target", "msg": "+ BUILD_FROM_TAR=false"}
- {"dt": "2025-04-08T12:44:28.062121", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.062170", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.062421", "lvl": "target", "msg": "+ SHARD_NUMBER=1"}
- {"dt": "2025-04-08T12:44:28.062681", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.062739", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-08T12:44:28.062798", "lvl": "target", "msg": "+ SHARD_INDEX=1"}
- {"dt": "2025-04-08T12:44:28.062852", "lvl": "target", "msg": "+ getopts M:T:S:b:d:g:e:i:s:v:R:r:u:p:t:c:n: arg"}
- {"dt": "2025-04-08T12:44:28.063854", "lvl": "target", "msg": "+ TEST_TARFILE=https://github.com/linux-test-project/ltp/releases/download//ltp-full-.tar.xz"}
- {"dt": "2025-04-08T12:44:28.063936", "lvl": "target", "msg": "+ '[' -n '' ']'"}
- {"dt": "2025-04-08T12:44:28.068668", "lvl": "target", "msg": "+ check_root"}
- {"dt": "2025-04-08T12:44:28.075091", "lvl": "target", "msg": "++ id -ru"}
- {"dt": "2025-04-08T12:44:28.086151", "lvl": "target", "msg": "+ '[' 0 -eq 0 ']'"}
- {"dt": "2025-04-08T12:44:28.086414", "lvl": "target", "msg": "+ return 0"}
- {"dt": "2025-04-08T12:44:28.087082", "lvl": "target", "msg": "+ create_out_dir /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-08T12:44:28.087510", "lvl": "target", "msg": "+ '[' -z /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-08T12:44:28.087789", "lvl": "target", "msg": "+ local OUTPUT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-08T12:44:28.088418", "lvl": "target", "msg": "+ '[' -d /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-08T12:44:28.088787", "lvl": "target", "msg": "+ mkdir -p /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-08T12:44:28.101547", "lvl": "target", "msg": "+ '[' -d /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-08T12:44:28.102329", "lvl": "target", "msg": "+ info_msg 'About to run ltp test...'"}
- {"dt": "2025-04-08T12:44:28.102585", "lvl": "target", "msg": "+ local 'msg=About to run ltp test...'"}
- {"dt": "2025-04-08T12:44:28.103193", "lvl": "target", "msg": "+ '[' -z 'About to run ltp test...' ']'"}
- {"dt": "2025-04-08T12:44:28.103428", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'About to run ltp test...'"}
- {"dt": "2025-04-08T12:44:28.103518", "lvl": "target", "msg": "INFO: About to run ltp test..."}
- {"dt": "2025-04-08T12:44:28.104001", "lvl": "target", "msg": "+ info_msg 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-08T12:44:28.104087", "lvl": "target", "msg": "+ local 'msg=Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-08T12:44:28.104300", "lvl": "target", "msg": "+ '[' -z 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output' ']'"}
- {"dt": "2025-04-08T12:44:28.104750", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-08T12:44:28.104871", "lvl": "target", "msg": "INFO: Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-08T12:44:28.104954", "lvl": "target", "msg": "+ '[' true = true ']'"}
- {"dt": "2025-04-08T12:44:28.105270", "lvl": "target", "msg": "+ info_msg 'ltp installation skipped altogether'"}
- {"dt": "2025-04-08T12:44:28.105321", "lvl": "target", "msg": "+ local 'msg=ltp installation skipped altogether'"}
- {"dt": "2025-04-08T12:44:28.105420", "lvl": "target", "msg": "+ '[' -z 'ltp installation skipped altogether' ']'"}
- {"dt": "2025-04-08T12:44:28.105592", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'ltp installation skipped altogether'"}
- {"dt": "2025-04-08T12:44:28.105687", "lvl": "target", "msg": "INFO: ltp installation skipped altogether"}
- {"dt": "2025-04-08T12:44:28.106187", "lvl": "target", "msg": "+ '[' '!' -d /opt/ltp/ ']'"}
- {"dt": "2025-04-08T12:44:28.106752", "lvl": "target", "msg": "+ info_msg 'Running prep_system'"}
- {"dt": "2025-04-08T12:44:28.106802", "lvl": "target", "msg": "+ local 'msg=Running prep_system'"}
- {"dt": "2025-04-08T12:44:28.106879", "lvl": "target", "msg": "+ '[' -z 'Running prep_system' ']'"}
- {"dt": "2025-04-08T12:44:28.106980", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Running prep_system'"}
- {"dt": "2025-04-08T12:44:28.107067", "lvl": "target", "msg": "INFO: Running prep_system"}
- {"dt": "2025-04-08T12:44:28.107150", "lvl": "target", "msg": "+ prep_system"}
- {"dt": "2025-04-08T12:44:28.107438", "lvl": "target", "msg": "+ systemctl is-active systemd-timesyncd"}
- {"dt": "2025-04-08T12:44:28.114974", "lvl": "target", "msg": "+ '[' -f /proc/sys/kernel/unprivileged_userns_clone ']'"}
- {"dt": "2025-04-08T12:44:28.115428", "lvl": "target", "msg": "+ info_msg 'Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-08T12:44:28.115881", "lvl": "target", "msg": "+ local 'msg=Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-08T12:44:28.115943", "lvl": "target", "msg": "+ '[' -z 'Kernel has no support of unprivileged_userns_clone' ']'"}
- {"dt": "2025-04-08T12:44:28.116134", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-08T12:44:28.116269", "lvl": "target", "msg": "INFO: Kernel has no support of unprivileged_userns_clone"}
- {"dt": "2025-04-08T12:44:28.116535", "lvl": "target", "msg": "+ info_msg 'Running run_ltp'"}
- {"dt": "2025-04-08T12:44:28.116631", "lvl": "target", "msg": "+ local 'msg=Running run_ltp'"}
- {"dt": "2025-04-08T12:44:28.116761", "lvl": "target", "msg": "+ '[' -z 'Running run_ltp' ']'"}
- {"dt": "2025-04-08T12:44:28.116999", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Running run_ltp'"}
- {"dt": "2025-04-08T12:44:28.117059", "lvl": "target", "msg": "INFO: Running run_ltp"}
- {"dt": "2025-04-08T12:44:28.117117", "lvl": "target", "msg": "+ run_ltp"}
- {"dt": "2025-04-08T12:44:28.117578", "lvl": "target", "msg": "+ cd /opt/ltp/"}
- {"dt": "2025-04-08T12:44:28.118325", "lvl": "target", "msg": "+ mkdir -m 777 -p /scratch"}
- {"dt": "2025-04-08T12:44:28.131998", "lvl": "target", "msg": "+ for file in ${TST_CMDFILES//,/ }"}
- {"dt": "2025-04-08T12:44:28.132120", "lvl": "target", "msg": "+ cat runtest/smoketest"}
- {"dt": "2025-04-08T12:44:28.147996", "lvl": "target", "msg": "+ sed -i 's/#.*$//;/^$/d' alltests"}
- {"dt": "2025-04-08T12:44:28.166506", "lvl": "target", "msg": "+ split --verbose --numeric-suffixes=1 -n l/1/1 alltests"}
- {"dt": "2025-04-08T12:44:28.183040", "lvl": "target", "msg": "+ echo '============== Tests to run ==============='"}
- {"dt": "2025-04-08T12:44:28.183128", "lvl": "target", "msg": "============== Tests to run ==============="}
- {"dt": "2025-04-08T12:44:28.183377", "lvl": "target", "msg": "+ cat runtest/shardfile"}
- {"dt": "2025-04-08T12:44:28.193499", "lvl": "target", "msg": "access01 access01"}
- {"dt": "2025-04-08T12:44:28.193565", "lvl": "target", "msg": "chdir01 chdir01"}
- {"dt": "2025-04-08T12:44:28.193622", "lvl": "target", "msg": "fork01 fork01"}
- {"dt": "2025-04-08T12:44:28.193687", "lvl": "target", "msg": "time01 time01"}
- {"dt": "2025-04-08T12:44:28.193742", "lvl": "target", "msg": "wait02 wait02"}
- {"dt": "2025-04-08T12:44:28.193818", "lvl": "target", "msg": "write01 write01"}
- {"dt": "2025-04-08T12:44:28.193884", "lvl": "target", "msg": "symlink01 symlink01"}
- {"dt": "2025-04-08T12:44:28.193938", "lvl": "target", "msg": "stat04 stat04"}
- {"dt": "2025-04-08T12:44:28.193989", "lvl": "target", "msg": "splice02 splice02 -s 20"}
- {"dt": "2025-04-08T12:44:28.194049", "lvl": "target", "msg": "df01_sh df01.sh"}
- {"dt": "2025-04-08T12:44:28.194101", "lvl": "target", "msg": "shell_test01 echo \"SUCCESS\" | shell_pipe01.sh"}
- {"dt": "2025-04-08T12:44:28.194162", "lvl": "target", "msg": "ping602 ping02.sh -6"}
- {"dt": "2025-04-08T12:44:28.194226", "lvl": "target", "msg": "macsec02 macsec02.sh"}
- {"dt": "2025-04-08T12:44:28.196165", "lvl": "target", "msg": "+ echo '===========End Tests to run ==============='"}
- {"dt": "2025-04-08T12:44:28.196241", "lvl": "target", "msg": "===========End Tests to run ==============="}
- {"dt": "2025-04-08T12:44:28.196665", "lvl": "target", "msg": "+ '[' -n '' ']'"}
- {"dt": "2025-04-08T12:44:28.198763", "lvl": "target", "msg": "+ pipe0_status './runltp -p -q -f shardfile                                  -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log                                  -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed                                  -d /scratch                                     -S /tuxtest/skipfiles/riscv64/ltp-smoke' 'tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-08T12:44:28.198825", "lvl": "target", "msg": "+ '[' 2 -ne 2 ']'"}
- {"dt": "2025-04-08T12:44:28.200006", "lvl": "target", "msg": "+ local 'cmd1=./runltp -p -q -f shardfile                                  -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log                                  -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed                                  -d /scratch                                     -S /tuxtest/skipfiles/riscv64/ltp-smoke'"}
- {"dt": "2025-04-08T12:44:28.200294", "lvl": "target", "msg": "+ local 'cmd2=tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-08T12:44:28.200338", "lvl": "target", "msg": "+ exec"}
- {"dt": "2025-04-08T12:44:28.200831", "lvl": "target", "msg": "+ local ret_val"}
- {"dt": "2025-04-08T12:44:28.213513", "lvl": "target", "msg": "++ eval './runltp -p -q -f shardfile                                  -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log                                  -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed                                  -d /scratch                                     -S /tuxtest/skipfiles/riscv64/ltp-smoke'"}
- {"dt": "2025-04-08T12:44:28.214529", "lvl": "target", "msg": "+++ ./runltp -p -q -f shardfile -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed -d /scratch -S /tuxtest/skipfiles/riscv64/ltp-smoke"}
- {"dt": "2025-04-08T12:44:28.214990", "lvl": "target", "msg": "++ eval 'tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-08T12:44:28.216151", "lvl": "target", "msg": "+++ tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out"}
- {"dt": "2025-04-08T12:44:28.272098", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-08T12:44:28.272321", "lvl": "target", "msg": "INFO: runltp script is deprecated, try kirk"}
- {"dt": "2025-04-08T12:44:28.272506", "lvl": "target", "msg": "https://github.com/linux-test-project/kirk"}
- {"dt": "2025-04-08T12:44:28.272778", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-08T12:44:28.357667", "lvl": "target", "msg": "INFO: creating /opt/ltp/output directory"}
- {"dt": "2025-04-08T12:44:28.561031", "lvl": "target", "msg": "Checking for required user/group ids"}
- {"dt": "2025-04-08T12:44:28.561369", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:28.696549", "lvl": "target", "msg": "'root' user id and group found."}
- {"dt": "2025-04-08T12:44:28.697234", "lvl": "target", "msg": "'nobody' user id and group found."}
- {"dt": "2025-04-08T12:44:28.697940", "lvl": "target", "msg": "'bin' user id and group found."}
- {"dt": "2025-04-08T12:44:28.698599", "lvl": "target", "msg": "'daemon' user id and group found."}
- {"dt": "2025-04-08T12:44:28.699180", "lvl": "target", "msg": "Users group found."}
- {"dt": "2025-04-08T12:44:28.699768", "lvl": "target", "msg": "Sys group found."}
- {"dt": "2025-04-08T12:44:28.850124", "lvl": "target", "msg": "Required users/groups exist."}
- {"dt": "2025-04-08T12:44:28.853242", "lvl": "target", "msg": "no big block device was specified on commandline."}
- {"dt": "2025-04-08T12:44:28.853586", "lvl": "target", "msg": "Tests which require a big block device are disabled."}
- {"dt": "2025-04-08T12:44:28.853668", "lvl": "target", "msg": "You can specify it with option -z"}
- {"dt": "2025-04-08T12:44:28.868808", "lvl": "target", "msg": "INFO: Test start time: Tue Apr  8 12:44:28 UTC 2025"}
- {"dt": "2025-04-08T12:44:28.870281", "lvl": "target", "msg": "COMMAND:    /opt/ltp/bin/ltp-pan -q  -e -S   -a 122     -n 122 -p -f /scratch/ltp-4rbq3aNnDD/alltests -l /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log  -C /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed -T /opt/ltp/output/LTP_RUN_ON-LTP_smoketest.log.tconf"}
- {"dt": "2025-04-08T12:44:28.885110", "lvl": "target", "msg": "LOG File: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log"}
- {"dt": "2025-04-08T12:44:28.900220", "lvl": "target", "msg": "FAILED COMMAND File: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed"}
- {"dt": "2025-04-08T12:44:28.915074", "lvl": "target", "msg": "TCONF COMMAND File: /opt/ltp/output/LTP_RUN_ON-LTP_smoketest.log.tconf"}
- {"dt": "2025-04-08T12:44:28.916383", "lvl": "target", "msg": "Running tests......."}
- {"dt": "2025-04-08T12:44:28.971549", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-4rbq3aNnDD/LTP_accfYcBVc as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-08T12:44:28.972690", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-08T12:44:28.973068", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Tue Apr  8 14:28:42 CEST 2025 riscv64"}
- {"dt": "2025-04-08T12:44:28.973959", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-08T12:44:29.020422", "lvl": "target", "msg": "tst_kconfig.c:676: TINFO: CONFIG_DEBUG_SPINLOCK kernel option detected which might slow the execution"}
- {"dt": "2025-04-08T12:44:29.020534", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 10m 20s"}
- {"dt": "2025-04-08T12:44:29.032626", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.036830", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.039180", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.042537", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, X_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.044086", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.047425", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.049004", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.052275", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.053765", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.057799", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.058328", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.061721", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.063016", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK|X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.066366", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.067888", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK|X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.070937", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.072736", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.076061", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.078054", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.081107", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.083137", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.086211", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.088255", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.091422", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.095960", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.100434", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.102112", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.105201", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.109716", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.114054", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.115979", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.119014", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.121050", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.124254", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.128728", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.130898", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.134540", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.136552", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.139564", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.141447", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.144842", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.149278", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.151006", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.154199", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.158738", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.163086", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.167631", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.172618", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.174234", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.174408", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.174879", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.174970", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.175120", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.175429", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.175502", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.175967", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.176242", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.176502", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.176559", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.176946", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.177245", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.177498", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.177561", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.177962", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.178217", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.178491", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.178552", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.178966", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.179305", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.179380", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.179630", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.179874", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.180330", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.180551", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.180718", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.180974", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.184152", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.185932", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.189223", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.191109", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.191427", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.194610", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.196437", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.196747", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.199860", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.201500", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.204648", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.206274", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.206619", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.206801", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.209976", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.211772", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.211934", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.212307", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.212587", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.212655", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.212836", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.213239", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.213487", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.213640", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.214094", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.214214", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.217422", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.219115", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.222361", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.224117", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.224301", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.227517", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.229335", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.229521", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.232709", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.234386", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.237471", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.239165", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.239389", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.239816", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.242858", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.244573", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.247705", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.250137", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.253233", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.254950", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.255239", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.258477", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.260245", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.260371", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.263643", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.265495", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.268590", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.270284", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.270483", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.270829", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-08T12:44:29.274032", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-08T12:44:29.278568", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.283090", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.287537", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.289601", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.292557", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.296986", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.301528", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.306053", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.307882", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.310853", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.315441", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.320311", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.325252", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.329592", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.334183", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.338573", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.343016", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.344908", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.348161", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.352629", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.357160", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.361749", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.363445", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.366512", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.371130", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.375635", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.380470", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.384840", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.389360", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.390975", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.394063", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.398389", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.400142", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.403102", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.407666", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.412290", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.416883", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.421103", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.425602", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.427483", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.430800", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.435468", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.439926", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.444259", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.446071", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.449204", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.453626", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.458253", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.462682", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.467184", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.471758", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.473562", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.477042", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.481507", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.483173", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.486402", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.490801", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.495553", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.500118", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.501781", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.504866", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.509508", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.511181", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.514333", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.519029", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.523500", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-08T12:44:29.527500", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:29.527767", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:29.527837", "lvl": "target", "msg": "passed   199"}
- {"dt": "2025-04-08T12:44:29.527896", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:29.527969", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-08T12:44:29.528041", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-08T12:44:29.528125", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-08T12:44:29.573459", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-4rbq3aNnDD/LTP_forx9gqoy as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-08T12:44:29.574007", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-08T12:44:29.574104", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Tue Apr  8 14:28:42 CEST 2025 riscv64"}
- {"dt": "2025-04-08T12:44:29.574550", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-08T12:44:29.615266", "lvl": "target", "msg": "tst_kconfig.c:676: TINFO: CONFIG_DEBUG_SPINLOCK kernel option detected which might slow the execution"}
- {"dt": "2025-04-08T12:44:29.615849", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 10m 00s"}
- {"dt": "2025-04-08T12:44:29.625891", "lvl": "target", "msg": "fork01.c:47: TPASS: correct child status returned 42"}
- {"dt": "2025-04-08T12:44:29.626373", "lvl": "target", "msg": "fork01.c:50: TPASS: child_pid == pid (284)"}
- {"dt": "2025-04-08T12:44:29.628341", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:29.628574", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:29.628631", "lvl": "target", "msg": "passed   2"}
- {"dt": "2025-04-08T12:44:29.628692", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:29.628782", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-08T12:44:29.628858", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-08T12:44:29.628931", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-08T12:44:29.648819", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-08T12:44:29.649442", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Tue Apr  8 14:28:42 CEST 2025 riscv64"}
- {"dt": "2025-04-08T12:44:29.649821", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-08T12:44:29.692847", "lvl": "target", "msg": "tst_kconfig.c:676: TINFO: CONFIG_DEBUG_SPINLOCK kernel option detected which might slow the execution"}
- {"dt": "2025-04-08T12:44:29.693247", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 10m 00s"}
- {"dt": "2025-04-08T12:44:29.697798", "lvl": "target", "msg": "time01.c:36: TPASS: time() returned value 1744116269"}
- {"dt": "2025-04-08T12:44:29.698221", "lvl": "target", "msg": "time01.c:38: TPASS: time() returned value 1744116269, stored value 1744116269 are same"}
- {"dt": "2025-04-08T12:44:29.699450", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:29.699695", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:29.699749", "lvl": "target", "msg": "passed   2"}
- {"dt": "2025-04-08T12:44:29.699801", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:29.699846", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-08T12:44:29.699902", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-08T12:44:29.699958", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-08T12:44:29.718236", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-08T12:44:29.718882", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Tue Apr  8 14:28:42 CEST 2025 riscv64"}
- {"dt": "2025-04-08T12:44:29.719226", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-08T12:44:29.763147", "lvl": "target", "msg": "tst_kconfig.c:676: TINFO: CONFIG_DEBUG_SPINLOCK kernel option detected which might slow the execution"}
- {"dt": "2025-04-08T12:44:29.763621", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 10m 00s"}
- {"dt": "2025-04-08T12:44:29.772227", "lvl": "target", "msg": "wait02.c:41: TPASS: wait() succeeded"}
- {"dt": "2025-04-08T12:44:29.774292", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:29.774582", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:29.774669", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-08T12:44:29.774762", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:29.774837", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-08T12:44:29.774919", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-08T12:44:29.774989", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-08T12:44:29.795411", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-4rbq3aNnDD/LTP_wri1CyvyP as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-08T12:44:29.796444", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-08T12:44:29.796786", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Tue Apr  8 14:28:42 CEST 2025 riscv64"}
- {"dt": "2025-04-08T12:44:29.797196", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-08T12:44:29.838467", "lvl": "target", "msg": "tst_kconfig.c:676: TINFO: CONFIG_DEBUG_SPINLOCK kernel option detected which might slow the execution"}
- {"dt": "2025-04-08T12:44:29.838960", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 10m 00s"}
- {"dt": "2025-04-08T12:44:30.194390", "lvl": "target", "msg": "write01.c:40: TPASS: write() passed"}
- {"dt": "2025-04-08T12:44:30.196136", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:30.196375", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:30.196424", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-08T12:44:30.196476", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:30.196549", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-08T12:44:30.196616", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-08T12:44:30.196684", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-08T12:44:30.276189", "lvl": "target", "msg": "symlink01    0  TINFO  :  Using /scratch/ltp-4rbq3aNnDD/LTP_symmvNmrL as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-08T12:44:30.276564", "lvl": "target", "msg": "symlink01    1  TPASS  :  Creation of symbolic link file to no object file is ok"}
- {"dt": "2025-04-08T12:44:30.276863", "lvl": "target", "msg": "symlink01    2  TPASS  :  Creation of symbolic link file to no object file is ok"}
- {"dt": "2025-04-08T12:44:30.277109", "lvl": "target", "msg": "symlink01    3  TPASS  :  Creation of symbolic link file and object file via symbolic link is ok"}
- {"dt": "2025-04-08T12:44:30.277337", "lvl": "target", "msg": "symlink01    4  TPASS  :  Creating an existing symbolic link file error is caught"}
- {"dt": "2025-04-08T12:44:30.277589", "lvl": "target", "msg": "symlink01    5  TPASS  :  Creating a symbolic link which exceeds maximum pathname error is caught"}
- {"dt": "2025-04-08T12:44:30.294456", "lvl": "target", "msg": "tst_buffers.c:57: TINFO: Test is using guarded buffers"}
- {"dt": "2025-04-08T12:44:30.296356", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-4rbq3aNnDD/LTP_sta120ORO as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-08T12:44:30.306459", "lvl": "target", "msg": "tst_device.c:306: TWARN: Failed to create test_dev.img: ENOSPC (28)"}
- {"dt": "2025-04-08T12:44:30.306823", "lvl": "target", "msg": "tst_device.c:367: TBROK: Failed to acquire device"}
- {"dt": "2025-04-08T12:44:30.306909", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:30.306988", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:30.307063", "lvl": "target", "msg": "passed   0"}
- {"dt": "2025-04-08T12:44:30.307121", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:30.307192", "lvl": "target", "msg": "broken   1"}
- {"dt": "2025-04-08T12:44:30.307266", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-08T12:44:30.307322", "lvl": "target", "msg": "warnings 1"}
- {"dt": "2025-04-08T12:44:30.327387", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-4rbq3aNnDD/LTP_spl0G8Ek1 as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-08T12:44:30.327946", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-08T12:44:30.328276", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Tue Apr  8 14:28:42 CEST 2025 riscv64"}
- {"dt": "2025-04-08T12:44:30.328819", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-08T12:44:30.369991", "lvl": "target", "msg": "tst_kconfig.c:676: TINFO: CONFIG_DEBUG_SPINLOCK kernel option detected which might slow the execution"}
- {"dt": "2025-04-08T12:44:30.370286", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 10m 00s"}
- {"dt": "2025-04-08T12:44:30.375183", "lvl": "target", "msg": "splice02.c:135: TINFO: writting 20 bytes"}
- {"dt": "2025-04-08T12:44:30.379448", "lvl": "target", "msg": "splice02.c:87: TINFO: checking file content"}
- {"dt": "2025-04-08T12:44:30.379972", "lvl": "target", "msg": "splice02.c:111: TPASS: splice() system call passed"}
- {"dt": "2025-04-08T12:44:30.383229", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:30.383551", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:30.383682", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-08T12:44:30.383787", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:30.383884", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-08T12:44:30.384035", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-08T12:44:30.384109", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-08T12:44:30.519815", "lvl": "target", "msg": "shell_pipe01 1 TINFO: Running: shell_pipe01.sh "}
- {"dt": "2025-04-08T12:44:30.536915", "lvl": "target", "msg": "shell_pipe01 1 TINFO: Tested kernel: Linux tuxtest 6.4.16 #1 SMP Tue Apr  8 14:28:42 CEST 2025 riscv64 GNU/Linux"}
- {"dt": "2025-04-08T12:44:30.609481", "lvl": "target", "msg": "shell_pipe01 1 TINFO: timeout per run is 0h 25m 0s"}
- {"dt": "2025-04-08T12:44:30.661279", "lvl": "target", "msg": "shell_pipe01 1 TINFO: expecting SUCCESS string passed from stdin"}
- {"dt": "2025-04-08T12:44:30.683664", "lvl": "target", "msg": "shell_pipe01 1 TPASS: [ SUCCESS = SUCCESS ] passed as expected"}
- {"dt": "2025-04-08T12:44:30.711951", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:30.712243", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:30.712325", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-08T12:44:30.712386", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:30.712441", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-08T12:44:30.712491", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-08T12:44:30.712533", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-08T12:44:30.876628", "lvl": "target", "msg": "macsec02 1 TINFO: Running: macsec02.sh "}
- {"dt": "2025-04-08T12:44:30.894210", "lvl": "target", "msg": "macsec02 1 TINFO: Tested kernel: Linux tuxtest 6.4.16 #1 SMP Tue Apr  8 14:28:42 CEST 2025 riscv64 GNU/Linux"}
- {"dt": "2025-04-08T12:44:31.536228", "lvl": "target", "msg": "macsec02 1 TINFO: initialize 'lhost' 'ltp_ns_veth2' interface"}
- {"dt": "2025-04-08T12:44:31.553339", "lvl": "target", "msg": "cat: /proc/sys/net/ipv6/conf/ltp_ns_veth2/disable_ipv6: No such file or directory"}
- {"dt": "2025-04-08T12:44:31.556147", "lvl": "target", "msg": "/opt/ltp/testcases/bin/tst_net.sh: line 142: [: =: unary operator expected"}
- {"dt": "2025-04-08T12:44:31.602639", "lvl": "target", "msg": "ip: SIOCGIFFLAGS: No such device"}
- {"dt": "2025-04-08T12:44:31.661034", "lvl": "target", "msg": "macsec02 1 TINFO: initialize 'rhost' 'ltp_ns_veth1' interface"}
- {"dt": "2025-04-08T12:44:32.332552", "lvl": "target", "msg": "macsec02 1 TINFO: add remote addr 10.0.0.1/24"}
- {"dt": "2025-04-08T12:44:32.554601", "lvl": "target", "msg": "macsec02 1 TINFO: add remote addr fd00:1:1:1::1/64"}
- {"dt": "2025-04-08T12:44:32.659779", "lvl": "target", "msg": "ip: either \"local\" is duplicate, or \"nodad\" is garbage"}
- {"dt": "2025-04-08T12:44:32.680788", "lvl": "target", "msg": "# tst_net_iface_prefix.c:133: TINFO: prefix and interface not found for '10.0.0.2'."}
- {"dt": "2025-04-08T12:44:32.680878", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:32.822539", "lvl": "target", "msg": "cat: /proc/sys/net/ipv6/conf/ltp_ns_veth2/disable_ipv6: No such file or directory"}
- {"dt": "2025-04-08T12:44:32.824985", "lvl": "target", "msg": "/opt/ltp/testcases/bin/tst_net.sh: line 142: [: =: unary operator expected"}
- {"dt": "2025-04-08T12:44:32.935844", "lvl": "target", "msg": "# tst_net_iface_prefix.c:133: TINFO: prefix and interface not found for 'fd00:1:1:1::2'."}
- {"dt": "2025-04-08T12:44:32.935931", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:33.046435", "lvl": "target", "msg": "macsec02 1 TINFO: Network config (local -- remote):"}
- {"dt": "2025-04-08T12:44:33.047904", "lvl": "target", "msg": "macsec02 1 TINFO: ltp_ns_veth2 -- ltp_ns_veth1"}
- {"dt": "2025-04-08T12:44:33.049359", "lvl": "target", "msg": "macsec02 1 TINFO: 10.0.0.2/24 -- 10.0.0.1/24"}
- {"dt": "2025-04-08T12:44:33.051170", "lvl": "target", "msg": "macsec02 1 TINFO: fd00:1:1:1::2/64 -- fd00:1:1:1::1/64"}
- {"dt": "2025-04-08T12:44:33.117532", "lvl": "target", "msg": "cat: /sys/class/net/ltp_ns_veth2/mtu: No such file or directory"}
- {"dt": "2025-04-08T12:44:33.162929", "lvl": "target", "msg": "cat: /sys/class/net/ltp_ns_veth2/address: No such file or directory"}
- {"dt": "2025-04-08T12:44:33.280082", "lvl": "target", "msg": "macsec02 1 TINFO: ping6 binary/symlink is missing, using workaround. Please, report missing ping6 to your distribution."}
- {"dt": "2025-04-08T12:44:33.474486", "lvl": "target", "msg": "macsec02 1 TCONF: macsec driver not available"}
- {"dt": "2025-04-08T12:44:33.489970", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:33.490116", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-08T12:44:33.490383", "lvl": "target", "msg": "passed   0"}
- {"dt": "2025-04-08T12:44:33.490459", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-08T12:44:33.490525", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-08T12:44:33.490585", "lvl": "target", "msg": "skipped  1"}
- {"dt": "2025-04-08T12:44:33.490645", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-08T12:44:33.499654", "lvl": "target", "msg": "INFO: ltp-pan reported some tests FAIL"}
- {"dt": "2025-04-08T12:44:33.499758", "lvl": "target", "msg": "LTP Version: 20250130"}
- {"dt": "2025-04-08T12:44:33.513586", "lvl": "target", "msg": "INFO: Test end time: Tue Apr  8 12:44:32 UTC 2025"}
- {"dt": "2025-04-08T12:44:33.525525", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:33.525813", "lvl": "target", "msg": "       ###############################################################"}
- {"dt": "2025-04-08T12:44:33.525912", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:33.525994", "lvl": "target", "msg": "            Done executing testcases."}
- {"dt": "2025-04-08T12:44:33.526067", "lvl": "target", "msg": "            LTP Version:  20250130"}
- {"dt": "2025-04-08T12:44:33.526138", "lvl": "target", "msg": "       ###############################################################"}
- {"dt": "2025-04-08T12:44:33.526204", "lvl": "target", "msg": ""}
- {"dt": "2025-04-08T12:44:33.528904", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-08T12:44:33.529199", "lvl": "target", "msg": "INFO: runltp script is deprecated, try kirk"}
- {"dt": "2025-04-08T12:44:33.529476", "lvl": "target", "msg": "https://github.com/linux-test-project/kirk"}
- {"dt": "2025-04-08T12:44:33.529744", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-08T12:44:33.549022", "lvl": "target", "msg": "++ echo 1"}
- {"dt": "2025-04-08T12:44:33.557643", "lvl": "target", "msg": "+ ret_val=1"}
- {"dt": "2025-04-08T12:44:33.557751", "lvl": "target", "msg": "+ exec"}
- {"dt": "2025-04-08T12:44:33.558115", "lvl": "target", "msg": "+ return 1"}
- {"dt": "2025-04-08T12:44:33.559119", "lvl": "target", "msg": "+ parse_ltp_output /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log"}
- {"dt": "2025-04-08T12:44:33.565177", "lvl": "target", "msg": "+ grep -E 'PASS|FAIL|CONF' /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log"}
- {"dt": "2025-04-08T12:44:33.571865", "lvl": "target", "msg": "+ awk '{print $1\" \"$2}'"}
- {"dt": "2025-04-08T12:44:33.576408", "lvl": "target", "msg": "+ sed 's/PASS/pass/; s/FAIL/fail/; s/CONF/skip/'"}
- {"dt": "2025-04-08T12:44:33.588514", "lvl": "target", "msg": "+ rm -rf /scratch"}
- {"dt": "2025-04-08T12:44:33.604072", "lvl": "target", "msg": "+ rm -rf alltests"}
- {"dt": "2025-04-08T12:44:33.620384", "lvl": "target", "msg": "+ ../../utils/send-to-lava.sh ./output/result.txt"}
- {"dt": "2025-04-08T12:44:33.671928", "lvl": "target", "msg": "WARNING: no log file found for access01 at ./output/access01.log"}
- {"dt": "2025-04-08T12:44:33.706400", "lvl": "target", "msg": "<8>[    7.997022] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=access01 RESULT=pass>"}
- {"dt": "2025-04-08T12:44:33.706750", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=access01 RESULT=pass"}
- {"dt": "2025-04-08T12:44:33.706870", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "access01", "result": "pass", "starttc": ! "1045", "endtc": ! "1045"}}
- {"dt": "2025-04-08T12:44:33.725046", "lvl": "target", "msg": "WARNING: no log file found for chdir01 at ./output/chdir01.log"}
- {"dt": "2025-04-08T12:44:33.758353", "lvl": "target", "msg": "<8>[    8.048968] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=chdir01 RESULT=skip>"}
- {"dt": "2025-04-08T12:44:33.758649", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=chdir01 RESULT=skip"}
- {"dt": "2025-04-08T12:44:33.758736", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "chdir01", "result": "skip", "starttc": ! "1049", "endtc": ! "1049"}}
- {"dt": "2025-04-08T12:44:33.777555", "lvl": "target", "msg": "WARNING: no log file found for fork01 at ./output/fork01.log"}
- {"dt": "2025-04-08T12:44:33.811118", "lvl": "target", "msg": "<8>[    8.101769] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=fork01 RESULT=pass>"}
- {"dt": "2025-04-08T12:44:33.811376", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=fork01 RESULT=pass"}
- {"dt": "2025-04-08T12:44:33.811455", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "fork01", "result": "pass", "starttc": ! "1053", "endtc": ! "1053"}}
- {"dt": "2025-04-08T12:44:33.830491", "lvl": "target", "msg": "WARNING: no log file found for time01 at ./output/time01.log"}
- {"dt": "2025-04-08T12:44:33.863857", "lvl": "target", "msg": "<8>[    8.154512] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=time01 RESULT=pass>"}
- {"dt": "2025-04-08T12:44:33.864132", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=time01 RESULT=pass"}
- {"dt": "2025-04-08T12:44:33.864254", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "time01", "result": "pass", "starttc": ! "1057", "endtc": ! "1057"}}
- {"dt": "2025-04-08T12:44:33.883101", "lvl": "target", "msg": "WARNING: no log file found for wait02 at ./output/wait02.log"}
- {"dt": "2025-04-08T12:44:33.916340", "lvl": "target", "msg": "<8>[    8.207000] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=wait02 RESULT=pass>"}
- {"dt": "2025-04-08T12:44:33.916609", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=wait02 RESULT=pass"}
- {"dt": "2025-04-08T12:44:33.916717", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "wait02", "result": "pass", "starttc": ! "1061", "endtc": ! "1061"}}
- {"dt": "2025-04-08T12:44:33.935967", "lvl": "target", "msg": "WARNING: no log file found for write01 at ./output/write01.log"}
- {"dt": "2025-04-08T12:44:33.969100", "lvl": "target", "msg": "<8>[    8.259741] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=write01 RESULT=pass>"}
- {"dt": "2025-04-08T12:44:33.969394", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=write01 RESULT=pass"}
- {"dt": "2025-04-08T12:44:33.969517", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "write01", "result": "pass", "starttc": ! "1065", "endtc": ! "1065"}}
- {"dt": "2025-04-08T12:44:33.988680", "lvl": "target", "msg": "WARNING: no log file found for symlink01 at ./output/symlink01.log"}
- {"dt": "2025-04-08T12:44:34.021779", "lvl": "target", "msg": "<8>[    8.312412] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=symlink01 RESULT=pass>"}
- {"dt": "2025-04-08T12:44:34.022071", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=symlink01 RESULT=pass"}
- {"dt": "2025-04-08T12:44:34.022175", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "symlink01", "result": "pass", "starttc": ! "1069", "endtc": ! "1069"}}
- {"dt": "2025-04-08T12:44:34.043733", "lvl": "target", "msg": "WARNING: no log file found for stat04 at ./output/stat04.log"}
- {"dt": "2025-04-08T12:44:34.076550", "lvl": "target", "msg": "<8>[    8.367211] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=stat04 RESULT=fail>"}
- {"dt": "2025-04-08T12:44:34.076796", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=stat04 RESULT=fail"}
- {"dt": "2025-04-08T12:44:34.076912", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "stat04", "result": "fail", "starttc": ! "1073", "endtc": ! "1073"}}
- {"dt": "2025-04-08T12:44:34.095677", "lvl": "target", "msg": "WARNING: no log file found for splice02 at ./output/splice02.log"}
- {"dt": "2025-04-08T12:44:34.127708", "lvl": "target", "msg": "<8>[    8.418354] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=splice02 RESULT=pass>"}
- {"dt": "2025-04-08T12:44:34.127992", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=splice02 RESULT=pass"}
- {"dt": "2025-04-08T12:44:34.128110", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "splice02", "result": "pass", "starttc": ! "1077", "endtc": ! "1077"}}
- {"dt": "2025-04-08T12:44:34.147950", "lvl": "target", "msg": "WARNING: no log file found for df01_sh at ./output/df01_sh.log"}
- {"dt": "2025-04-08T12:44:34.181051", "lvl": "target", "msg": "<8>[    8.471688] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=df01_sh RESULT=skip>"}
- {"dt": "2025-04-08T12:44:34.181329", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=df01_sh RESULT=skip"}
- {"dt": "2025-04-08T12:44:34.181413", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "df01_sh", "result": "skip", "starttc": ! "1081", "endtc": ! "1081"}}
- {"dt": "2025-04-08T12:44:34.200478", "lvl": "target", "msg": "WARNING: no log file found for shell_test01 at ./output/shell_test01.log"}
- {"dt": "2025-04-08T12:44:34.233741", "lvl": "target", "msg": "<8>[    8.524404] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=shell_test01 RESULT=pass>"}
- {"dt": "2025-04-08T12:44:34.234034", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=shell_test01 RESULT=pass"}
- {"dt": "2025-04-08T12:44:34.234118", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "shell_test01", "result": "pass", "starttc": ! "1085", "endtc": ! "1085"}}
- {"dt": "2025-04-08T12:44:34.252866", "lvl": "target", "msg": "WARNING: no log file found for ping602 at ./output/ping602.log"}
- {"dt": "2025-04-08T12:44:34.285734", "lvl": "target", "msg": "<8>[    8.576401] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=ping602 RESULT=skip>"}
- {"dt": "2025-04-08T12:44:34.286012", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=ping602 RESULT=skip"}
- {"dt": "2025-04-08T12:44:34.286102", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "ping602", "result": "skip", "starttc": ! "1089", "endtc": ! "1089"}}
- {"dt": "2025-04-08T12:44:34.305311", "lvl": "target", "msg": "WARNING: no log file found for macsec02 at ./output/macsec02.log"}
- {"dt": "2025-04-08T12:44:34.338954", "lvl": "target", "msg": "<8>[    8.629585] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=macsec02 RESULT=skip>"}
- {"dt": "2025-04-08T12:44:34.339230", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=macsec02 RESULT=skip"}
- {"dt": "2025-04-08T12:44:34.339319", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "macsec02", "result": "skip", "starttc": ! "1093", "endtc": ! "1093"}}
- {"dt": "2025-04-08T12:44:34.343467", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-04-08T12:44:34.344035", "lvl": "target", "msg": "<8>[    8.634723] <LAVA_SIGNAL_ENDRUN 0_ltp-smoke 1_1.1.3.1>"}
- {"dt": "2025-04-08T12:44:34.344276", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_ltp-smoke 1_1.1.3.1"}
- {"dt": "2025-04-08T12:44:34.344349", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2025-04-08T12:44:34.344395", "lvl": "info", "msg": "Ending test lava.0_ltp-smoke (1_1.1.3.1), duration 6.42"}
- {"dt": "2025-04-08T12:44:34.344453", "lvl": "results", "msg": {"definition": "lava", "case": "0_ltp-smoke", "uuid": "1_1.1.3.1", "repository": "file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst", "path": "automated/linux/ltp/ltp.yaml", "duration": "6.42", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2025-04-08T12:44:34.348745", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2025-04-08T12:44:34.348990", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2025-04-08T12:44:34.349147", "lvl": "debug", "msg": "access01: pass\nchdir01: skip\nfork01: pass\ntime01: pass\nwait02: pass\nwrite01: pass\nsymlink01: pass\nstat04: fail\nsplice02: pass\ndf01_sh: skip\nshell_test01: pass\nping602: skip\nmacsec02: skip\n"}
- {"dt": "2025-04-08T12:44:34.349244", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:07) [common]"}
- {"dt": "2025-04-08T12:44:34.349334", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:07) [common]"}
- {"dt": "2025-04-08T12:44:34.349403", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2025-04-08T12:44:34.349465", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2025-04-08T12:44:34.349524", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2025-04-08T12:44:34.349579", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2025-04-08T12:44:34.349682", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-04-08T12:44:34.351352", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-04-08T12:44:35.350763", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2025-04-08T12:44:35.350927", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2025-04-08T12:44:35.451562", "lvl": "input", "msg": "poweroff"}
- {"dt": "2025-04-08T12:44:35.451980", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-04-08T12:44:35.452100", "lvl": "target", "msg": "root@tuxtest:~# poweroff"}
- {"dt": "2025-04-08T12:44:35.552726", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2025-04-08T12:44:35.552889", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-04-08T12:44:35.552977", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2025-04-08T12:44:35.553106", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2025-04-08T12:44:35.553272", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/url-repo-action-e1t2c_ox/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar"}
- {"dt": "2025-04-08T12:44:35.560927", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/deployimages-4csfsum8/kernel/Image"}
- {"dt": "2025-04-08T12:44:35.564259", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1/deployimages-4csfsum8/rootfs/rootfs.ext2"}
- {"dt": "2025-04-08T12:44:35.704778", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-s92x6ytv-1"}
- {"dt": "2025-04-08T12:44:35.738707", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2025-04-08T12:44:35.738780", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
