- {"dt": "2025-04-07T09:24:06.524638", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2024.09.dev0100"}
- {"dt": "2025-04-07T09:24:06.524724", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2025-04-07T09:24:06.524800", "lvl": "info", "msg": "Start time: 2025-04-07 09:24:06.524794+00:00 (UTC)"}
- {"dt": "2025-04-07T09:24:06.525546", "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-07T09:24:06.525876", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tuxtest-buildroot/builds/arm64/images/Image exists"}
- {"dt": "2025-04-07T09:24:06.525974", "lvl": "debug", "msg": "Validating that file:///home/anders/src/tuxtest-buildroot/builds/arm64/images/rootfs.ext2.zst exists"}
- {"dt": "2025-04-07T09:24:06.534754", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:9.2.0+ds-2, host architecture: amd64"}
- {"dt": "2025-04-07T09:24:06.534996", "lvl": "warning", "msg": "Missing image arg for url_repo"}
- {"dt": "2025-04-07T09:24:06.535192", "lvl": "info", "msg": "validate duration: 0.01"}
- {"dt": "2025-04-07T09:24:06.535269", "lvl": "results", "msg": {"definition": "lava", "case": "validate", "result": "pass"}}
- {"dt": "2025-04-07T09:24:06.535363", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.535423", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.535562", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz"}
- {"dt": "2025-04-07T09:24:06.535693", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin"}
- {"dt": "2025-04-07T09:24:06.535785", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/tests"}
- {"dt": "2025-04-07T09:24:06.535880", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/results"}
- {"dt": "2025-04-07T09:24:06.535952", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-add-keys"}
- {"dt": "2025-04-07T09:24:06.536170", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-add-sources"}
- {"dt": "2025-04-07T09:24:06.536379", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-background-process-start"}
- {"dt": "2025-04-07T09:24:06.536591", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-background-process-stop"}
- {"dt": "2025-04-07T09:24:06.536799", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-common-functions"}
- {"dt": "2025-04-07T09:24:06.536999", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-echo-ipv4"}
- {"dt": "2025-04-07T09:24:06.537199", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-install-packages"}
- {"dt": "2025-04-07T09:24:06.537397", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-installed-packages"}
- {"dt": "2025-04-07T09:24:06.537594", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-os-build"}
- {"dt": "2025-04-07T09:24:06.537800", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-probe-channel"}
- {"dt": "2025-04-07T09:24:06.538002", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-probe-ip"}
- {"dt": "2025-04-07T09:24:06.538201", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-target-ip"}
- {"dt": "2025-04-07T09:24:06.538405", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-target-mac"}
- {"dt": "2025-04-07T09:24:06.538610", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-target-storage"}
- {"dt": "2025-04-07T09:24:06.538812", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-test-case"}
- {"dt": "2025-04-07T09:24:06.539007", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-test-event"}
- {"dt": "2025-04-07T09:24:06.539201", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-test-feedback"}
- {"dt": "2025-04-07T09:24:06.539397", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-test-raise"}
- {"dt": "2025-04-07T09:24:06.539595", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-test-reference"}
- {"dt": "2025-04-07T09:24:06.539790", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-test-runner"}
- {"dt": "2025-04-07T09:24:06.539984", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-test-set"}
- {"dt": "2025-04-07T09:24:06.540179", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-test-shell"}
- {"dt": "2025-04-07T09:24:06.540378", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-install-packages (oe)"}
- {"dt": "2025-04-07T09:24:06.540595", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/bin/lava-installed-packages (oe)"}
- {"dt": "2025-04-07T09:24:06.540778", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/environment"}
- {"dt": "2025-04-07T09:24:06.540866", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2025-04-07T09:24:06.540914", "lvl": "debug", "msg": "- LAVA_JOB_ID=1"}
- {"dt": "2025-04-07T09:24:06.540956", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.32.138"}
- {"dt": "2025-04-07T09:24:06.541028", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.541073", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2025-04-07T09:24:06.541124", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.541178", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.541217", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2025-04-07T09:24:06.541263", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.541312", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.541359", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2025-04-07T09:24:06.541415", "lvl": "debug", "msg": "start: 1.1.3.1 url-repo-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.541467", "lvl": "debug", "msg": "start: 1.1.3.1.1 download-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.541520", "lvl": "debug", "msg": "start: 1.1.3.1.1.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-07T09:24:06.541633", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.tuxboot.com_test-definitions_2025.03.tar.zst"}
- {"dt": "2025-04-07T09:24:06.541683", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/url-repo-action-b66svup_/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar"}
- {"dt": "2025-04-07T09:24:06.541723", "lvl": "debug", "msg": "total size: 13699163 (13\u202FMB)"}
- {"dt": "2025-04-07T09:24:06.541770", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2025-04-07T09:24:06.593842", "lvl": "info", "msg": "13\u202FMB downloaded in 0.05\u202Fs (250.74\u202FMB/s)"}
- {"dt": "2025-04-07T09:24:06.594080", "lvl": "debug", "msg": "end: 1.1.3.1.1.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.594161", "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-07T09:24:06.594308", "lvl": "debug", "msg": "end: 1.1.3.1.1 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.594383", "lvl": "debug", "msg": "Using /lava-1 at stage 0"}
- {"dt": "2025-04-07T09:24:06.594454", "lvl": "debug", "msg": "Runner path : /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-07T09:24:06.594520", "lvl": "info", "msg": "Untar tests from file /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/url-repo-action-b66svup_/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar to directory /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-07T09:24:06.791382", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.yaml"}
- {"dt": "2025-04-07T09:24:06.791742", "lvl": "debug", "msg": "uuid=1_1.1.3.1 testdef=None"}
- {"dt": "2025-04-07T09:24:06.791835", "lvl": "debug", "msg": "end: 1.1.3.1 url-repo-action (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.791904", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.792496", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.792551", "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-07T09:24:06.792675", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.793457", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.793512", "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-07T09:24:06.793634", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:06.801911", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/0/tests/0_ltp-smoke test_uuid 1_1.1.3.1"}
- {"dt": "2025-04-07T09:24:06.801974", "lvl": "debug", "msg": "SKIP_INSTALL='true'"}
- {"dt": "2025-04-07T09:24:06.802019", "lvl": "debug", "msg": "TST_CMDFILES='smoketest'"}
- {"dt": "2025-04-07T09:24:06.802058", "lvl": "debug", "msg": "SKIPFILE='/tuxtest/skipfiles/arm64/ltp-smoke'"}
- {"dt": "2025-04-07T09:24:06.802096", "lvl": "debug", "msg": "ENVIRONMENT='production'"}
- {"dt": "2025-04-07T09:24:06.802132", "lvl": "debug", "msg": "LTP_TMPDIR='/scratch'"}
- {"dt": "2025-04-07T09:24:06.802168", "lvl": "debug", "msg": "LTP_INSTALL_PATH='/opt/ltp/'"}
- {"dt": "2025-04-07T09:24:06.802204", "lvl": "debug", "msg": "SHARD_NUMBER='1'"}
- {"dt": "2025-04-07T09:24:06.802240", "lvl": "debug", "msg": "SHARD_INDEX='1'"}
- {"dt": "2025-04-07T09:24:06.802276", "lvl": "debug", "msg": "RUNNER=''"}
- {"dt": "2025-04-07T09:24:06.802312", "lvl": "debug", "msg": "TIMEOUT_MULTIPLIER='5'"}
- {"dt": "2025-04-07T09:24:06.802432", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.802486", "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-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/0/tests/0_ltp-smoke/run.sh"}}}
- {"dt": "2025-04-07T09:24:06.802582", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2025-04-07T09:24:06.802623", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/lava-overlay-gz5f8fpz/lava-1/0 for stage 0"}
- {"dt": "2025-04-07T09:24:06.802695", "lvl": "debug", "msg": "- 0_ltp-smoke"}
- {"dt": "2025-04-07T09:24:06.802772", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:06.802831", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:12.533340", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:06) [common]"}
- {"dt": "2025-04-07T09:24:12.533472", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:06) [common]"}
- {"dt": "2025-04-07T09:24:12.533538", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 00:14:54) [common]"}
- {"dt": "2025-04-07T09:24:12.533588", "lvl": "debug", "msg": "Overlay already applied"}
- {"dt": "2025-04-07T09:24:12.533640", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:12.533694", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 00:14:54) [common]"}
- {"dt": "2025-04-07T09:24:12.533745", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:12.533808", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 00:14:54) [common]"}
- {"dt": "2025-04-07T09:24:12.533862", "lvl": "debug", "msg": "start: 1.4.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-07T09:24:12.534039", "lvl": "info", "msg": "downloading file:///home/anders/src/tuxtest-buildroot/builds/arm64/images/Image"}
- {"dt": "2025-04-07T09:24:12.534092", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/deployimages-zkzwa7eu/kernel/Image"}
- {"dt": "2025-04-07T09:24:12.534137", "lvl": "debug", "msg": "total size: 12300800 (11\u202FMB)"}
- {"dt": "2025-04-07T09:24:12.534179", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2025-04-07T09:24:12.547295", "lvl": "info", "msg": "11\u202FMB downloaded in 0.01\u202Fs (891.81\u202FMB/s)"}
- {"dt": "2025-04-07T09:24:12.547394", "lvl": "debug", "msg": "end: 1.4.1 file-download (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:12.547447", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.4.1", "duration": "0.01", "result": "pass", "extra": {"label": "kernel", "size": ! "12300800", "sha256sum": "19c9c1589933aa02b74d96caca48f2e19752ec7dbc9a5c2aca4668c8f27c9188"}}}
- {"dt": "2025-04-07T09:24:12.547547", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:12.547609", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 00:14:54) [common]"}
- {"dt": "2025-04-07T09:24:12.547668", "lvl": "debug", "msg": "start: 1.5.1 file-download (timeout 00:05:00) [common]"}
- {"dt": "2025-04-07T09:24:12.547820", "lvl": "info", "msg": "downloading file:///home/anders/src/tuxtest-buildroot/builds/arm64/images/rootfs.ext2.zst"}
- {"dt": "2025-04-07T09:24:12.547877", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/deployimages-zkzwa7eu/rootfs/rootfs.ext2"}
- {"dt": "2025-04-07T09:24:12.547919", "lvl": "debug", "msg": "total size: 76435969 (72\u202FMB)"}
- {"dt": "2025-04-07T09:24:12.547959", "lvl": "info", "msg": "Using unzstd to decompress zstd"}
- {"dt": "2025-04-07T09:24:12.648301", "lvl": "debug", "msg": "progress  24\u202F% (17\u202FMB)"}
- {"dt": "2025-04-07T09:24:12.748784", "lvl": "debug", "msg": "progress  47\u202F% (34\u202FMB)"}
- {"dt": "2025-04-07T09:24:12.849737", "lvl": "debug", "msg": "progress  69\u202F% (50\u202FMB)"}
- {"dt": "2025-04-07T09:24:13.165204", "lvl": "info", "msg": "72\u202FMB downloaded in 0.62\u202Fs (118.09\u202FMB/s)"}
- {"dt": "2025-04-07T09:24:13.165434", "lvl": "debug", "msg": "end: 1.5.1 file-download (duration 00:00:01) [common]"}
- {"dt": "2025-04-07T09:24:13.165514", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "file-download", "level": "1.5.1", "duration": "0.62", "result": "pass", "extra": {"label": "rootfs", "size": ! "76435969", "sha256sum": "3d0449c429492eb0be150bcf4bc08683dcde5ad4597394df3afa48e8531e5c98"}}}
- {"dt": "2025-04-07T09:24:13.165666", "lvl": "debug", "msg": "start: 1.5.2 append-overlays (timeout 00:05:00) [common]"}
- {"dt": "2025-04-07T09:24:13.165737", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/deployimages-zkzwa7eu/rootfs/rootfs.ext2'"}
- {"dt": "2025-04-07T09:24:14.853620", "lvl": "debug", "msg": "Overlays:"}
- {"dt": "2025-04-07T09:24:14.854272", "lvl": "debug", "msg": "- rootfs.lava: '/var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/compress-overlay-n_g26n0z/overlay-1.1.4.tar.gz' to '/'"}
- {"dt": "2025-04-07T09:24:15.468295", "lvl": "debug", "msg": "end: 1.5.2 append-overlays (duration 00:00:02) [common]"}
- {"dt": "2025-04-07T09:24:15.468679", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:00:03) [common]"}
- {"dt": "2025-04-07T09:24:15.468783", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:09) [common]"}
- {"dt": "2025-04-07T09:24:15.468882", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:15.468969", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:15.469050", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:15.469344", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-aarch64 -cpu max,pauth-impdef=on -machine virt,virtualization=on,gic-version=3,mte=on -nographic -nic none -m 4G -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/deployimages-zkzwa7eu/kernel/Image -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning earlycon\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/deployimages-zkzwa7eu/rootfs/rootfs.ext2,format=raw,id=hd0,if=virtio"}
- {"dt": "2025-04-07T09:24:15.472818", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2025-04-07T09:24:15.473162", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:15.473247", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "execute-qemu", "level": "2.1.1", "duration": "0.00", "result": "pass", "extra": {"qemu_version": "1:9.2.0+ds-2", "host_arch": "amd64", "job_arch": "arm64"}}}
- {"dt": "2025-04-07T09:24:15.473371", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:15.473456", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:15.473535", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2025-04-07T09:24:15.473597", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:15:00)"}
- {"dt": "2025-04-07T09:24:15.674414", "lvl": "target", "msg": "Booting Linux on physical CPU 0x0000000000 [0x000f0510]"}
- {"dt": "2025-04-07T09:24:15.674684", "lvl": "target", "msg": "Linux version 6.4.16 (anders@monster) (aarch64-linux-gcc.br_real (Buildroot 2021.11-12449-g1bef613319) 14.2.0, GNU ld (GNU Binutils) 2.42) #1 SMP Mon Apr  7 11:12:06 CEST 2025"}
- {"dt": "2025-04-07T09:24:15.674774", "lvl": "target", "msg": "random: crng init done"}
- {"dt": "2025-04-07T09:24:15.674847", "lvl": "target", "msg": "Machine model: linux,dummy-virt"}
- {"dt": "2025-04-07T09:24:15.674902", "lvl": "target", "msg": "efi: UEFI not found."}
- {"dt": "2025-04-07T09:24:15.674967", "lvl": "target", "msg": "earlycon: pl11 at MMIO 0x0000000009000000 (options '')"}
- {"dt": "2025-04-07T09:24:15.675034", "lvl": "target", "msg": "printk: bootconsole [pl11] enabled"}
- {"dt": "2025-04-07T09:24:15.675306", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:15:00) [common]"}
- {"dt": "2025-04-07T09:24:15.675387", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2025-04-07T09:24:15.675463", "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-07T09:24:15.675534", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2025-04-07T09:24:15.675587", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2025-04-07T09:24:15.675630", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2025-04-07T09:24:15.675665", "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-07T09:24:15.675735", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:15:00)"}
- {"dt": "2025-04-07T09:24:15.675780", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2025-04-07T09:24:15.678736", "lvl": "target", "msg": "Zone ranges:"}
- {"dt": "2025-04-07T09:24:15.679170", "lvl": "target", "msg": "  DMA      [mem 0x0000000040000000-0x00000000ffffffff]"}
- {"dt": "2025-04-07T09:24:15.679230", "lvl": "target", "msg": "  DMA32    empty"}
- {"dt": "2025-04-07T09:24:15.679288", "lvl": "target", "msg": "  Normal   [mem 0x0000000100000000-0x000000013fffffff]"}
- {"dt": "2025-04-07T09:24:15.679351", "lvl": "target", "msg": "Movable zone start for each node"}
- {"dt": "2025-04-07T09:24:15.679435", "lvl": "target", "msg": "Early memory node ranges"}
- {"dt": "2025-04-07T09:24:15.679613", "lvl": "target", "msg": "  node   0: [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-04-07T09:24:15.679995", "lvl": "target", "msg": "Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]"}
- {"dt": "2025-04-07T09:24:15.705247", "lvl": "target", "msg": "psci: probing for conduit method from DT."}
- {"dt": "2025-04-07T09:24:15.705346", "lvl": "target", "msg": "psci: PSCIv1.1 detected in firmware."}
- {"dt": "2025-04-07T09:24:15.705451", "lvl": "target", "msg": "psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2025-04-07T09:24:15.705723", "lvl": "target", "msg": "psci: Trusted OS migration not required"}
- {"dt": "2025-04-07T09:24:15.705829", "lvl": "target", "msg": "psci: SMC Calling Convention v1.0"}
- {"dt": "2025-04-07T09:24:15.707690", "lvl": "target", "msg": "percpu: Embedded 18 pages/cpu s35112 r8192 d30424 u73728"}
- {"dt": "2025-04-07T09:24:15.708230", "lvl": "target", "msg": "pcpu-alloc: s35112 r8192 d30424 u73728 alloc=18*4096"}
- {"dt": "2025-04-07T09:24:15.708333", "lvl": "target", "msg": "pcpu-alloc: [0] 0 [0] 1 "}
- {"dt": "2025-04-07T09:24:15.709566", "lvl": "target", "msg": "Detected PIPT I-cache on CPU0"}
- {"dt": "2025-04-07T09:24:15.713341", "lvl": "target", "msg": "CPU features: detected: Address authentication (IMP DEF algorithm)"}
- {"dt": "2025-04-07T09:24:15.713879", "lvl": "target", "msg": "CPU features: detected: GIC system register CPU interface"}
- {"dt": "2025-04-07T09:24:15.713989", "lvl": "target", "msg": "CPU features: detected: Virtualization Host Extensions"}
- {"dt": "2025-04-07T09:24:15.714218", "lvl": "target", "msg": "CPU features: detected: Hardware dirty bit management"}
- {"dt": "2025-04-07T09:24:15.714321", "lvl": "target", "msg": "CPU features: detected: Memory Tagging Extension"}
- {"dt": "2025-04-07T09:24:15.714456", "lvl": "target", "msg": "CPU features: detected: Asymmetric MTE Tag Check Fault"}
- {"dt": "2025-04-07T09:24:15.714701", "lvl": "target", "msg": "CPU features: detected: Spectre-v4"}
- {"dt": "2025-04-07T09:24:15.716959", "lvl": "target", "msg": "alternatives: applying boot alternatives"}
- {"dt": "2025-04-07T09:24:15.719644", "lvl": "target", "msg": "Kernel command line: console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog systemd.log_level=warning earlycon"}
- {"dt": "2025-04-07T09:24:15.721636", "lvl": "target", "msg": "<5>Unknown kernel command line parameters \"verbose\", will be passed to user space."}
- {"dt": "2025-04-07T09:24:15.725620", "lvl": "target", "msg": "<6>Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2025-04-07T09:24:15.726648", "lvl": "target", "msg": "<6>Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)"}
- {"dt": "2025-04-07T09:24:15.727293", "lvl": "target", "msg": "<6>Built 1 zonelists, mobility grouping on.  Total pages: 1032192"}
- {"dt": "2025-04-07T09:24:15.727647", "lvl": "target", "msg": "<6>mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2025-04-07T09:24:15.727921", "lvl": "target", "msg": "<6>software IO TLB: area num 2."}
- {"dt": "2025-04-07T09:24:15.776906", "lvl": "target", "msg": "<6>software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB)"}
- {"dt": "2025-04-07T09:24:15.791806", "lvl": "target", "msg": "<6>Memory: 4034392K/4194304K available (7744K kernel code, 876K rwdata, 1860K rodata, 1408K init, 438K bss, 159912K reserved, 0K cma-reserved)"}
- {"dt": "2025-04-07T09:24:15.795580", "lvl": "target", "msg": "<6>SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"}
- {"dt": "2025-04-07T09:24:15.800895", "lvl": "target", "msg": "<6>rcu: Hierarchical RCU implementation."}
- {"dt": "2025-04-07T09:24:15.800983", "lvl": "target", "msg": "<6>rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2."}
- {"dt": "2025-04-07T09:24:15.801283", "lvl": "target", "msg": "<6>rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2025-04-07T09:24:15.801368", "lvl": "target", "msg": "<6>rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"}
- {"dt": "2025-04-07T09:24:15.802467", "lvl": "target", "msg": "<6>NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2025-04-07T09:24:15.806618", "lvl": "target", "msg": "<6>GICv3: GIC: Using split EOI/Deactivate mode"}
- {"dt": "2025-04-07T09:24:15.806702", "lvl": "target", "msg": "<6>GICv3: 256 SPIs implemented"}
- {"dt": "2025-04-07T09:24:15.806770", "lvl": "target", "msg": "<6>GICv3: 0 Extended SPIs implemented"}
- {"dt": "2025-04-07T09:24:15.808044", "lvl": "target", "msg": "<6>Root IRQ handler: gic_handle_irq"}
- {"dt": "2025-04-07T09:24:15.808168", "lvl": "target", "msg": "<6>GICv3: GICv3 features: 16 PPIs"}
- {"dt": "2025-04-07T09:24:15.808727", "lvl": "target", "msg": "<6>GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000"}
- {"dt": "2025-04-07T09:24:15.812079", "lvl": "target", "msg": "<6>ITS [mem 0x08080000-0x0809ffff]"}
- {"dt": "2025-04-07T09:24:15.812723", "lvl": "target", "msg": "<6>ITS@0x0000000008080000: allocated 8192 Devices @100040000 (indirect, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-04-07T09:24:15.813007", "lvl": "target", "msg": "<6>ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100050000 (flat, esz 8, psz 64K, shr 1)"}
- {"dt": "2025-04-07T09:24:15.813654", "lvl": "target", "msg": "<6>GICv3: using LPI property table @0x0000000100060000"}
- {"dt": "2025-04-07T09:24:15.814287", "lvl": "target", "msg": "<6>GICv3: CPU0: using allocated LPI pending table @0x0000000100070000"}
- {"dt": "2025-04-07T09:24:15.816031", "lvl": "target", "msg": "<6>rcu: srcu_init: Setting srcu_struct sizes based on contention."}
- {"dt": "2025-04-07T09:24:15.819838", "lvl": "target", "msg": "<6>arch_timer: cp15 timer(s) running at 1000.00MHz (phys)."}
- {"dt": "2025-04-07T09:24:15.820419", "lvl": "target", "msg": "<6>clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns"}
- {"dt": "2025-04-07T09:24:15.820882", "lvl": "target", "msg": "<6>sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns"}
- {"dt": "2025-04-07T09:24:15.833311", "lvl": "target", "msg": "<6>Console: colour dummy device 80x25"}
- {"dt": "2025-04-07T09:24:15.835170", "lvl": "target", "msg": "<6>Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)"}
- {"dt": "2025-04-07T09:24:15.835333", "lvl": "target", "msg": "<6>pid_max: default: 32768 minimum: 301"}
- {"dt": "2025-04-07T09:24:15.838383", "lvl": "target", "msg": "<6>Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-07T09:24:15.838587", "lvl": "target", "msg": "<6>Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-07T09:24:15.860440", "lvl": "target", "msg": "<4>cacheinfo: Unable to detect cache hierarchy for CPU 0"}
- {"dt": "2025-04-07T09:24:15.867655", "lvl": "target", "msg": "<6>rcu: Hierarchical SRCU implementation."}
- {"dt": "2025-04-07T09:24:15.867719", "lvl": "target", "msg": "<6>rcu: \tMax phase no-delay instances is 1000."}
- {"dt": "2025-04-07T09:24:15.870516", "lvl": "target", "msg": "<6>Platform MSI: its@8080000 domain created"}
- {"dt": "2025-04-07T09:24:15.871043", "lvl": "target", "msg": "<6>PCI/MSI: /intc@8000000/its@8080000 domain created"}
- {"dt": "2025-04-07T09:24:15.871132", "lvl": "target", "msg": "<6>EFI services will not be available."}
- {"dt": "2025-04-07T09:24:15.872572", "lvl": "target", "msg": "<6>smp: Bringing up secondary CPUs ..."}
- {"dt": "2025-04-07T09:24:15.885739", "lvl": "target", "msg": "<6>Detected PIPT I-cache on CPU1"}
- {"dt": "2025-04-07T09:24:15.885826", "lvl": "target", "msg": "<6>GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000"}
- {"dt": "2025-04-07T09:24:15.885897", "lvl": "target", "msg": "<6>GICv3: CPU1: using allocated LPI pending table @0x0000000100080000"}
- {"dt": "2025-04-07T09:24:15.885983", "lvl": "target", "msg": "<6>CPU1: Booted secondary processor 0x0000000001 [0x000f0510]"}
- {"dt": "2025-04-07T09:24:15.886079", "lvl": "target", "msg": "<6>smp: Brought up 1 node, 2 CPUs"}
- {"dt": "2025-04-07T09:24:15.886221", "lvl": "target", "msg": "<6>SMP: Total of 2 processors activated."}
- {"dt": "2025-04-07T09:24:15.886894", "lvl": "target", "msg": "<6>CPU features: detected: Branch Target Identification"}
- {"dt": "2025-04-07T09:24:15.886969", "lvl": "target", "msg": "<6>CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2025-04-07T09:24:15.887090", "lvl": "target", "msg": "<6>CPU features: detected: ARMv8.4 Translation Table Level"}
- {"dt": "2025-04-07T09:24:15.887353", "lvl": "target", "msg": "<6>CPU features: detected: Instruction cache invalidation not required for I/D coherence"}
- {"dt": "2025-04-07T09:24:15.887697", "lvl": "target", "msg": "<6>CPU features: detected: Data cache clean to the PoU not required for I/D coherence"}
- {"dt": "2025-04-07T09:24:15.887953", "lvl": "target", "msg": "<6>CPU features: detected: Common not Private translations"}
- {"dt": "2025-04-07T09:24:15.888244", "lvl": "target", "msg": "<6>CPU features: detected: CRC32 instructions"}
- {"dt": "2025-04-07T09:24:15.888294", "lvl": "target", "msg": "<6>CPU features: detected: Data independent timing control (DIT)"}
- {"dt": "2025-04-07T09:24:15.888402", "lvl": "target", "msg": "<6>CPU features: detected: E0PD"}
- {"dt": "2025-04-07T09:24:15.888683", "lvl": "target", "msg": "<6>CPU features: detected: Enhanced Counter Virtualization"}
- {"dt": "2025-04-07T09:24:15.888955", "lvl": "target", "msg": "<6>CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)"}
- {"dt": "2025-04-07T09:24:15.889004", "lvl": "target", "msg": "<6>CPU features: detected: Enhanced Privileged Access Never"}
- {"dt": "2025-04-07T09:24:15.889268", "lvl": "target", "msg": "<6>CPU features: detected: Generic authentication (IMP DEF algorithm)"}
- {"dt": "2025-04-07T09:24:15.889433", "lvl": "target", "msg": "<6>CPU features: detected: RCpc load-acquire (LDAPR)"}
- {"dt": "2025-04-07T09:24:15.889718", "lvl": "target", "msg": "<6>CPU features: detected: LSE atomic instructions"}
- {"dt": "2025-04-07T09:24:15.889775", "lvl": "target", "msg": "<6>CPU features: detected: Privileged Access Never"}
- {"dt": "2025-04-07T09:24:15.889981", "lvl": "target", "msg": "<6>CPU features: detected: RAS Extension Support"}
- {"dt": "2025-04-07T09:24:15.890282", "lvl": "target", "msg": "<6>CPU features: detected: Random Number Generator"}
- {"dt": "2025-04-07T09:24:15.890549", "lvl": "target", "msg": "<6>CPU features: detected: Speculation barrier (SB)"}
- {"dt": "2025-04-07T09:24:15.890706", "lvl": "target", "msg": "<6>CPU features: detected: Stage-2 Force Write-Back"}
- {"dt": "2025-04-07T09:24:15.890961", "lvl": "target", "msg": "<6>CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality"}
- {"dt": "2025-04-07T09:24:15.891144", "lvl": "target", "msg": "<6>CPU features: detected: TLB range maintenance instructions"}
- {"dt": "2025-04-07T09:24:15.891267", "lvl": "target", "msg": "<6>CPU features: detected: WFx with timeout"}
- {"dt": "2025-04-07T09:24:15.891425", "lvl": "target", "msg": "<6>CPU features: detected: Scalable Matrix Extension"}
- {"dt": "2025-04-07T09:24:15.891502", "lvl": "target", "msg": "<6>CPU features: detected: FA64"}
- {"dt": "2025-04-07T09:24:15.891675", "lvl": "target", "msg": "<6>CPU features: detected: Speculative Store Bypassing Safe (SSBS)"}
- {"dt": "2025-04-07T09:24:15.891824", "lvl": "target", "msg": "<6>CPU features: detected: Scalable Vector Extension"}
- {"dt": "2025-04-07T09:24:15.902021", "lvl": "target", "msg": "<6>SVE: maximum available vector length 256 bytes per vector"}
- {"dt": "2025-04-07T09:24:15.902332", "lvl": "target", "msg": "<6>SVE: default vector length 64 bytes per vector"}
- {"dt": "2025-04-07T09:24:15.903763", "lvl": "target", "msg": "<6>SME: minimum available vector length 16 bytes per vector"}
- {"dt": "2025-04-07T09:24:15.903975", "lvl": "target", "msg": "<6>SME: maximum available vector length 256 bytes per vector"}
- {"dt": "2025-04-07T09:24:15.904156", "lvl": "target", "msg": "<6>SME: default vector length 32 bytes per vector"}
- {"dt": "2025-04-07T09:24:15.904453", "lvl": "target", "msg": "<6>CPU: All CPU(s) started at EL2"}
- {"dt": "2025-04-07T09:24:15.904615", "lvl": "target", "msg": "<6>alternatives: applying system-wide alternatives"}
- {"dt": "2025-04-07T09:24:15.940255", "lvl": "target", "msg": "<6>devtmpfs: initialized"}
- {"dt": "2025-04-07T09:24:15.951042", "lvl": "target", "msg": "<6>clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2025-04-07T09:24:15.951608", "lvl": "target", "msg": "<6>futex hash table entries: 512 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-04-07T09:24:15.953453", "lvl": "target", "msg": "<6>DMI not present or invalid."}
- {"dt": "2025-04-07T09:24:15.956986", "lvl": "target", "msg": "<6>NET: Registered PF_NETLINK/PF_ROUTE protocol family"}
- {"dt": "2025-04-07T09:24:15.960689", "lvl": "target", "msg": "<6>DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2025-04-07T09:24:15.961305", "lvl": "target", "msg": "<6>DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2025-04-07T09:24:15.961558", "lvl": "target", "msg": "<6>DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2025-04-07T09:24:15.965999", "lvl": "target", "msg": "<6>thermal_sys: Registered thermal governor 'step_wise'"}
- {"dt": "2025-04-07T09:24:15.966066", "lvl": "target", "msg": "<6>cpuidle: using governor menu"}
- {"dt": "2025-04-07T09:24:15.967287", "lvl": "target", "msg": "<6>hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2025-04-07T09:24:15.967886", "lvl": "target", "msg": "<6>ASID allocator initialised with 65536 entries"}
- {"dt": "2025-04-07T09:24:15.967944", "lvl": "target", "msg": "<6>Serial: AMBA PL011 UART driver"}
- {"dt": "2025-04-07T09:24:15.991725", "lvl": "target", "msg": "<6>9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 15, base_baud = 0) is a PL011 rev1"}
- {"dt": "2025-04-07T09:24:15.993063", "lvl": "target", "msg": "<6>printk: console [ttyAMA0] enabled"}
- {"dt": "2025-04-07T09:24:15.993107", "lvl": "target", "msg": "<6>printk: console [ttyAMA0] enabled"}
- {"dt": "2025-04-07T09:24:15.993210", "lvl": "target", "msg": "<6>printk: bootconsole [pl11] disabled"}
- {"dt": "2025-04-07T09:24:15.993292", "lvl": "target", "msg": "<6>printk: bootconsole [pl11] disabled"}
- {"dt": "2025-04-07T09:24:16.005828", "lvl": "target", "msg": "<6>ACPI: Interpreter disabled."}
- {"dt": "2025-04-07T09:24:16.006483", "lvl": "target", "msg": "<6>iommu: Default domain type: Translated "}
- {"dt": "2025-04-07T09:24:16.006561", "lvl": "target", "msg": "<6>iommu: DMA domain TLB invalidation policy: strict mode "}
- {"dt": "2025-04-07T09:24:16.007716", "lvl": "target", "msg": "<5>SCSI subsystem initialized"}
- {"dt": "2025-04-07T09:24:16.008411", "lvl": "target", "msg": "<7>libata version 3.00 loaded."}
- {"dt": "2025-04-07T09:24:16.008714", "lvl": "target", "msg": "<6>pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2025-04-07T09:24:16.008897", "lvl": "target", "msg": "<6>pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2025-04-07T09:24:16.009045", "lvl": "target", "msg": "<6>PTP clock support registered"}
- {"dt": "2025-04-07T09:24:16.016213", "lvl": "target", "msg": "<6>vgaarb: loaded"}
- {"dt": "2025-04-07T09:24:16.017124", "lvl": "target", "msg": "<6>clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2025-04-07T09:24:16.018377", "lvl": "target", "msg": "<6>pnp: PnP ACPI: disabled"}
- {"dt": "2025-04-07T09:24:16.039406", "lvl": "target", "msg": "<6>NET: Registered PF_INET protocol family"}
- {"dt": "2025-04-07T09:24:16.040405", "lvl": "target", "msg": "<6>IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2025-04-07T09:24:16.045674", "lvl": "target", "msg": "<6>tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)"}
- {"dt": "2025-04-07T09:24:16.045877", "lvl": "target", "msg": "<6>Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-04-07T09:24:16.046120", "lvl": "target", "msg": "<6>TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)"}
- {"dt": "2025-04-07T09:24:16.046633", "lvl": "target", "msg": "<6>TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2025-04-07T09:24:16.047401", "lvl": "target", "msg": "<6>TCP: Hash tables configured (established 32768 bind 32768)"}
- {"dt": "2025-04-07T09:24:16.048318", "lvl": "target", "msg": "<6>UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-07T09:24:16.048813", "lvl": "target", "msg": "<6>UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)"}
- {"dt": "2025-04-07T09:24:16.049637", "lvl": "target", "msg": "<6>NET: Registered PF_UNIX/PF_LOCAL protocol family"}
- {"dt": "2025-04-07T09:24:16.049962", "lvl": "target", "msg": "<6>PCI: CLS 0 bytes, default 64"}
- {"dt": "2025-04-07T09:24:16.057856", "lvl": "target", "msg": "<6>workingset: timestamp_bits=46 max_order=20 bucket_order=0"}
- {"dt": "2025-04-07T09:24:16.059119", "lvl": "target", "msg": "<6>fuse: init (API version 7.38)"}
- {"dt": "2025-04-07T09:24:16.060652", "lvl": "target", "msg": "<6>Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)"}
- {"dt": "2025-04-07T09:24:16.060905", "lvl": "target", "msg": "<6>io scheduler mq-deadline registered"}
- {"dt": "2025-04-07T09:24:16.061001", "lvl": "target", "msg": "<6>io scheduler kyber registered"}
- {"dt": "2025-04-07T09:24:16.063091", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:"}
- {"dt": "2025-04-07T09:24:16.063787", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000"}
- {"dt": "2025-04-07T09:24:16.064222", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000"}
- {"dt": "2025-04-07T09:24:16.064479", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000"}
- {"dt": "2025-04-07T09:24:16.065104", "lvl": "target", "msg": "<4>pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits"}
- {"dt": "2025-04-07T09:24:16.065797", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]"}
- {"dt": "2025-04-07T09:24:16.066909", "lvl": "target", "msg": "<6>pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00"}
- {"dt": "2025-04-07T09:24:16.067176", "lvl": "target", "msg": "<6>pci_bus 0000:00: root bus resource [bus 00-ff]"}
- {"dt": "2025-04-07T09:24:16.067311", "lvl": "target", "msg": "<6>pci_bus 0000:00: root bus resource [io  0x0000-0xffff]"}
- {"dt": "2025-04-07T09:24:16.067472", "lvl": "target", "msg": "<6>pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"}
- {"dt": "2025-04-07T09:24:16.067657", "lvl": "target", "msg": "<6>pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]"}
- {"dt": "2025-04-07T09:24:16.070161", "lvl": "target", "msg": "<6>pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000"}
- {"dt": "2025-04-07T09:24:16.072596", "lvl": "target", "msg": "<6>pci 0000:00:01.0: [1af4:1001] type 00 class 0x010000"}
- {"dt": "2025-04-07T09:24:16.072893", "lvl": "target", "msg": "<6>pci 0000:00:01.0: reg 0x10: [io  0x0000-0x007f]"}
- {"dt": "2025-04-07T09:24:16.072987", "lvl": "target", "msg": "<6>pci 0000:00:01.0: reg 0x14: [mem 0x00000000-0x00000fff]"}
- {"dt": "2025-04-07T09:24:16.073272", "lvl": "target", "msg": "<6>pci 0000:00:01.0: reg 0x20: [mem 0x00000000-0x00003fff 64bit pref]"}
- {"dt": "2025-04-07T09:24:16.075517", "lvl": "target", "msg": "<6>pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref]"}
- {"dt": "2025-04-07T09:24:16.075809", "lvl": "target", "msg": "<6>pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff]"}
- {"dt": "2025-04-07T09:24:16.075938", "lvl": "target", "msg": "<6>pci 0000:00:01.0: BAR 0: assigned [io  0x1000-0x107f]"}
- {"dt": "2025-04-07T09:24:16.082410", "lvl": "target", "msg": "<6>virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)"}
- {"dt": "2025-04-07T09:24:16.184606", "lvl": "target", "msg": "<6>virtio_blk virtio0: 2/0/0 default/read/poll queues"}
- {"dt": "2025-04-07T09:24:16.194786", "lvl": "target", "msg": "<5>virtio_blk virtio0: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)"}
- {"dt": "2025-04-07T09:24:16.210051", "lvl": "target", "msg": "<6>rtc-pl031 9010000.pl031: registered as rtc0"}
- {"dt": "2025-04-07T09:24:16.210823", "lvl": "target", "msg": "<6>rtc-pl031 9010000.pl031: setting system clock to 2025-04-07T09:24:16 UTC (1744017856)"}
- {"dt": "2025-04-07T09:24:16.216512", "lvl": "target", "msg": "<6>hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available"}
- {"dt": "2025-04-07T09:24:16.217623", "lvl": "target", "msg": "<6>NET: Registered PF_INET6 protocol family"}
- {"dt": "2025-04-07T09:24:16.222153", "lvl": "target", "msg": "<6>Segment Routing with IPv6"}
- {"dt": "2025-04-07T09:24:16.222232", "lvl": "target", "msg": "<6>In-situ OAM (IOAM) with IPv6"}
- {"dt": "2025-04-07T09:24:16.222579", "lvl": "target", "msg": "<6>sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"}
- {"dt": "2025-04-07T09:24:16.224412", "lvl": "target", "msg": "<6>NET: Registered PF_PACKET protocol family"}
- {"dt": "2025-04-07T09:24:16.224467", "lvl": "target", "msg": "<6>NET: Registered PF_KEY protocol family"}
- {"dt": "2025-04-07T09:24:16.225142", "lvl": "target", "msg": "<6>NET: Registered PF_VSOCK protocol family"}
- {"dt": "2025-04-07T09:24:16.233703", "lvl": "target", "msg": "<6>registered taskstats version 1"}
- {"dt": "2025-04-07T09:24:16.246719", "lvl": "target", "msg": "<6>clk: Disabling unused clocks"}
- {"dt": "2025-04-07T09:24:16.282659", "lvl": "target", "msg": "<6>EXT4-fs (vda): orphan cleanup on readonly fs"}
- {"dt": "2025-04-07T09:24:16.283415", "lvl": "target", "msg": "<6>EXT4-fs (vda): mounted filesystem 2fcbc1ed-dd01-42d4-a9c7-5be91a955875 ro with ordered data mode. Quota mode: disabled."}
- {"dt": "2025-04-07T09:24:16.283731", "lvl": "target", "msg": "<6>VFS: Mounted root (ext4 filesystem) readonly on device 254:0."}
- {"dt": "2025-04-07T09:24:16.285030", "lvl": "target", "msg": "<6>devtmpfs: mounted"}
- {"dt": "2025-04-07T09:24:16.305282", "lvl": "target", "msg": "<6>Freeing unused kernel memory: 1408K"}
- {"dt": "2025-04-07T09:24:16.314245", "lvl": "target", "msg": "<6>Run /sbin/init as init process"}
- {"dt": "2025-04-07T09:24:16.314332", "lvl": "target", "msg": "<7>  with arguments:"}
- {"dt": "2025-04-07T09:24:16.314396", "lvl": "target", "msg": "<7>    /sbin/init"}
- {"dt": "2025-04-07T09:24:16.314457", "lvl": "target", "msg": "<7>    verbose"}
- {"dt": "2025-04-07T09:24:16.314522", "lvl": "target", "msg": "<7>  with environment:"}
- {"dt": "2025-04-07T09:24:16.314580", "lvl": "target", "msg": "<7>    HOME=/"}
- {"dt": "2025-04-07T09:24:16.314628", "lvl": "target", "msg": "<7>    TERM=linux"}
- {"dt": "2025-04-07T09:24:16.408354", "lvl": "target", "msg": "<6>EXT4-fs (vda): re-mounted 2fcbc1ed-dd01-42d4-a9c7-5be91a955875 r/w. Quota mode: disabled."}
- {"dt": "2025-04-07T09:24:16.637262", "lvl": "target", "msg": "Saving 256 bits of creditable seed for next boot"}
- {"dt": "2025-04-07T09:24:16.724972", "lvl": "target", "msg": "Starting syslogd: OK"}
- {"dt": "2025-04-07T09:24:16.761083", "lvl": "target", "msg": "Starting klogd: OK"}
- {"dt": "2025-04-07T09:24:16.830425", "lvl": "target", "msg": "Running sysctl: OK"}
- {"dt": "2025-04-07T09:24:16.984234", "lvl": "target", "msg": "Starting network: OK"}
- {"dt": "2025-04-07T09:24:17.016972", "lvl": "target", "msg": "Starting crond: OK"}
- {"dt": "2025-04-07T09:24:17.132719", "lvl": "target", "msg": "\r"}
- {"dt": "2025-04-07T09:24:17.133703", "lvl": "target", "msg": "Welcome to TuxTest"}
- {"dt": "2025-04-07T09:24:17.133998", "lvl": "debug", "msg": "Matched prompt #11: login:"}
- {"dt": "2025-04-07T09:24:17.134061", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "kernel-messages", "level": "2.2.1", "duration": "1.46", "result": "pass", "extra": {"extra": []}}}
- {"dt": "2025-04-07T09:24:17.134157", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2025-04-07T09:24:17.134207", "lvl": "debug", "msg": "Sending line: 'root'"}
- {"dt": "2025-04-07T09:24:17.234793", "lvl": "input", "msg": "root"}
- {"dt": "2025-04-07T09:24:17.234946", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-07T09:24:17.235021", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:14:58)"}
- {"dt": "2025-04-07T09:24:17.235081", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:29)"}
- {"dt": "2025-04-07T09:24:17.235623", "lvl": "target", "msg": "\rtuxtest login: root"}
- {"dt": "2025-04-07T09:24:17.291304", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-07T09:24:17.291453", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:00:02) [common]"}
- {"dt": "2025-04-07T09:24:17.291532", "lvl": "results", "msg": {"definition": "lava", "namespace": "common", "case": "login-action", "level": "2.2.1", "duration": "1.62", "result": "pass", "extra": {"success": "No kernel warnings or errors detected."}}}
- {"dt": "2025-04-07T09:24:17.291664", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:02) [common]"}
- {"dt": "2025-04-07T09:24:17.291723", "lvl": "debug", "msg": "start: 2.3 expect-shell-connection (timeout 00:14:58) [common]"}
- {"dt": "2025-04-07T09:24:17.291770", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"}
- {"dt": "2025-04-07T09:24:17.291810", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@(.*):[/~]#']"}
- {"dt": "2025-04-07T09:24:17.291851", "lvl": "debug", "msg": "Sending line: ''"}
- {"dt": "2025-04-07T09:24:17.341999", "lvl": "input", "msg": ""}
- {"dt": "2025-04-07T09:24:17.342154", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:15:00)"}
- {"dt": "2025-04-07T09:24:17.342231", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:07:30)"}
- {"dt": "2025-04-07T09:24:17.343296", "lvl": "target", "msg": "root@tuxtest:~# "}
- {"dt": "2025-04-07T09:24:17.344654", "lvl": "debug", "msg": "end: 2.3 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:17.344763", "lvl": "debug", "msg": "start: 2.4 export-device-env (timeout 00:14:58) [common]"}
- {"dt": "2025-04-07T09:24:17.344863", "lvl": "debug", "msg": "end: 2.4 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:17.344978", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:02) [common]"}
- {"dt": "2025-04-07T09:24:17.345073", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:02:00) [common]"}
- {"dt": "2025-04-07T09:24:17.345162", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:02:00) [common]"}
- {"dt": "2025-04-07T09:24:17.345238", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2025-04-07T09:24:17.345313", "lvl": "debug", "msg": "Sending line: '#'"}
- {"dt": "2025-04-07T09:24:17.445548", "lvl": "input", "msg": "#"}
- {"dt": "2025-04-07T09:24:17.445700", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@(.*):[/~]#'] (timeout 00:02:00)"}
- {"dt": "2025-04-07T09:24:17.446015", "lvl": "target", "msg": "root@tuxtest:~# #"}
- {"dt": "2025-04-07T09:24:17.447413", "lvl": "debug", "msg": "Using /lava-1"}
- {"dt": "2025-04-07T09:24:17.447483", "lvl": "debug", "msg": "Sending line: 'export SHELL=/bin/sh'"}
- {"dt": "2025-04-07T09:24:17.547698", "lvl": "input", "msg": "export SHELL=/bin/sh"}
- {"dt": "2025-04-07T09:24:17.548397", "lvl": "target", "msg": "root@tuxtest:~# export SHELL=/bin/sh"}
- {"dt": "2025-04-07T09:24:17.549663", "lvl": "debug", "msg": "Sending line: '. /lava-1/environment'"}
- {"dt": "2025-04-07T09:24:17.650058", "lvl": "input", "msg": ". /lava-1/environment"}
- {"dt": "2025-04-07T09:24:17.650554", "lvl": "target", "msg": "root@tuxtest:~# . /lava-1/environment"}
- {"dt": "2025-04-07T09:24:17.652666", "lvl": "debug", "msg": "Sending line: '/lava-1/bin/lava-test-runner /lava-1/0'"}
- {"dt": "2025-04-07T09:24:17.752908", "lvl": "input", "msg": "/lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-04-07T09:24:17.753059", "lvl": "info", "msg": "Test shell timeout: 10s (minimum of the action and connection timeout)"}
- {"dt": "2025-04-07T09:24:17.753514", "lvl": "target", "msg": "root@tuxtest:~# /lava-1/bin/lava-test-runner /lava-1/0"}
- {"dt": "2025-04-07T09:24:17.851069", "lvl": "target", "msg": "+ export TESTRUN_ID=0_ltp-smoke"}
- {"dt": "2025-04-07T09:24:17.851227", "lvl": "target", "msg": "+ TESTRUN_ID=0_ltp-smoke"}
- {"dt": "2025-04-07T09:24:17.851526", "lvl": "target", "msg": "+ cd /lava-1/0/tests/0_ltp-smoke"}
- {"dt": "2025-04-07T09:24:17.854839", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2025-04-07T09:24:17.860320", "lvl": "target", "msg": "+ UUID=1_1.1.3.1"}
- {"dt": "2025-04-07T09:24:17.860592", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-04-07T09:24:17.862687", "lvl": "target", "msg": "<8><LAVA_SIGNAL_STARTRUN 0_ltp-smoke 1_1.1.3.1>"}
- {"dt": "2025-04-07T09:24:17.862948", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_ltp-smoke 1_1.1.3.1"}
- {"dt": "2025-04-07T09:24:17.863021", "lvl": "info", "msg": "Starting test lava.0_ltp-smoke (1_1.1.3.1)"}
- {"dt": "2025-04-07T09:24:17.863111", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2025-04-07T09:24:17.863247", "lvl": "target", "msg": "+ cd ./automated/linux/ltp/"}
- {"dt": "2025-04-07T09:24:17.868621", "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/arm64/ltp-smoke -p '' -u '' -t false -n 1 -c 1"}
- {"dt": "2025-04-07T09:24:17.878583", "lvl": "target", "msg": "+ . ../../lib/sh-test-lib"}
- {"dt": "2025-04-07T09:24:17.880120", "lvl": "target", "msg": "++ LANG=C"}
- {"dt": "2025-04-07T09:24:17.880337", "lvl": "target", "msg": "++ export LANG"}
- {"dt": "2025-04-07T09:24:17.891548", "lvl": "target", "msg": "++ export PATH=/lava-1/0/../bin:/bin:/sbin:/usr/bin:/usr/sbin:/sbin:/usr/sbin:/usr/local/sbin"}
- {"dt": "2025-04-07T09:24:17.891966", "lvl": "target", "msg": "++ PATH=/lava-1/0/../bin:/bin:/sbin:/usr/bin:/usr/sbin:/sbin:/usr/sbin:/usr/local/sbin"}
- {"dt": "2025-04-07T09:24:17.896629", "lvl": "target", "msg": "++ kernel_config="}
- {"dt": "2025-04-07T09:24:17.900336", "lvl": "target", "msg": "++ pwd"}
- {"dt": "2025-04-07T09:24:17.902520", "lvl": "target", "msg": "+ OUTPUT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-07T09:24:17.903000", "lvl": "target", "msg": "+ RESULT_FILE=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/result.txt"}
- {"dt": "2025-04-07T09:24:17.906198", "lvl": "target", "msg": "++ readlink -f ./ltp.sh"}
- {"dt": "2025-04-07T09:24:17.913227", "lvl": "target", "msg": "+ SCRIPT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.sh"}
- {"dt": "2025-04-07T09:24:17.915949", "lvl": "target", "msg": "++ dirname /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp.sh"}
- {"dt": "2025-04-07T09:24:17.921565", "lvl": "target", "msg": "+ SCRIPTPATH=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp"}
- {"dt": "2025-04-07T09:24:17.922486", "lvl": "target", "msg": "+ echo 'Script path is: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp'"}
- {"dt": "2025-04-07T09:24:17.922856", "lvl": "target", "msg": "Script path is: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp"}
- {"dt": "2025-04-07T09:24:17.922910", "lvl": "target", "msg": "+ TST_CMDFILES="}
- {"dt": "2025-04-07T09:24:17.923151", "lvl": "target", "msg": "+ SKIPFILE="}
- {"dt": "2025-04-07T09:24:17.923262", "lvl": "target", "msg": "+ SKIPFILE_YAML="}
- {"dt": "2025-04-07T09:24:17.923331", "lvl": "target", "msg": "+ BOARD="}
- {"dt": "2025-04-07T09:24:17.923379", "lvl": "target", "msg": "+ BRANCH="}
- {"dt": "2025-04-07T09:24:17.923429", "lvl": "target", "msg": "+ ENVIRONMENT="}
- {"dt": "2025-04-07T09:24:17.923672", "lvl": "target", "msg": "+ LTP_VERSION=20180926"}
- {"dt": "2025-04-07T09:24:17.923721", "lvl": "target", "msg": "+ TEST_PROGRAM=ltp"}
- {"dt": "2025-04-07T09:24:17.923778", "lvl": "target", "msg": "+ TEST_GIT_URL="}
- {"dt": "2025-04-07T09:24:17.926377", "lvl": "target", "msg": "++ pwd"}
- {"dt": "2025-04-07T09:24:17.928171", "lvl": "target", "msg": "+ TEST_DIR=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/ltp"}
- {"dt": "2025-04-07T09:24:17.928616", "lvl": "target", "msg": "+ BUILD_FROM_TAR=false"}
- {"dt": "2025-04-07T09:24:17.928682", "lvl": "target", "msg": "+ SHARD_NUMBER=1"}
- {"dt": "2025-04-07T09:24:17.928970", "lvl": "target", "msg": "+ SHARD_INDEX=1"}
- {"dt": "2025-04-07T09:24:17.929033", "lvl": "target", "msg": "+ RUNNER="}
- {"dt": "2025-04-07T09:24:17.929082", "lvl": "target", "msg": "+ LTP_TMPDIR=/ltp-tmp"}
- {"dt": "2025-04-07T09:24:17.929152", "lvl": "target", "msg": "+ LTP_INSTALL_PATH=/opt/ltp"}
- {"dt": "2025-04-07T09:24:17.931087", "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-07T09:24:17.932168", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.932253", "lvl": "target", "msg": "+ TST_CMDFILES=smoketest"}
- {"dt": "2025-04-07T09:24:17.940052", "lvl": "target", "msg": "++ echo smoketest"}
- {"dt": "2025-04-07T09:24:17.941621", "lvl": "target", "msg": "++ sed 's,\\/,_,'"}
- {"dt": "2025-04-07T09:24:17.950296", "lvl": "target", "msg": "+ LOG_FILE=smoketest"}
- {"dt": "2025-04-07T09:24:17.950601", "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-07T09:24:17.950671", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.950908", "lvl": "target", "msg": "+ SKIP_INSTALL=true"}
- {"dt": "2025-04-07T09:24:17.951190", "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-07T09:24:17.951234", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.951443", "lvl": "target", "msg": "+ LTP_VERSION="}
- {"dt": "2025-04-07T09:24:17.951698", "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-07T09:24:17.951742", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.951793", "lvl": "target", "msg": "+ export LTP_TIMEOUT_MUL=5"}
- {"dt": "2025-04-07T09:24:17.952030", "lvl": "target", "msg": "+ LTP_TIMEOUT_MUL=5"}
- {"dt": "2025-04-07T09:24:17.952080", "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-07T09:24:17.952289", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.952338", "lvl": "target", "msg": "+ export PASSWD=root"}
- {"dt": "2025-04-07T09:24:17.952558", "lvl": "target", "msg": "+ PASSWD=root"}
- {"dt": "2025-04-07T09:24:17.952608", "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-07T09:24:17.952816", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.953027", "lvl": "target", "msg": "+ export RUNNER="}
- {"dt": "2025-04-07T09:24:17.953068", "lvl": "target", "msg": "+ RUNNER="}
- {"dt": "2025-04-07T09:24:17.953326", "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-07T09:24:17.953368", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.953411", "lvl": "target", "msg": "+ export BOARD="}
- {"dt": "2025-04-07T09:24:17.953456", "lvl": "target", "msg": "+ BOARD="}
- {"dt": "2025-04-07T09:24:17.953663", "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-07T09:24:17.953879", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.953923", "lvl": "target", "msg": "+ export LTP_TMPDIR=/scratch"}
- {"dt": "2025-04-07T09:24:17.953966", "lvl": "target", "msg": "+ LTP_TMPDIR=/scratch"}
- {"dt": "2025-04-07T09:24:17.954173", "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-07T09:24:17.954223", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.954431", "lvl": "target", "msg": "+ export BRANCH="}
- {"dt": "2025-04-07T09:24:17.954473", "lvl": "target", "msg": "+ BRANCH="}
- {"dt": "2025-04-07T09:24:17.954578", "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-07T09:24:17.954790", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.954838", "lvl": "target", "msg": "+ export ENVIRONMENT=production"}
- {"dt": "2025-04-07T09:24:17.955060", "lvl": "target", "msg": "+ ENVIRONMENT=production"}
- {"dt": "2025-04-07T09:24:17.955108", "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-07T09:24:17.955155", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.955361", "lvl": "target", "msg": "+ export LTP_INSTALL_PATH=/opt/ltp/"}
- {"dt": "2025-04-07T09:24:17.955412", "lvl": "target", "msg": "+ LTP_INSTALL_PATH=/opt/ltp/"}
- {"dt": "2025-04-07T09:24:17.955619", "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-07T09:24:17.955670", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.956584", "lvl": "target", "msg": "+ '[' -z /tuxtest/skipfiles/arm64/ltp-smoke ']'"}
- {"dt": "2025-04-07T09:24:17.956976", "lvl": "target", "msg": "+ '[' /tuxtest/skipfiles/arm64/ltp-smoke = yaml ']'"}
- {"dt": "2025-04-07T09:24:17.957612", "lvl": "target", "msg": "+ '[' / == / ']'"}
- {"dt": "2025-04-07T09:24:17.957868", "lvl": "target", "msg": "+ SKIPFILE='-S /tuxtest/skipfiles/arm64/ltp-smoke'"}
- {"dt": "2025-04-07T09:24:17.957917", "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-07T09:24:17.958125", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.958702", "lvl": "target", "msg": "+ [[ '' != '' ]]"}
- {"dt": "2025-04-07T09:24:17.959006", "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-07T09:24:17.959049", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.959094", "lvl": "target", "msg": "+ [[ '' != '' ]]"}
- {"dt": "2025-04-07T09:24:17.959376", "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-07T09:24:17.959417", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.959462", "lvl": "target", "msg": "+ BUILD_FROM_TAR=false"}
- {"dt": "2025-04-07T09:24:17.959763", "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-07T09:24:17.959806", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.959849", "lvl": "target", "msg": "+ SHARD_NUMBER=1"}
- {"dt": "2025-04-07T09:24:17.960131", "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-07T09:24:17.960174", "lvl": "target", "msg": "+ case \"$arg\" in"}
- {"dt": "2025-04-07T09:24:17.960217", "lvl": "target", "msg": "+ SHARD_INDEX=1"}
- {"dt": "2025-04-07T09:24:17.960502", "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-07T09:24:17.961108", "lvl": "target", "msg": "+ TEST_TARFILE=https://github.com/linux-test-project/ltp/releases/download//ltp-full-.tar.xz"}
- {"dt": "2025-04-07T09:24:17.961499", "lvl": "target", "msg": "+ '[' -n '' ']'"}
- {"dt": "2025-04-07T09:24:17.964189", "lvl": "target", "msg": "+ check_root"}
- {"dt": "2025-04-07T09:24:17.966981", "lvl": "target", "msg": "++ id -ru"}
- {"dt": "2025-04-07T09:24:17.972329", "lvl": "target", "msg": "+ '[' 0 -eq 0 ']'"}
- {"dt": "2025-04-07T09:24:17.972405", "lvl": "target", "msg": "+ return 0"}
- {"dt": "2025-04-07T09:24:17.972980", "lvl": "target", "msg": "+ create_out_dir /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-07T09:24:17.973433", "lvl": "target", "msg": "+ '[' -z /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-07T09:24:17.973531", "lvl": "target", "msg": "+ local OUTPUT=/lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-07T09:24:17.974338", "lvl": "target", "msg": "+ '[' -d /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-07T09:24:17.974748", "lvl": "target", "msg": "+ mkdir -p /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-07T09:24:17.981781", "lvl": "target", "msg": "+ '[' -d /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output ']'"}
- {"dt": "2025-04-07T09:24:17.982469", "lvl": "target", "msg": "+ info_msg 'About to run ltp test...'"}
- {"dt": "2025-04-07T09:24:17.982574", "lvl": "target", "msg": "+ local 'msg=About to run ltp test...'"}
- {"dt": "2025-04-07T09:24:17.982814", "lvl": "target", "msg": "+ '[' -z 'About to run ltp test...' ']'"}
- {"dt": "2025-04-07T09:24:17.983102", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'About to run ltp test...'"}
- {"dt": "2025-04-07T09:24:17.983320", "lvl": "target", "msg": "INFO: About to run ltp test..."}
- {"dt": "2025-04-07T09:24:17.983770", "lvl": "target", "msg": "+ info_msg 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-07T09:24:17.983888", "lvl": "target", "msg": "+ local 'msg=Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-07T09:24:17.984321", "lvl": "target", "msg": "+ '[' -z 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output' ']'"}
- {"dt": "2025-04-07T09:24:17.984672", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output'"}
- {"dt": "2025-04-07T09:24:17.984916", "lvl": "target", "msg": "INFO: Output directory: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output"}
- {"dt": "2025-04-07T09:24:17.985131", "lvl": "target", "msg": "+ '[' true = true ']'"}
- {"dt": "2025-04-07T09:24:17.985346", "lvl": "target", "msg": "+ info_msg 'ltp installation skipped altogether'"}
- {"dt": "2025-04-07T09:24:17.985561", "lvl": "target", "msg": "+ local 'msg=ltp installation skipped altogether'"}
- {"dt": "2025-04-07T09:24:17.985875", "lvl": "target", "msg": "+ '[' -z 'ltp installation skipped altogether' ']'"}
- {"dt": "2025-04-07T09:24:17.986100", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'ltp installation skipped altogether'"}
- {"dt": "2025-04-07T09:24:17.986142", "lvl": "target", "msg": "INFO: ltp installation skipped altogether"}
- {"dt": "2025-04-07T09:24:17.986562", "lvl": "target", "msg": "+ '[' '!' -d /opt/ltp/ ']'"}
- {"dt": "2025-04-07T09:24:17.987304", "lvl": "target", "msg": "+ info_msg 'Running prep_system'"}
- {"dt": "2025-04-07T09:24:17.987539", "lvl": "target", "msg": "+ local 'msg=Running prep_system'"}
- {"dt": "2025-04-07T09:24:17.987589", "lvl": "target", "msg": "+ '[' -z 'Running prep_system' ']'"}
- {"dt": "2025-04-07T09:24:17.987802", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Running prep_system'"}
- {"dt": "2025-04-07T09:24:17.987851", "lvl": "target", "msg": "INFO: Running prep_system"}
- {"dt": "2025-04-07T09:24:17.988061", "lvl": "target", "msg": "+ prep_system"}
- {"dt": "2025-04-07T09:24:17.988124", "lvl": "target", "msg": "+ systemctl is-active systemd-timesyncd"}
- {"dt": "2025-04-07T09:24:17.993387", "lvl": "target", "msg": "+ '[' -f /proc/sys/kernel/unprivileged_userns_clone ']'"}
- {"dt": "2025-04-07T09:24:17.993969", "lvl": "target", "msg": "+ info_msg 'Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-07T09:24:17.994238", "lvl": "target", "msg": "+ local 'msg=Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-07T09:24:17.994662", "lvl": "target", "msg": "+ '[' -z 'Kernel has no support of unprivileged_userns_clone' ']'"}
- {"dt": "2025-04-07T09:24:17.994738", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Kernel has no support of unprivileged_userns_clone'"}
- {"dt": "2025-04-07T09:24:17.994885", "lvl": "target", "msg": "INFO: Kernel has no support of unprivileged_userns_clone"}
- {"dt": "2025-04-07T09:24:17.995313", "lvl": "target", "msg": "+ info_msg 'Running run_ltp'"}
- {"dt": "2025-04-07T09:24:17.995371", "lvl": "target", "msg": "+ local 'msg=Running run_ltp'"}
- {"dt": "2025-04-07T09:24:17.995460", "lvl": "target", "msg": "+ '[' -z 'Running run_ltp' ']'"}
- {"dt": "2025-04-07T09:24:17.995674", "lvl": "target", "msg": "+ printf 'INFO: %s\\n' 'Running run_ltp'"}
- {"dt": "2025-04-07T09:24:17.995758", "lvl": "target", "msg": "INFO: Running run_ltp"}
- {"dt": "2025-04-07T09:24:17.995980", "lvl": "target", "msg": "+ run_ltp"}
- {"dt": "2025-04-07T09:24:17.996211", "lvl": "target", "msg": "+ cd /opt/ltp/"}
- {"dt": "2025-04-07T09:24:17.996607", "lvl": "target", "msg": "+ mkdir -m 777 -p /scratch"}
- {"dt": "2025-04-07T09:24:18.005193", "lvl": "target", "msg": "+ for file in ${TST_CMDFILES//,/ }"}
- {"dt": "2025-04-07T09:24:18.005290", "lvl": "target", "msg": "+ cat runtest/smoketest"}
- {"dt": "2025-04-07T09:24:18.015629", "lvl": "target", "msg": "+ sed -i 's/#.*$//;/^$/d' alltests"}
- {"dt": "2025-04-07T09:24:18.030406", "lvl": "target", "msg": "+ split --verbose --numeric-suffixes=1 -n l/1/1 alltests"}
- {"dt": "2025-04-07T09:24:18.039842", "lvl": "target", "msg": "+ echo '============== Tests to run ==============='"}
- {"dt": "2025-04-07T09:24:18.040165", "lvl": "target", "msg": "============== Tests to run ==============="}
- {"dt": "2025-04-07T09:24:18.040243", "lvl": "target", "msg": "+ cat runtest/shardfile"}
- {"dt": "2025-04-07T09:24:18.045245", "lvl": "target", "msg": "access01 access01"}
- {"dt": "2025-04-07T09:24:18.045315", "lvl": "target", "msg": "chdir01 chdir01"}
- {"dt": "2025-04-07T09:24:18.045372", "lvl": "target", "msg": "fork01 fork01"}
- {"dt": "2025-04-07T09:24:18.045428", "lvl": "target", "msg": "time01 time01"}
- {"dt": "2025-04-07T09:24:18.045496", "lvl": "target", "msg": "wait02 wait02"}
- {"dt": "2025-04-07T09:24:18.045549", "lvl": "target", "msg": "write01 write01"}
- {"dt": "2025-04-07T09:24:18.045599", "lvl": "target", "msg": "symlink01 symlink01"}
- {"dt": "2025-04-07T09:24:18.045648", "lvl": "target", "msg": "stat04 stat04"}
- {"dt": "2025-04-07T09:24:18.045695", "lvl": "target", "msg": "splice02 splice02 -s 20"}
- {"dt": "2025-04-07T09:24:18.045743", "lvl": "target", "msg": "df01_sh df01.sh"}
- {"dt": "2025-04-07T09:24:18.045813", "lvl": "target", "msg": "shell_test01 echo \"SUCCESS\" | shell_pipe01.sh"}
- {"dt": "2025-04-07T09:24:18.045867", "lvl": "target", "msg": "ping602 ping02.sh -6"}
- {"dt": "2025-04-07T09:24:18.045917", "lvl": "target", "msg": "macsec02 macsec02.sh"}
- {"dt": "2025-04-07T09:24:18.046899", "lvl": "target", "msg": "+ echo '===========End Tests to run ==============='"}
- {"dt": "2025-04-07T09:24:18.047000", "lvl": "target", "msg": "===========End Tests to run ==============="}
- {"dt": "2025-04-07T09:24:18.047217", "lvl": "target", "msg": "+ '[' -n '' ']'"}
- {"dt": "2025-04-07T09:24:18.048529", "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/arm64/ltp-smoke' 'tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-07T09:24:18.049118", "lvl": "target", "msg": "+ '[' 2 -ne 2 ']'"}
- {"dt": "2025-04-07T09:24:18.050133", "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/arm64/ltp-smoke'"}
- {"dt": "2025-04-07T09:24:18.050469", "lvl": "target", "msg": "+ local 'cmd2=tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-07T09:24:18.050515", "lvl": "target", "msg": "+ exec"}
- {"dt": "2025-04-07T09:24:18.050728", "lvl": "target", "msg": "+ local ret_val"}
- {"dt": "2025-04-07T09:24:18.057459", "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/arm64/ltp-smoke'"}
- {"dt": "2025-04-07T09:24:18.057742", "lvl": "target", "msg": "++ eval 'tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out'"}
- {"dt": "2025-04-07T09:24:18.058931", "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/arm64/ltp-smoke"}
- {"dt": "2025-04-07T09:24:18.059182", "lvl": "target", "msg": "+++ tee /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.out"}
- {"dt": "2025-04-07T09:24:18.094736", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-07T09:24:18.094848", "lvl": "target", "msg": "INFO: runltp script is deprecated, try kirk"}
- {"dt": "2025-04-07T09:24:18.095103", "lvl": "target", "msg": "https://github.com/linux-test-project/kirk"}
- {"dt": "2025-04-07T09:24:18.095219", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-07T09:24:18.140409", "lvl": "target", "msg": "INFO: creating /opt/ltp/output directory"}
- {"dt": "2025-04-07T09:24:18.265039", "lvl": "target", "msg": "Checking for required user/group ids"}
- {"dt": "2025-04-07T09:24:18.265569", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:18.344217", "lvl": "target", "msg": "'root' user id and group found."}
- {"dt": "2025-04-07T09:24:18.345242", "lvl": "target", "msg": "'nobody' user id and group found."}
- {"dt": "2025-04-07T09:24:18.345643", "lvl": "target", "msg": "'bin' user id and group found."}
- {"dt": "2025-04-07T09:24:18.346104", "lvl": "target", "msg": "'daemon' user id and group found."}
- {"dt": "2025-04-07T09:24:18.346201", "lvl": "target", "msg": "Users group found."}
- {"dt": "2025-04-07T09:24:18.346697", "lvl": "target", "msg": "Sys group found."}
- {"dt": "2025-04-07T09:24:18.435127", "lvl": "target", "msg": "Required users/groups exist."}
- {"dt": "2025-04-07T09:24:18.438517", "lvl": "target", "msg": "no big block device was specified on commandline."}
- {"dt": "2025-04-07T09:24:18.438863", "lvl": "target", "msg": "Tests which require a big block device are disabled."}
- {"dt": "2025-04-07T09:24:18.438947", "lvl": "target", "msg": "You can specify it with option -z"}
- {"dt": "2025-04-07T09:24:18.446118", "lvl": "target", "msg": "INFO: Test start time: Mon Apr  7 09:24:18 UTC 2025"}
- {"dt": "2025-04-07T09:24:18.447956", "lvl": "target", "msg": "COMMAND:    /opt/ltp/bin/ltp-pan -q  -e -S   -a 117     -n 117 -p -f /scratch/ltp-ZfhPvJ9DWR/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-07T09:24:18.457613", "lvl": "target", "msg": "LOG File: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log"}
- {"dt": "2025-04-07T09:24:18.467741", "lvl": "target", "msg": "FAILED COMMAND File: /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.failed"}
- {"dt": "2025-04-07T09:24:18.475860", "lvl": "target", "msg": "TCONF COMMAND File: /opt/ltp/output/LTP_RUN_ON-LTP_smoketest.log.tconf"}
- {"dt": "2025-04-07T09:24:18.477155", "lvl": "target", "msg": "Running tests......."}
- {"dt": "2025-04-07T09:24:18.517551", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-ZfhPvJ9DWR/LTP_accg7tCKa as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-07T09:24:18.519061", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-07T09:24:18.519332", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Mon Apr  7 11:12:06 CEST 2025 aarch64"}
- {"dt": "2025-04-07T09:24:18.519832", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-07T09:24:18.551876", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 35s"}
- {"dt": "2025-04-07T09:24:18.563806", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.565921", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.567605", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.569369", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, X_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.570585", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.572326", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.573323", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.575084", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.576006", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.577778", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.578796", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.580481", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.581531", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK|X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.583294", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, W_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.584352", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK|X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.586002", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_rwx, R_OK|W_OK|X_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.586978", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.588662", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.589685", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.591393", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.592326", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.594183", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.595319", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.597156", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.599485", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.601784", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.602820", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.604672", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.607324", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.609605", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.610618", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.612535", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.614048", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.615811", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.618112", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.619292", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.621268", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_r, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.622268", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.624154", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.625481", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.627385", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.629781", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.630972", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK|X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.632915", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_w, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.635525", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.638995", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.641847", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.644530", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessfile_x, R_OK|W_OK|X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.645571", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.646198", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_r, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.646786", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.647332", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_w, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.647901", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.648444", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.649099", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessfile_x, R_OK|W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.649675", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.650264", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.650832", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.651406", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.651966", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.652519", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.653196", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.653691", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.654321", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.654969", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_r/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.655624", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.656258", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.657226", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.657723", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.658449", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.659155", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.660139", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.660441", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.660671", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.661064", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_w/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.661284", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.662090", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.663190", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.664532", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.665752", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.665993", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.667176", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.668254", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.668495", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.669872", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.671100", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.672450", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.673713", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.674026", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.674342", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.675316", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_x/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.677383", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.677684", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.677990", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.678262", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.678333", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.678583", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.678850", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.679290", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.679359", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.679630", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rw/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.679870", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.680118", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.680940", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.682039", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.683592", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.683938", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.685081", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.686372", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.686700", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.687813", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.688901", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.690452", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.692084", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.692146", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.692549", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.693776", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_rx/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.695075", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.696142", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.697498", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.698546", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, R_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.699614", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_r, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.700049", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.701207", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.702263", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.702677", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.703664", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_w, W_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.704855", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, F_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.705967", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, F_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.707667", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, R_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.708023", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, W_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.708096", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, X_OK) as root passed"}
- {"dt": "2025-04-07T09:24:18.709355", "lvl": "target", "msg": "access01.c:245: TPASS: access(accessdir_wx/accessfile_x, X_OK) as nobody passed"}
- {"dt": "2025-04-07T09:24:18.711631", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.714637", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.717350", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.719010", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.720321", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.722524", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.725409", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.728241", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.730042", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.731267", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.734237", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.737323", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.740246", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.743410", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_r/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.746642", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.749622", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.752607", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.754316", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.755572", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.757735", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.760616", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.763189", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.764868", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.766186", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.770215", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.773294", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.775675", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.778392", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_w/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.780622", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.781870", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.783509", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.785977", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.787135", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.788773", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.791042", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.793580", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_x/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.795875", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.798284", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.800510", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.801812", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.803540", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.805953", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.808120", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.810617", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.811733", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.813539", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.815847", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, F_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.818302", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.820591", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.823133", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rw/accessfile_x, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.825611", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.826809", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.828609", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.831211", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.832375", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.834251", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.836589", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.839183", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_rx/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.841731", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.842658", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.844441", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_r, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.846948", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.848089", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, X_OK) as root : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.849902", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_w, X_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.852148", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_x, R_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.855044", "lvl": "target", "msg": "access01.c:242: TPASS: access(accessdir_wx/accessfile_x, W_OK) as nobody : EACCES (13)"}
- {"dt": "2025-04-07T09:24:18.861001", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:18.861268", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:18.861343", "lvl": "target", "msg": "passed   199"}
- {"dt": "2025-04-07T09:24:18.861441", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:18.861547", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-07T09:24:18.861639", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-07T09:24:18.861723", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:18.891859", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-ZfhPvJ9DWR/LTP_forNnV9bP as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-07T09:24:18.892985", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-07T09:24:18.893527", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Mon Apr  7 11:12:06 CEST 2025 aarch64"}
- {"dt": "2025-04-07T09:24:18.894173", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-07T09:24:18.919837", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-07T09:24:18.927531", "lvl": "target", "msg": "fork01.c:47: TPASS: correct child status returned 42"}
- {"dt": "2025-04-07T09:24:18.928049", "lvl": "target", "msg": "fork01.c:50: TPASS: child_pid == pid (279)"}
- {"dt": "2025-04-07T09:24:18.931580", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:18.931834", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:18.931898", "lvl": "target", "msg": "passed   2"}
- {"dt": "2025-04-07T09:24:18.931956", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:18.932016", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-07T09:24:18.932079", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-07T09:24:18.932136", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:18.944067", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-07T09:24:18.944524", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Mon Apr  7 11:12:06 CEST 2025 aarch64"}
- {"dt": "2025-04-07T09:24:18.945465", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-07T09:24:18.970062", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-07T09:24:18.974382", "lvl": "target", "msg": "time01.c:36: TPASS: time() returned value 1744017859"}
- {"dt": "2025-04-07T09:24:18.974876", "lvl": "target", "msg": "time01.c:38: TPASS: time() returned value 1744017859, stored value 1744017859 are same"}
- {"dt": "2025-04-07T09:24:18.976750", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:18.976986", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:18.977244", "lvl": "target", "msg": "passed   2"}
- {"dt": "2025-04-07T09:24:18.977501", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:18.977746", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-07T09:24:18.978009", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-07T09:24:18.978225", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:18.991091", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-07T09:24:18.991693", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Mon Apr  7 11:12:06 CEST 2025 aarch64"}
- {"dt": "2025-04-07T09:24:18.992363", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-07T09:24:19.018090", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-07T09:24:19.023532", "lvl": "target", "msg": "wait02.c:41: TPASS: wait() succeeded"}
- {"dt": "2025-04-07T09:24:19.026099", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:19.026351", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:19.026416", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-07T09:24:19.026479", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:19.026542", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-07T09:24:19.026610", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-07T09:24:19.026679", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:19.038469", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-ZfhPvJ9DWR/LTP_wricHv70W as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-07T09:24:19.039537", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-07T09:24:19.040081", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Mon Apr  7 11:12:06 CEST 2025 aarch64"}
- {"dt": "2025-04-07T09:24:19.040926", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-07T09:24:19.065817", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-07T09:24:19.534745", "lvl": "target", "msg": "write01.c:40: TPASS: write() passed"}
- {"dt": "2025-04-07T09:24:19.536343", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:19.536587", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:19.536819", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-07T09:24:19.537182", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:19.537495", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-07T09:24:19.537809", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-07T09:24:19.537942", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:19.604426", "lvl": "target", "msg": "symlink01    0  TINFO  :  Using /scratch/ltp-ZfhPvJ9DWR/LTP_symEh4oge as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-07T09:24:19.604558", "lvl": "target", "msg": "symlink01    1  TPASS  :  Creation of symbolic link file to no object file is ok"}
- {"dt": "2025-04-07T09:24:19.604893", "lvl": "target", "msg": "symlink01    2  TPASS  :  Creation of symbolic link file to no object file is ok"}
- {"dt": "2025-04-07T09:24:19.605208", "lvl": "target", "msg": "symlink01    3  TPASS  :  Creation of symbolic link file and object file via symbolic link is ok"}
- {"dt": "2025-04-07T09:24:19.605309", "lvl": "target", "msg": "symlink01    4  TPASS  :  Creating an existing symbolic link file error is caught"}
- {"dt": "2025-04-07T09:24:19.605623", "lvl": "target", "msg": "symlink01    5  TPASS  :  Creating a symbolic link which exceeds maximum pathname error is caught"}
- {"dt": "2025-04-07T09:24:19.618230", "lvl": "target", "msg": "tst_buffers.c:57: TINFO: Test is using guarded buffers"}
- {"dt": "2025-04-07T09:24:19.620588", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-ZfhPvJ9DWR/LTP_staE0YsXy as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-07T09:24:19.626292", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 0"}
- {"dt": "2025-04-07T09:24:19.626655", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 1"}
- {"dt": "2025-04-07T09:24:19.627125", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 2"}
- {"dt": "2025-04-07T09:24:19.627461", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 3"}
- {"dt": "2025-04-07T09:24:19.627952", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 4"}
- {"dt": "2025-04-07T09:24:19.628239", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 5"}
- {"dt": "2025-04-07T09:24:19.628869", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 6"}
- {"dt": "2025-04-07T09:24:19.629324", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 7"}
- {"dt": "2025-04-07T09:24:19.629568", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 8"}
- {"dt": "2025-04-07T09:24:19.630013", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 9"}
- {"dt": "2025-04-07T09:24:19.630367", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 10"}
- {"dt": "2025-04-07T09:24:19.630807", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 11"}
- {"dt": "2025-04-07T09:24:19.631247", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 12"}
- {"dt": "2025-04-07T09:24:19.631741", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 13"}
- {"dt": "2025-04-07T09:24:19.632128", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 14"}
- {"dt": "2025-04-07T09:24:19.632514", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 15"}
- {"dt": "2025-04-07T09:24:19.632911", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 16"}
- {"dt": "2025-04-07T09:24:19.633328", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 17"}
- {"dt": "2025-04-07T09:24:19.633718", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 18"}
- {"dt": "2025-04-07T09:24:19.634188", "lvl": "target", "msg": "tst_device.c:124: TINFO: non-existant loopback device 19"}
- {"dt": "2025-04-07T09:24:19.644190", "lvl": "target", "msg": "tst_device.c:154: TINFO: No free devices found"}
- {"dt": "2025-04-07T09:24:19.644631", "lvl": "target", "msg": "tst_device.c:367: TBROK: Failed to acquire device"}
- {"dt": "2025-04-07T09:24:19.645240", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:19.645468", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:19.645531", "lvl": "target", "msg": "passed   0"}
- {"dt": "2025-04-07T09:24:19.645789", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:19.646019", "lvl": "target", "msg": "broken   1"}
- {"dt": "2025-04-07T09:24:19.646415", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-07T09:24:19.646644", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:19.662178", "lvl": "target", "msg": "tst_tmpdir.c:316: TINFO: Using /scratch/ltp-ZfhPvJ9DWR/LTP_splqTh5ms as tmpdir (ext2/ext3/ext4 filesystem)"}
- {"dt": "2025-04-07T09:24:19.663381", "lvl": "target", "msg": "tst_test.c:1900: TINFO: LTP version: 20250130"}
- {"dt": "2025-04-07T09:24:19.663902", "lvl": "target", "msg": "tst_test.c:1904: TINFO: Tested kernel: 6.4.16 #1 SMP Mon Apr  7 11:12:06 CEST 2025 aarch64"}
- {"dt": "2025-04-07T09:24:19.664537", "lvl": "target", "msg": "tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'"}
- {"dt": "2025-04-07T09:24:19.689622", "lvl": "target", "msg": "tst_test.c:1722: TINFO: Overall timeout per run is 0h 02m 30s"}
- {"dt": "2025-04-07T09:24:19.693351", "lvl": "target", "msg": "splice02.c:135: TINFO: writting 20 bytes"}
- {"dt": "2025-04-07T09:24:19.695903", "lvl": "target", "msg": "splice02.c:87: TINFO: checking file content"}
- {"dt": "2025-04-07T09:24:19.696507", "lvl": "target", "msg": "splice02.c:111: TPASS: splice() system call passed"}
- {"dt": "2025-04-07T09:24:19.700896", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:19.701161", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:19.701254", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-07T09:24:19.701333", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:19.701405", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-07T09:24:19.701477", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-07T09:24:19.701525", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:19.783081", "lvl": "target", "msg": "shell_pipe01 1 TINFO: Running: shell_pipe01.sh "}
- {"dt": "2025-04-07T09:24:19.792340", "lvl": "target", "msg": "shell_pipe01 1 TINFO: Tested kernel: Linux tuxtest 6.4.16 #1 SMP Mon Apr  7 11:12:06 CEST 2025 aarch64 GNU/Linux"}
- {"dt": "2025-04-07T09:24:19.842591", "lvl": "target", "msg": "shell_pipe01 1 TINFO: timeout per run is 0h 25m 0s"}
- {"dt": "2025-04-07T09:24:19.874456", "lvl": "target", "msg": "shell_pipe01 1 TINFO: expecting SUCCESS string passed from stdin"}
- {"dt": "2025-04-07T09:24:19.889299", "lvl": "target", "msg": "shell_pipe01 1 TPASS: [ SUCCESS = SUCCESS ] passed as expected"}
- {"dt": "2025-04-07T09:24:19.906512", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:19.906817", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:19.906906", "lvl": "target", "msg": "passed   1"}
- {"dt": "2025-04-07T09:24:19.906999", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:19.907083", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-07T09:24:19.907154", "lvl": "target", "msg": "skipped  0"}
- {"dt": "2025-04-07T09:24:19.907239", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:20.010573", "lvl": "target", "msg": "macsec02 1 TINFO: Running: macsec02.sh "}
- {"dt": "2025-04-07T09:24:20.021179", "lvl": "target", "msg": "macsec02 1 TINFO: Tested kernel: Linux tuxtest 6.4.16 #1 SMP Mon Apr  7 11:12:06 CEST 2025 aarch64 GNU/Linux"}
- {"dt": "2025-04-07T09:24:20.181166", "lvl": "target", "msg": "macsec02 1 TCONF: veth driver not available"}
- {"dt": "2025-04-07T09:24:20.185986", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:20.186090", "lvl": "target", "msg": "Summary:"}
- {"dt": "2025-04-07T09:24:20.186366", "lvl": "target", "msg": "passed   0"}
- {"dt": "2025-04-07T09:24:20.186445", "lvl": "target", "msg": "failed   0"}
- {"dt": "2025-04-07T09:24:20.186521", "lvl": "target", "msg": "broken   0"}
- {"dt": "2025-04-07T09:24:20.186592", "lvl": "target", "msg": "skipped  1"}
- {"dt": "2025-04-07T09:24:20.186662", "lvl": "target", "msg": "warnings 0"}
- {"dt": "2025-04-07T09:24:20.195435", "lvl": "target", "msg": "INFO: ltp-pan reported some tests FAIL"}
- {"dt": "2025-04-07T09:24:20.195523", "lvl": "target", "msg": "LTP Version: 20250130"}
- {"dt": "2025-04-07T09:24:20.202357", "lvl": "target", "msg": "INFO: Test end time: Mon Apr  7 09:24:20 UTC 2025"}
- {"dt": "2025-04-07T09:24:20.209501", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:20.209769", "lvl": "target", "msg": "       ###############################################################"}
- {"dt": "2025-04-07T09:24:20.209845", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:20.209936", "lvl": "target", "msg": "            Done executing testcases."}
- {"dt": "2025-04-07T09:24:20.210005", "lvl": "target", "msg": "            LTP Version:  20250130"}
- {"dt": "2025-04-07T09:24:20.210271", "lvl": "target", "msg": "       ###############################################################"}
- {"dt": "2025-04-07T09:24:20.210322", "lvl": "target", "msg": ""}
- {"dt": "2025-04-07T09:24:20.211098", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-07T09:24:20.211392", "lvl": "target", "msg": "INFO: runltp script is deprecated, try kirk"}
- {"dt": "2025-04-07T09:24:20.211628", "lvl": "target", "msg": "https://github.com/linux-test-project/kirk"}
- {"dt": "2025-04-07T09:24:20.211733", "lvl": "target", "msg": "-------------------------------------------"}
- {"dt": "2025-04-07T09:24:20.224459", "lvl": "target", "msg": "++ echo 1"}
- {"dt": "2025-04-07T09:24:20.230174", "lvl": "target", "msg": "+ ret_val=1"}
- {"dt": "2025-04-07T09:24:20.230461", "lvl": "target", "msg": "+ exec"}
- {"dt": "2025-04-07T09:24:20.230527", "lvl": "target", "msg": "+ return 1"}
- {"dt": "2025-04-07T09:24:20.231308", "lvl": "target", "msg": "+ parse_ltp_output /lava-1/0/tests/0_ltp-smoke/automated/linux/ltp/output/LTP_smoketest.log"}
- {"dt": "2025-04-07T09:24:20.234253", "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-07T09:24:20.236372", "lvl": "target", "msg": "+ sed 's/PASS/pass/; s/FAIL/fail/; s/CONF/skip/'"}
- {"dt": "2025-04-07T09:24:20.242893", "lvl": "target", "msg": "+ awk '{print $1\" \"$2}'"}
- {"dt": "2025-04-07T09:24:20.251162", "lvl": "target", "msg": "+ rm -rf /scratch"}
- {"dt": "2025-04-07T09:24:20.258129", "lvl": "target", "msg": "+ rm -rf alltests"}
- {"dt": "2025-04-07T09:24:20.266472", "lvl": "target", "msg": "+ ../../utils/send-to-lava.sh ./output/result.txt"}
- {"dt": "2025-04-07T09:24:20.296077", "lvl": "target", "msg": "WARNING: no log file found for access01 at ./output/access01.log"}
- {"dt": "2025-04-07T09:24:20.315999", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=access01 RESULT=pass>"}
- {"dt": "2025-04-07T09:24:20.316314", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=access01 RESULT=pass"}
- {"dt": "2025-04-07T09:24:20.316404", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "access01", "result": "pass", "starttc": ! "987", "endtc": ! "987"}}
- {"dt": "2025-04-07T09:24:20.329075", "lvl": "target", "msg": "WARNING: no log file found for chdir01 at ./output/chdir01.log"}
- {"dt": "2025-04-07T09:24:20.347018", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=chdir01 RESULT=skip>"}
- {"dt": "2025-04-07T09:24:20.347276", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=chdir01 RESULT=skip"}
- {"dt": "2025-04-07T09:24:20.347356", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "chdir01", "result": "skip", "starttc": ! "991", "endtc": ! "991"}}
- {"dt": "2025-04-07T09:24:20.357862", "lvl": "target", "msg": "WARNING: no log file found for fork01 at ./output/fork01.log"}
- {"dt": "2025-04-07T09:24:20.376188", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=fork01 RESULT=pass>"}
- {"dt": "2025-04-07T09:24:20.376453", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=fork01 RESULT=pass"}
- {"dt": "2025-04-07T09:24:20.376543", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "fork01", "result": "pass", "starttc": ! "995", "endtc": ! "995"}}
- {"dt": "2025-04-07T09:24:20.387553", "lvl": "target", "msg": "WARNING: no log file found for time01 at ./output/time01.log"}
- {"dt": "2025-04-07T09:24:20.406192", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=time01 RESULT=pass>"}
- {"dt": "2025-04-07T09:24:20.406473", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=time01 RESULT=pass"}
- {"dt": "2025-04-07T09:24:20.406594", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "time01", "result": "pass", "starttc": ! "999", "endtc": ! "999"}}
- {"dt": "2025-04-07T09:24:20.417866", "lvl": "target", "msg": "WARNING: no log file found for wait02 at ./output/wait02.log"}
- {"dt": "2025-04-07T09:24:20.435616", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=wait02 RESULT=pass>"}
- {"dt": "2025-04-07T09:24:20.435908", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=wait02 RESULT=pass"}
- {"dt": "2025-04-07T09:24:20.436021", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "wait02", "result": "pass", "starttc": ! "1003", "endtc": ! "1003"}}
- {"dt": "2025-04-07T09:24:20.448369", "lvl": "target", "msg": "WARNING: no log file found for write01 at ./output/write01.log"}
- {"dt": "2025-04-07T09:24:20.466758", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=write01 RESULT=pass>"}
- {"dt": "2025-04-07T09:24:20.467011", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=write01 RESULT=pass"}
- {"dt": "2025-04-07T09:24:20.467087", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "write01", "result": "pass", "starttc": ! "1007", "endtc": ! "1007"}}
- {"dt": "2025-04-07T09:24:20.477781", "lvl": "target", "msg": "WARNING: no log file found for symlink01 at ./output/symlink01.log"}
- {"dt": "2025-04-07T09:24:20.497148", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=symlink01 RESULT=pass>"}
- {"dt": "2025-04-07T09:24:20.497403", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=symlink01 RESULT=pass"}
- {"dt": "2025-04-07T09:24:20.497477", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "symlink01", "result": "pass", "starttc": ! "1011", "endtc": ! "1011"}}
- {"dt": "2025-04-07T09:24:20.508093", "lvl": "target", "msg": "WARNING: no log file found for stat04 at ./output/stat04.log"}
- {"dt": "2025-04-07T09:24:20.526823", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=stat04 RESULT=fail>"}
- {"dt": "2025-04-07T09:24:20.527088", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=stat04 RESULT=fail"}
- {"dt": "2025-04-07T09:24:20.527178", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "stat04", "result": "fail", "starttc": ! "1015", "endtc": ! "1015"}}
- {"dt": "2025-04-07T09:24:20.538368", "lvl": "target", "msg": "WARNING: no log file found for splice02 at ./output/splice02.log"}
- {"dt": "2025-04-07T09:24:20.556113", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=splice02 RESULT=pass>"}
- {"dt": "2025-04-07T09:24:20.556378", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=splice02 RESULT=pass"}
- {"dt": "2025-04-07T09:24:20.556456", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "splice02", "result": "pass", "starttc": ! "1019", "endtc": ! "1019"}}
- {"dt": "2025-04-07T09:24:20.566793", "lvl": "target", "msg": "WARNING: no log file found for df01_sh at ./output/df01_sh.log"}
- {"dt": "2025-04-07T09:24:20.585190", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=df01_sh RESULT=skip>"}
- {"dt": "2025-04-07T09:24:20.585454", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=df01_sh RESULT=skip"}
- {"dt": "2025-04-07T09:24:20.585535", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "df01_sh", "result": "skip", "starttc": ! "1023", "endtc": ! "1023"}}
- {"dt": "2025-04-07T09:24:20.596216", "lvl": "target", "msg": "WARNING: no log file found for shell_test01 at ./output/shell_test01.log"}
- {"dt": "2025-04-07T09:24:20.614849", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=shell_test01 RESULT=pass>"}
- {"dt": "2025-04-07T09:24:20.615113", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=shell_test01 RESULT=pass"}
- {"dt": "2025-04-07T09:24:20.615194", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "shell_test01", "result": "pass", "starttc": ! "1027", "endtc": ! "1027"}}
- {"dt": "2025-04-07T09:24:20.628308", "lvl": "target", "msg": "WARNING: no log file found for ping602 at ./output/ping602.log"}
- {"dt": "2025-04-07T09:24:20.646553", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=ping602 RESULT=skip>"}
- {"dt": "2025-04-07T09:24:20.646819", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=ping602 RESULT=skip"}
- {"dt": "2025-04-07T09:24:20.646910", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "ping602", "result": "skip", "starttc": ! "1031", "endtc": ! "1031"}}
- {"dt": "2025-04-07T09:24:20.657719", "lvl": "target", "msg": "WARNING: no log file found for macsec02 at ./output/macsec02.log"}
- {"dt": "2025-04-07T09:24:20.675616", "lvl": "target", "msg": "<8><LAVA_SIGNAL_TESTCASE TEST_CASE_ID=macsec02 RESULT=skip>"}
- {"dt": "2025-04-07T09:24:20.675885", "lvl": "debug", "msg": "Received signal: <TESTCASE> TEST_CASE_ID=macsec02 RESULT=skip"}
- {"dt": "2025-04-07T09:24:20.675989", "lvl": "results", "msg": {"definition": "0_ltp-smoke", "case": "macsec02", "result": "skip", "starttc": ! "1035", "endtc": ! "1035"}}
- {"dt": "2025-04-07T09:24:20.679254", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2025-04-07T09:24:20.679521", "lvl": "target", "msg": "<8><LAVA_SIGNAL_ENDRUN 0_ltp-smoke 1_1.1.3.1>"}
- {"dt": "2025-04-07T09:24:20.679800", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_ltp-smoke 1_1.1.3.1"}
- {"dt": "2025-04-07T09:24:20.679899", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2025-04-07T09:24:20.679968", "lvl": "info", "msg": "Ending test lava.0_ltp-smoke (1_1.1.3.1), duration 2.82"}
- {"dt": "2025-04-07T09:24:20.680082", "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": "2.82", "result": "pass", "revision": "unspecified", "namespace": "common"}}
- {"dt": "2025-04-07T09:24:20.684013", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2025-04-07T09:24:20.684266", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2025-04-07T09:24:20.684444", "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-07T09:24:20.684583", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:03) [common]"}
- {"dt": "2025-04-07T09:24:20.684704", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:03) [common]"}
- {"dt": "2025-04-07T09:24:20.684840", "lvl": "info", "msg": "start: 4 finalize (timeout 00:05:00) [common]"}
- {"dt": "2025-04-07T09:24:20.684974", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:30) [common]"}
- {"dt": "2025-04-07T09:24:20.685093", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2025-04-07T09:24:20.685175", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:05:00) [common]"}
- {"dt": "2025-04-07T09:24:20.685318", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-04-07T09:24:20.686748", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-04-07T09:24:20.686985", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2025-04-07T09:24:21.686361", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2025-04-07T09:24:21.686529", "lvl": "debug", "msg": "Sending line: 'poweroff'"}
- {"dt": "2025-04-07T09:24:21.787086", "lvl": "input", "msg": "poweroff"}
- {"dt": "2025-04-07T09:24:21.787494", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-04-07T09:24:21.787571", "lvl": "target", "msg": "root@tuxtest:~# poweroff"}
- {"dt": "2025-04-07T09:24:21.887874", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2025-04-07T09:24:21.888036", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2025-04-07T09:24:21.888123", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:01) [common]"}
- {"dt": "2025-04-07T09:24:21.888222", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2025-04-07T09:24:21.888345", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/url-repo-action-b66svup_/url_repo/https___storage.tuxboot.com_test-definitions_2025.03.tar"}
- {"dt": "2025-04-07T09:24:21.896025", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/deployimages-zkzwa7eu/kernel/Image"}
- {"dt": "2025-04-07T09:24:21.897925", "lvl": "debug", "msg": "Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1/deployimages-zkzwa7eu/rootfs/rootfs.ext2"}
- {"dt": "2025-04-07T09:24:22.042031", "lvl": "info", "msg": "Removing root tmp directory at /var/lib/lava/dispatcher/tmp/tuxrun-i4oz5h3k-1"}
- {"dt": "2025-04-07T09:24:22.077145", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2025-04-07T09:24:22.077240", "lvl": "results", "msg": {"definition": "lava", "case": "job", "result": "pass"}}
