- {"dt": "2023-03-15T16:18:46.390558", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-15T16:18:46.391074", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-15T16:18:46.391412", "lvl": "info", "msg": "Start time: 2023-03-15 16:18:46.391388+00:00 (UTC)"}
- {"dt": "2023-03-15T16:18:46.395045", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28568/1/qemuarm64/Image exists"}
- {"dt": "2023-03-15T16:18:46.601811", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28568/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz exists"}
- {"dt": "2023-03-15T16:18:46.830138", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-15T16:18:46.831791", "lvl": "info", "msg": "validate duration: 0.44"}
- {"dt": "2023-03-15T16:18:46.832072", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-15T16:18:46.832574", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.832879", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.833442", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua"}
- {"dt": "2023-03-15T16:18:46.833858", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin"}
- {"dt": "2023-03-15T16:18:46.834198", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/tests"}
- {"dt": "2023-03-15T16:18:46.834518", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/results"}
- {"dt": "2023-03-15T16:18:46.834875", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-add-keys"}
- {"dt": "2023-03-15T16:18:46.835342", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-add-sources"}
- {"dt": "2023-03-15T16:18:46.835771", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-background-process-start"}
- {"dt": "2023-03-15T16:18:46.836203", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-background-process-stop"}
- {"dt": "2023-03-15T16:18:46.836624", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-common-functions"}
- {"dt": "2023-03-15T16:18:46.837044", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-echo-ipv4"}
- {"dt": "2023-03-15T16:18:46.837464", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-install-packages"}
- {"dt": "2023-03-15T16:18:46.837878", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-installed-packages"}
- {"dt": "2023-03-15T16:18:46.838293", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-os-build"}
- {"dt": "2023-03-15T16:18:46.838705", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-probe-channel"}
- {"dt": "2023-03-15T16:18:46.839118", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-probe-ip"}
- {"dt": "2023-03-15T16:18:46.839541", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-target-ip"}
- {"dt": "2023-03-15T16:18:46.839973", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-target-mac"}
- {"dt": "2023-03-15T16:18:46.840397", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-target-storage"}
- {"dt": "2023-03-15T16:18:46.840840", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-test-case"}
- {"dt": "2023-03-15T16:18:46.841252", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-test-event"}
- {"dt": "2023-03-15T16:18:46.841662", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-test-feedback"}
- {"dt": "2023-03-15T16:18:46.842071", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-test-raise"}
- {"dt": "2023-03-15T16:18:46.842484", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-test-reference"}
- {"dt": "2023-03-15T16:18:46.842895", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-test-runner"}
- {"dt": "2023-03-15T16:18:46.843299", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-test-set"}
- {"dt": "2023-03-15T16:18:46.843706", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-test-shell"}
- {"dt": "2023-03-15T16:18:46.844127", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-15T16:18:46.844617", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-15T16:18:46.845017", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/environment"}
- {"dt": "2023-03-15T16:18:46.845349", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-15T16:18:46.845597", "lvl": "debug", "msg": "- LAVA_JOB_ID=32256"}
- {"dt": "2023-03-15T16:18:46.845828", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.21.0.2"}
- {"dt": "2023-03-15T16:18:46.846152", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.846385", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-15T16:18:46.846654", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:46.846939", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.847162", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-15T16:18:46.847418", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:46.847688", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.848087", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-15T16:18:46.848406", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.848975", "lvl": "debug", "msg": "Using /lava-32256 at stage 0"}
- {"dt": "2023-03-15T16:18:46.850229", "lvl": "debug", "msg": "uuid=32256_1.1.3.1 testdef=None"}
- {"dt": "2023-03-15T16:18:46.850556", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:46.850861", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.852649", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:46.852920", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32256_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:46.853509", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.855681", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:46.855948", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "skipped test-install-overlay": "32256_1.1.3.1", "uuid": "32256_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:46.856566", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.858632", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/0/tests/0_fixup-weston-watchdog test_uuid 32256_1.1.3.1"}
- {"dt": "2023-03-15T16:18:46.859154", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:46.859410", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/0/tests/0_fixup-weston-watchdog/run.sh", "from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32256_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:46.860023", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-15T16:18:46.860281", "lvl": "debug", "msg": "Using /lava-32256 at stage 1"}
- {"dt": "2023-03-15T16:18:46.860636", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T16:18:46.860925", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-15T16:18:49.092924", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/1_ci-busybox"}
- {"dt": "2023-03-15T16:18:49.096416", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-15T16:18:49.097709", "lvl": "debug", "msg": "uuid=32256_1.1.3.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-15T16:18:49.098183", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T16:18:49.098487", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.24", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:49.099099", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-15T16:18:49.101403", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:49.101696", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:49.102343", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-15T16:18:49.105395", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:49.105692", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32256_1.1.3.5", "uuid": "32256_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:49.106368", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:58) [common]"}
- {"dt": "2023-03-15T16:18:49.109313", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/1_ci-busybox test_uuid 32256_1.1.3.5"}
- {"dt": "2023-03-15T16:18:49.109899", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:49.110182", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/1_ci-busybox/run.sh", "from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:49.110860", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:58) [common]"}
- {"dt": "2023-03-15T16:18:49.111147", "lvl": "debug", "msg": "Using /lava-32256 at stage 1"}
- {"dt": "2023-03-15T16:18:49.111544", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T16:18:49.111840", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-15T16:18:50.533247", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-15T16:18:50.536401", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-15T16:18:50.537523", "lvl": "debug", "msg": "uuid=32256_1.1.3.9 testdef=None"}
- {"dt": "2023-03-15T16:18:50.537940", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T16:18:50.538221", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.43", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:50.538794", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-15T16:18:50.540713", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:50.540984", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:50.541589", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-15T16:18:50.544051", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:50.544321", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32256_1.1.3.9", "uuid": "32256_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:50.544965", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-15T16:18:50.547371", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/2_ci-smoke-tests-basic test_uuid 32256_1.1.3.9"}
- {"dt": "2023-03-15T16:18:50.548404", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:50.548685", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/2_ci-smoke-tests-basic/run.sh", "from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:50.549313", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:56) [common]"}
- {"dt": "2023-03-15T16:18:50.549579", "lvl": "debug", "msg": "Using /lava-32256 at stage 1"}
- {"dt": "2023-03-15T16:18:50.549953", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T16:18:50.550239", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-15T16:18:52.582503", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/3_ci-service-check"}
- {"dt": "2023-03-15T16:18:52.585727", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-15T16:18:52.586881", "lvl": "debug", "msg": "uuid=32256_1.1.3.13 testdef=None"}
- {"dt": "2023-03-15T16:18:52.587292", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T16:18:52.587563", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.04", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:52.588128", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-15T16:18:52.590101", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:52.590364", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:52.590961", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-15T16:18:52.604098", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:52.604366", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32256_1.1.3.13", "uuid": "32256_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:52.605031", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-15T16:18:52.617094", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/3_ci-service-check test_uuid 32256_1.1.3.13"}
- {"dt": "2023-03-15T16:18:52.617376", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-15T16:18:52.617605", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-15T16:18:52.618069", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:52.618316", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1/tests/3_ci-service-check/run.sh", "from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:52.618927", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:54) [common]"}
- {"dt": "2023-03-15T16:18:52.619187", "lvl": "debug", "msg": "Using /lava-32256 at stage 2"}
- {"dt": "2023-03-15T16:18:52.619551", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T16:18:52.619853", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/2/tests/4_screenshooter'"}
- {"dt": "2023-03-15T16:18:55.024796", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-15T16:18:55.026327", "lvl": "debug", "msg": "uuid=32256_1.1.3.17 testdef=None"}
- {"dt": "2023-03-15T16:18:55.026752", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T16:18:55.027033", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.41", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:55.027609", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-15T16:18:55.029290", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:55.029555", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:55.030171", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-15T16:18:55.137237", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:55.137532", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.11", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32256_1.1.3.17", "uuid": "32256_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:55.138175", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-15T16:18:55.201385", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/2/tests/4_screenshooter test_uuid 32256_1.1.3.17"}
- {"dt": "2023-03-15T16:18:55.201924", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:55.202194", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.06", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/2/tests/4_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:55.202818", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:52) [common]"}
- {"dt": "2023-03-15T16:18:55.203085", "lvl": "debug", "msg": "Using /lava-32256 at stage 3"}
- {"dt": "2023-03-15T16:18:55.203463", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-15T16:18:55.203764", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-03-15T16:18:57.595882", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-03-15T16:18:57.599105", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-15T16:18:57.600331", "lvl": "debug", "msg": "uuid=32256_1.1.3.21 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-15T16:18:57.600774", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-15T16:18:57.601045", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.40", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:57.601614", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T16:18:57.603679", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:57.603939", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:57.604544", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T16:18:57.641057", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:57.641334", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.04", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32256_1.1.3.21", "uuid": "32256_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:57.641951", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T16:18:57.727849", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/3/tests/5_ci-dumpjournal test_uuid 32256_1.1.3.21"}
- {"dt": "2023-03-15T16:18:57.728375", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:57.728657", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.09", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/3/tests/5_ci-dumpjournal/run.sh", "from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32256_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:18:57.729244", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-15T16:18:57.729477", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/0 for stage 0"}
- {"dt": "2023-03-15T16:18:57.729794", "lvl": "debug", "msg": "- 0_fixup-weston-watchdog"}
- {"dt": "2023-03-15T16:18:57.730083", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/1 for stage 1"}
- {"dt": "2023-03-15T16:18:57.730397", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-15T16:18:57.730629", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-15T16:18:57.730850", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-15T16:18:57.731105", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/2 for stage 2"}
- {"dt": "2023-03-15T16:18:57.731415", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-03-15T16:18:57.731677", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32256/lava-overlay-ebyvt7ua/lava-32256/3 for stage 3"}
- {"dt": "2023-03-15T16:18:57.731981", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-03-15T16:18:57.732308", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:11) [common]"}
- {"dt": "2023-03-15T16:18:57.732620", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T16:18:57.977292", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:57.977637", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T16:18:57.977937", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:18:57.978259", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:11) [common]"}
- {"dt": "2023-03-15T16:18:57.978560", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:49) [common]"}
- {"dt": "2023-03-15T16:18:57.978817", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32256/compress-overlay-cosnwjip/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-15T16:19:05.685219", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:08) [common]"}
- {"dt": "2023-03-15T16:19:05.685995", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "7.71", "extra": {"success": "dfb9cb15-9c13-4c01-9948-c27905796636"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:19:05.686550", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:41) [common]"}
- {"dt": "2023-03-15T16:19:05.686855", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:19:05.687169", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:41) [common]"}
- {"dt": "2023-03-15T16:19:05.687491", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:41) [common]"}
- {"dt": "2023-03-15T16:19:05.687977", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28568/1/qemuarm64/Image"}
- {"dt": "2023-03-15T16:19:05.688219", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32256/deployimages-2qt0gvvu/kernel/Image"}
- {"dt": "2023-03-15T16:19:05.688447", "lvl": "debug", "msg": "total size: 21885440 (20MB)"}
- {"dt": "2023-03-15T16:19:05.688685", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-15T16:19:05.991929", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-15T16:19:06.577542", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-15T16:19:07.062558", "lvl": "debug", "msg": "progress  10% (2MB)"}
- {"dt": "2023-03-15T16:19:07.549294", "lvl": "debug", "msg": "progress  15% (3MB)"}
- {"dt": "2023-03-15T16:19:08.032576", "lvl": "debug", "msg": "progress  20% (4MB)"}
- {"dt": "2023-03-15T16:19:08.521473", "lvl": "debug", "msg": "progress  25% (5MB)"}
- {"dt": "2023-03-15T16:19:09.108868", "lvl": "debug", "msg": "progress  30% (6MB)"}
- {"dt": "2023-03-15T16:19:09.690707", "lvl": "debug", "msg": "progress  35% (7MB)"}
- {"dt": "2023-03-15T16:19:10.262681", "lvl": "debug", "msg": "progress  40% (8MB)"}
- {"dt": "2023-03-15T16:19:10.760262", "lvl": "debug", "msg": "progress  45% (9MB)"}
- {"dt": "2023-03-15T16:19:11.296586", "lvl": "debug", "msg": "progress  50% (10MB)"}
- {"dt": "2023-03-15T16:19:11.861663", "lvl": "debug", "msg": "progress  55% (11MB)"}
- {"dt": "2023-03-15T16:19:12.536105", "lvl": "debug", "msg": "progress  60% (12MB)"}
- {"dt": "2023-03-15T16:19:13.164074", "lvl": "debug", "msg": "progress  65% (13MB)"}
- {"dt": "2023-03-15T16:19:13.717236", "lvl": "debug", "msg": "progress  70% (14MB)"}
- {"dt": "2023-03-15T16:19:14.285827", "lvl": "debug", "msg": "progress  75% (15MB)"}
- {"dt": "2023-03-15T16:19:14.856397", "lvl": "debug", "msg": "progress  80% (16MB)"}
- {"dt": "2023-03-15T16:19:15.352711", "lvl": "debug", "msg": "progress  85% (17MB)"}
- {"dt": "2023-03-15T16:19:15.877313", "lvl": "debug", "msg": "progress  90% (18MB)"}
- {"dt": "2023-03-15T16:19:16.417748", "lvl": "debug", "msg": "progress  95% (19MB)"}
- {"dt": "2023-03-15T16:19:16.911279", "lvl": "debug", "msg": "progress 100% (20MB)"}
- {"dt": "2023-03-15T16:19:16.911966", "lvl": "info", "msg": "20MB downloaded in 11.22s (1.86MB/s)"}
- {"dt": "2023-03-15T16:19:16.912433", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:11) [common]"}
- {"dt": "2023-03-15T16:19:16.912715", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "11.22", "extra": {"label": "kernel", "md5sum": "48303aec3e8019f21bc725444b4ed6e6", "sha256sum": "b50dc761bd62f83daea4b5718c1ba0bbf9db024fb619c452187ded96b36658c4", "sha512sum": "b939292618c842586b14d01a7c570197e065e6fd4e85c04909e57e35ed3e7f013a4755a32705c8f77401a25790937496411c11eaf5711d1d1c3235c119087ba9", "size": ! "21885440"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:19:16.913335", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:11) [common]"}
- {"dt": "2023-03-15T16:19:16.913632", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:30) [common]"}
- {"dt": "2023-03-15T16:19:16.913927", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:30) [common]"}
- {"dt": "2023-03-15T16:19:16.914315", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28568/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz"}
- {"dt": "2023-03-15T16:19:16.914558", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32256/deployimages-2qt0gvvu/rootvd/agl-demo-platform-qemuarm64.ext4"}
- {"dt": "2023-03-15T16:19:16.914784", "lvl": "debug", "msg": "total size: 475324948 (453MB)"}
- {"dt": "2023-03-15T16:19:16.915010", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-15T16:19:17.218534", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-15T16:19:30.573695", "lvl": "debug", "msg": "progress   5% (22MB)"}
- {"dt": "2023-03-15T16:19:48.155277", "lvl": "debug", "msg": "progress  10% (45MB)"}
- {"dt": "2023-03-15T16:20:00.214484", "lvl": "debug", "msg": "progress  15% (68MB)"}
- {"dt": "2023-03-15T16:20:07.312018", "lvl": "debug", "msg": "progress  20% (90MB)"}
- {"dt": "2023-03-15T16:20:16.713013", "lvl": "debug", "msg": "progress  25% (113MB)"}
- {"dt": "2023-03-15T16:20:25.971421", "lvl": "debug", "msg": "progress  30% (136MB)"}
- {"dt": "2023-03-15T16:20:33.974843", "lvl": "debug", "msg": "progress  35% (158MB)"}
- {"dt": "2023-03-15T16:20:38.043944", "lvl": "debug", "msg": "progress  40% (181MB)"}
- {"dt": "2023-03-15T16:20:51.402229", "lvl": "debug", "msg": "progress  45% (204MB)"}
- {"dt": "2023-03-15T16:21:06.642494", "lvl": "debug", "msg": "progress  50% (226MB)"}
- {"dt": "2023-03-15T16:21:18.920015", "lvl": "debug", "msg": "progress  55% (249MB)"}
- {"dt": "2023-03-15T16:21:24.804527", "lvl": "debug", "msg": "progress  60% (272MB)"}
- {"dt": "2023-03-15T16:21:32.851397", "lvl": "debug", "msg": "progress  65% (294MB)"}
- {"dt": "2023-03-15T16:21:40.587202", "lvl": "debug", "msg": "progress  70% (317MB)"}
- {"dt": "2023-03-15T16:21:48.032183", "lvl": "debug", "msg": "progress  75% (340MB)"}
- {"dt": "2023-03-15T16:21:56.460705", "lvl": "debug", "msg": "progress  80% (362MB)"}
- {"dt": "2023-03-15T16:22:04.831147", "lvl": "debug", "msg": "progress  85% (385MB)"}
- {"dt": "2023-03-15T16:22:15.501956", "lvl": "debug", "msg": "progress  90% (408MB)"}
- {"dt": "2023-03-15T16:22:20.711829", "lvl": "debug", "msg": "progress  95% (430MB)"}
- {"dt": "2023-03-15T16:22:26.306226", "lvl": "debug", "msg": "progress 100% (453MB)"}
- {"dt": "2023-03-15T16:22:28.932769", "lvl": "info", "msg": "453MB downloaded in 192.02s (2.36MB/s)"}
- {"dt": "2023-03-15T16:22:28.933610", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:03:12) [common]"}
- {"dt": "2023-03-15T16:22:28.933895", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "192.02", "extra": {"label": "rootvd", "md5sum": "2bd978c2c3faeec5d4f8332907fced5a", "sha256sum": "53b91a50463bdc4acafef3317d019b9e19c465486c11173fb2457af0c47405dd", "sha512sum": "2866fea8670f5cce580ffdd68ed25ae0fe5b6a46ff74ec080b811db87aba0a32238c130e56ecac9012a2d04ebe6dbcdb80c9f2172de77947bf99563f2f3ab530", "size": ! "475324948"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:22:28.934557", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:03:12) [common]"}
- {"dt": "2023-03-15T16:22:28.934855", "lvl": "info", "msg": "end: 1 deployimages (duration 00:03:42) [common]"}
- {"dt": "2023-03-15T16:22:28.935182", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T16:22:28.935510", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T16:22:28.935808", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-15T16:22:28.936543", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-15T16:22:28.937034", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-aarch64 -cpu cortex-a57 -machine virt -nographic -net nic,model=virtio,macaddr=52:54:00:12:34:58 -net user -m 4096 -monitor none -smp 2 -soundhw hda -device usb-ehci -device virtio-rng-pci -device VGA,vgamem_mb=128,edid=on -device qemu-xhci -device usb-tablet -device usb-kbd  -vnc unix:/tmp/vnc-AGL-qemuarm64-28004 -kernel /var/lib/lava/dispatcher/tmp/32256/deployimages-2qt0gvvu/kernel/Image -append \"console=ttyAMA0,115200 root=/dev/vda verbose mem=4096M systemd.log_color=false \" -drive format=raw,file=/var/lib/lava/dispatcher/tmp/32256/deployimages-2qt0gvvu/rootvd/agl-demo-platform-qemuarm64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32256/apply-overlay-guest-f2x4y1dd/lava-guest.qcow2,media=disk,if=virtio,id=lavatest"}
- {"dt": "2023-03-15T16:22:29.830592", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-15T16:22:29.831564", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T16:22:29.831863", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.90", "extra": {"host_arch": "amd64", "job_arch": "aarch64", "qemu_version": "1:7.2+dfsg-1~bpo11+2"}, "level": "2.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-15T16:22:29.832530", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T16:22:29.832857", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-15T16:22:29.833175", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-15T16:22:29.833436", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-15T16:22:29.854746", "lvl": "target", "msg": "qemu-system-aarch64: -soundhw: invalid option"}
- {"dt": "2023-03-15T16:22:29.876837", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-15T16:22:29.877122", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:22:29.877395", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "0.04", "extra": {"fail": "Connection closed"}, "level": "2.2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-15T16:22:29.877860", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-15T16:22:29.878147", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-15T16:22:29.878393", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-15T16:22:29.878651", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.94", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-15T16:22:29.879196", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-15T16:22:29.879555", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32256/deployimages-2qt0gvvu/kernel"}
- {"dt": "2023-03-15T16:22:29.884504", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32256/deployimages-2qt0gvvu/rootvd"}
- {"dt": "2023-03-15T16:22:30.397108", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-15T16:22:30.397531", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:22:30.397836", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-15T16:22:30.398452", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-15T16:22:30.398843", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-15T16:22:30.499853", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-15T16:22:30.500174", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32256"}
- {"dt": "2023-03-15T16:22:30.520937", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-15T16:22:30.521208", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
