- {"dt": "2023-03-23T16:21:09.039841", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-23T16:21:09.040336", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-23T16:21:09.040691", "lvl": "info", "msg": "Start time: 2023-03-23 16:21:09.040668+00:00 (UTC)"}
- {"dt": "2023-03-23T16:21:09.044290", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28494/1/qemuarm64/Image exists"}
- {"dt": "2023-03-23T16:21:09.243971", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28494/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz exists"}
- {"dt": "2023-03-23T16:21:09.467889", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-23T16:21:09.469518", "lvl": "info", "msg": "validate duration: 0.43"}
- {"dt": "2023-03-23T16:21:09.469801", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-23T16:21:09.470291", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.470600", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.471167", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2"}
- {"dt": "2023-03-23T16:21:09.471585", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin"}
- {"dt": "2023-03-23T16:21:09.471932", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/tests"}
- {"dt": "2023-03-23T16:21:09.472252", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/results"}
- {"dt": "2023-03-23T16:21:09.472620", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-add-keys"}
- {"dt": "2023-03-23T16:21:09.473085", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-add-sources"}
- {"dt": "2023-03-23T16:21:09.473515", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-background-process-start"}
- {"dt": "2023-03-23T16:21:09.473945", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-background-process-stop"}
- {"dt": "2023-03-23T16:21:09.474355", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-common-functions"}
- {"dt": "2023-03-23T16:21:09.474762", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-echo-ipv4"}
- {"dt": "2023-03-23T16:21:09.475180", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-install-packages"}
- {"dt": "2023-03-23T16:21:09.475592", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-installed-packages"}
- {"dt": "2023-03-23T16:21:09.476004", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-os-build"}
- {"dt": "2023-03-23T16:21:09.476411", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-probe-channel"}
- {"dt": "2023-03-23T16:21:09.476837", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-probe-ip"}
- {"dt": "2023-03-23T16:21:09.477258", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-target-ip"}
- {"dt": "2023-03-23T16:21:09.477692", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-target-mac"}
- {"dt": "2023-03-23T16:21:09.478110", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-target-storage"}
- {"dt": "2023-03-23T16:21:09.478520", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-test-case"}
- {"dt": "2023-03-23T16:21:09.478934", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-test-event"}
- {"dt": "2023-03-23T16:21:09.479344", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-test-feedback"}
- {"dt": "2023-03-23T16:21:09.479748", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-test-raise"}
- {"dt": "2023-03-23T16:21:09.480163", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-test-reference"}
- {"dt": "2023-03-23T16:21:09.480591", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-test-runner"}
- {"dt": "2023-03-23T16:21:09.481006", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-test-set"}
- {"dt": "2023-03-23T16:21:09.481419", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-test-shell"}
- {"dt": "2023-03-23T16:21:09.481837", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-23T16:21:09.482318", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-23T16:21:09.482718", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/environment"}
- {"dt": "2023-03-23T16:21:09.483048", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-23T16:21:09.483290", "lvl": "debug", "msg": "- LAVA_JOB_ID=32402"}
- {"dt": "2023-03-23T16:21:09.483519", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.23.0.2"}
- {"dt": "2023-03-23T16:21:09.483841", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.484080", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-23T16:21:09.484364", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:09.484668", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.484895", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-23T16:21:09.485153", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:09.485424", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.485826", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-23T16:21:09.486146", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.486413", "lvl": "debug", "msg": "Using /lava-32402 at stage 0"}
- {"dt": "2023-03-23T16:21:09.487465", "lvl": "debug", "msg": "uuid=32402_1.1.3.1 testdef=None"}
- {"dt": "2023-03-23T16:21:09.487763", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:09.488048", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.489724", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:09.489983", "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": "32402_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:09.490557", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.492620", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:09.492879", "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": "32402_1.1.3.1", "uuid": "32402_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:09.493470", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.495424", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/0/tests/0_fixup-weston-watchdog test_uuid 32402_1.1.3.1"}
- {"dt": "2023-03-23T16:21:09.495927", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:09.496179", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/0/tests/0_fixup-weston-watchdog/run.sh", "from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32402_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:09.496794", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T16:21:09.497048", "lvl": "debug", "msg": "Using /lava-32402 at stage 1"}
- {"dt": "2023-03-23T16:21:09.497371", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T16:21:09.497645", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-23T16:21:14.445214", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/1_ci-busybox"}
- {"dt": "2023-03-23T16:21:14.448223", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-23T16:21:14.449425", "lvl": "debug", "msg": "uuid=32402_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-23T16:21:14.449846", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:05) [common]"}
- {"dt": "2023-03-23T16:21:14.450111", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "4.95", "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-23T16:21:14.450667", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-23T16:21:14.452724", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:14.452973", "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": "32402_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:14.453565", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-23T16:21:14.456269", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:14.456537", "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": "32402_1.1.3.5", "uuid": "32402_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:14.457166", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-23T16:21:14.459803", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/1_ci-busybox test_uuid 32402_1.1.3.5"}
- {"dt": "2023-03-23T16:21:14.460304", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:14.460563", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/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": "32402_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:14.461170", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:55) [common]"}
- {"dt": "2023-03-23T16:21:14.461425", "lvl": "debug", "msg": "Using /lava-32402 at stage 1"}
- {"dt": "2023-03-23T16:21:14.461778", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T16:21:14.462042", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-23T16:21:15.906506", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-23T16:21:15.909390", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-23T16:21:15.910424", "lvl": "debug", "msg": "uuid=32402_1.1.3.9 testdef=None"}
- {"dt": "2023-03-23T16:21:15.910812", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T16:21:15.911081", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.45", "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-23T16:21:15.911638", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-23T16:21:15.913484", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:15.913743", "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": "32402_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:15.914338", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-23T16:21:15.916720", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:15.916985", "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": "32402_1.1.3.9", "uuid": "32402_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:15.917623", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-23T16:21:15.919926", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/2_ci-smoke-tests-basic test_uuid 32402_1.1.3.9"}
- {"dt": "2023-03-23T16:21:15.920908", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:15.921171", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/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": "32402_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:15.921783", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:54) [common]"}
- {"dt": "2023-03-23T16:21:15.922045", "lvl": "debug", "msg": "Using /lava-32402 at stage 1"}
- {"dt": "2023-03-23T16:21:15.922403", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T16:21:15.922675", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-23T16:21:17.377082", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/3_ci-service-check"}
- {"dt": "2023-03-23T16:21:17.379941", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-23T16:21:17.381021", "lvl": "debug", "msg": "uuid=32402_1.1.3.13 testdef=None"}
- {"dt": "2023-03-23T16:21:17.381405", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T16:21:17.381667", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.46", "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-23T16:21:17.382224", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-23T16:21:17.384111", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:17.384366", "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": "32402_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:17.384976", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-23T16:21:17.387428", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:17.387694", "lvl": "results", "msg": {"case": "test-install-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", "skipped test-install-overlay": "32402_1.1.3.13", "uuid": "32402_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:17.388319", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-23T16:21:17.390713", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1/tests/3_ci-service-check test_uuid 32402_1.1.3.13"}
- {"dt": "2023-03-23T16:21:17.390993", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-23T16:21:17.391223", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-23T16:21:17.391683", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:17.391930", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/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": "32402_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:17.392551", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:52) [common]"}
- {"dt": "2023-03-23T16:21:17.392812", "lvl": "debug", "msg": "Using /lava-32402 at stage 2"}
- {"dt": "2023-03-23T16:21:17.393167", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T16:21:17.393435", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/2/tests/4_screenshooter'"}
- {"dt": "2023-03-23T16:21:18.888662", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-23T16:21:18.890006", "lvl": "debug", "msg": "uuid=32402_1.1.3.17 testdef=None"}
- {"dt": "2023-03-23T16:21:18.890403", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T16:21:18.890675", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.50", "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-23T16:21:18.891239", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-23T16:21:18.892853", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:18.893109", "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": "32402_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:18.893716", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-23T16:21:18.895720", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:18.895989", "lvl": "results", "msg": {"case": "test-install-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", "skipped test-install-overlay": "32402_1.1.3.17", "uuid": "32402_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:18.896634", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-23T16:21:18.898562", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/2/tests/4_screenshooter test_uuid 32402_1.1.3.17"}
- {"dt": "2023-03-23T16:21:18.899077", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:18.899330", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/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": "32402_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:18.899959", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:51) [common]"}
- {"dt": "2023-03-23T16:21:18.900219", "lvl": "debug", "msg": "Using /lava-32402 at stage 3"}
- {"dt": "2023-03-23T16:21:18.900584", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T16:21:18.900858", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-03-23T16:21:20.379052", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-03-23T16:21:20.382070", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-23T16:21:20.383236", "lvl": "debug", "msg": "uuid=32402_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-23T16:21:20.383648", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T16:21:20.383911", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.48", "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-23T16:21:20.384479", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-23T16:21:20.386477", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:20.386728", "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": "32402_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:20.387313", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-23T16:21:20.389934", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:20.390188", "lvl": "results", "msg": {"case": "test-install-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", "skipped test-install-overlay": "32402_1.1.3.21", "uuid": "32402_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:20.390794", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-23T16:21:20.393327", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/3/tests/5_ci-dumpjournal test_uuid 32402_1.1.3.21"}
- {"dt": "2023-03-23T16:21:20.393835", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:20.394082", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/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": "32402_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:20.394637", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-23T16:21:20.394863", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/0 for stage 0"}
- {"dt": "2023-03-23T16:21:20.395169", "lvl": "debug", "msg": "- 0_fixup-weston-watchdog"}
- {"dt": "2023-03-23T16:21:20.395458", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/1 for stage 1"}
- {"dt": "2023-03-23T16:21:20.395760", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-23T16:21:20.395984", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-23T16:21:20.396197", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-23T16:21:20.396443", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/2 for stage 2"}
- {"dt": "2023-03-23T16:21:20.396756", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-03-23T16:21:20.397012", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32402/lava-overlay-_2qlrhy2/lava-32402/3 for stage 3"}
- {"dt": "2023-03-23T16:21:20.397310", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-03-23T16:21:20.397624", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:11) [common]"}
- {"dt": "2023-03-23T16:21:20.397914", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-23T16:21:20.638301", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:20.638683", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-23T16:21:20.638966", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:20.639279", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:11) [common]"}
- {"dt": "2023-03-23T16:21:20.639572", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:49) [common]"}
- {"dt": "2023-03-23T16:21:20.639822", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32402/compress-overlay-y35m7_by/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-23T16:21:25.660169", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:05) [common]"}
- {"dt": "2023-03-23T16:21:25.660914", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "5.02", "extra": {"success": "ae7c6abf-cc7b-4973-b388-b64ed59673c4"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:25.661465", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:44) [common]"}
- {"dt": "2023-03-23T16:21:25.661765", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:21:25.662067", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:44) [common]"}
- {"dt": "2023-03-23T16:21:25.662371", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:44) [common]"}
- {"dt": "2023-03-23T16:21:25.662852", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28494/1/qemuarm64/Image"}
- {"dt": "2023-03-23T16:21:25.663089", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32402/deployimages-yq10a5d3/kernel/Image"}
- {"dt": "2023-03-23T16:21:25.663309", "lvl": "debug", "msg": "total size: 21883392 (20MB)"}
- {"dt": "2023-03-23T16:21:25.663525", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-23T16:21:25.969051", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-23T16:21:26.667093", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-23T16:21:26.768467", "lvl": "debug", "msg": "progress  10% (2MB)"}
- {"dt": "2023-03-23T16:21:27.153108", "lvl": "debug", "msg": "progress  15% (3MB)"}
- {"dt": "2023-03-23T16:21:27.540538", "lvl": "debug", "msg": "progress  20% (4MB)"}
- {"dt": "2023-03-23T16:21:27.836427", "lvl": "debug", "msg": "progress  25% (5MB)"}
- {"dt": "2023-03-23T16:21:28.222103", "lvl": "debug", "msg": "progress  30% (6MB)"}
- {"dt": "2023-03-23T16:21:28.631100", "lvl": "debug", "msg": "progress  35% (7MB)"}
- {"dt": "2023-03-23T16:21:29.015279", "lvl": "debug", "msg": "progress  40% (8MB)"}
- {"dt": "2023-03-23T16:21:29.483650", "lvl": "debug", "msg": "progress  45% (9MB)"}
- {"dt": "2023-03-23T16:21:29.881141", "lvl": "debug", "msg": "progress  50% (10MB)"}
- {"dt": "2023-03-23T16:21:30.282174", "lvl": "debug", "msg": "progress  55% (11MB)"}
- {"dt": "2023-03-23T16:21:30.672566", "lvl": "debug", "msg": "progress  60% (12MB)"}
- {"dt": "2023-03-23T16:21:31.269759", "lvl": "debug", "msg": "progress  65% (13MB)"}
- {"dt": "2023-03-23T16:21:31.931102", "lvl": "debug", "msg": "progress  70% (14MB)"}
- {"dt": "2023-03-23T16:21:32.610176", "lvl": "debug", "msg": "progress  75% (15MB)"}
- {"dt": "2023-03-23T16:21:33.217913", "lvl": "debug", "msg": "progress  80% (16MB)"}
- {"dt": "2023-03-23T16:21:33.874165", "lvl": "debug", "msg": "progress  85% (17MB)"}
- {"dt": "2023-03-23T16:21:34.479428", "lvl": "debug", "msg": "progress  90% (18MB)"}
- {"dt": "2023-03-23T16:21:35.134421", "lvl": "debug", "msg": "progress  95% (19MB)"}
- {"dt": "2023-03-23T16:21:35.721794", "lvl": "debug", "msg": "progress 100% (20MB)"}
- {"dt": "2023-03-23T16:21:35.722451", "lvl": "info", "msg": "20MB downloaded in 10.06s (2.07MB/s)"}
- {"dt": "2023-03-23T16:21:35.722907", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:10) [common]"}
- {"dt": "2023-03-23T16:21:35.723161", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "10.06", "extra": {"label": "kernel", "md5sum": "5e9f20a034c195b15e674b6650a83a3b", "sha256sum": "a0ee5e704725ef8a5e4b0b3fadebb1359ada4ae8f2d83149eef6d735146c6ebe", "sha512sum": "05754345a6a6bcfb4c6c1cfc3891fb46bb159741d9cc41993d6e6895d40320bbebbb301ba1b7013084c457abddbed74909b995629e73dd40808a6031388353df", "size": ! "21883392"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:21:35.723773", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:10) [common]"}
- {"dt": "2023-03-23T16:21:35.724064", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:34) [common]"}
- {"dt": "2023-03-23T16:21:35.724355", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:34) [common]"}
- {"dt": "2023-03-23T16:21:35.724784", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28494/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz"}
- {"dt": "2023-03-23T16:21:35.725020", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32402/deployimages-yq10a5d3/rootvd/agl-demo-platform-qemuarm64.ext4"}
- {"dt": "2023-03-23T16:21:35.725238", "lvl": "debug", "msg": "total size: 475193804 (453MB)"}
- {"dt": "2023-03-23T16:21:35.725460", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-23T16:21:36.031192", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-23T16:22:14.816850", "lvl": "debug", "msg": "progress   5% (22MB)"}
- {"dt": "2023-03-23T16:22:35.439195", "lvl": "debug", "msg": "progress  10% (45MB)"}
- {"dt": "2023-03-23T16:22:42.082828", "lvl": "debug", "msg": "progress  15% (68MB)"}
- {"dt": "2023-03-23T16:22:58.003570", "lvl": "debug", "msg": "progress  20% (90MB)"}
- {"dt": "2023-03-23T16:23:21.768795", "lvl": "debug", "msg": "progress  25% (113MB)"}
- {"dt": "2023-03-23T16:25:10.528587", "lvl": "debug", "msg": "progress  30% (135MB)"}
- {"dt": "2023-03-23T16:25:21.439559", "lvl": "debug", "msg": "progress  35% (158MB)"}
- {"dt": "2023-03-23T16:25:27.760656", "lvl": "debug", "msg": "progress  40% (181MB)"}
- {"dt": "2023-03-23T16:25:31.991634", "lvl": "debug", "msg": "progress  45% (203MB)"}
- {"dt": "2023-03-23T16:25:35.464839", "lvl": "debug", "msg": "progress  50% (226MB)"}
- {"dt": "2023-03-23T16:25:39.200216", "lvl": "debug", "msg": "progress  55% (249MB)"}
- {"dt": "2023-03-23T16:25:42.985804", "lvl": "debug", "msg": "progress  60% (271MB)"}
- {"dt": "2023-03-23T16:25:47.438163", "lvl": "debug", "msg": "progress  65% (294MB)"}
- {"dt": "2023-03-23T16:25:53.383170", "lvl": "debug", "msg": "progress  70% (317MB)"}
- {"dt": "2023-03-23T16:25:58.544560", "lvl": "debug", "msg": "progress  75% (339MB)"}
- {"dt": "2023-03-23T16:26:03.011054", "lvl": "debug", "msg": "progress  80% (362MB)"}
- {"dt": "2023-03-23T16:26:07.167052", "lvl": "debug", "msg": "progress  85% (385MB)"}
- {"dt": "2023-03-23T16:26:10.878547", "lvl": "debug", "msg": "progress  90% (407MB)"}
- {"dt": "2023-03-23T16:26:15.668930", "lvl": "debug", "msg": "progress  95% (430MB)"}
- {"dt": "2023-03-23T16:26:22.214303", "lvl": "debug", "msg": "progress 100% (453MB)"}
- {"dt": "2023-03-23T16:26:24.922378", "lvl": "info", "msg": "453MB downloaded in 289.20s (1.57MB/s)"}
- {"dt": "2023-03-23T16:26:24.923201", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:04:49) [common]"}
- {"dt": "2023-03-23T16:26:24.923482", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "289.20", "extra": {"label": "rootvd", "md5sum": "912e0bf641da898cb4b0f7873a318891", "sha256sum": "f2a90738915485b0b703f4f9e7ca57f80666568939b4d9cd1f8b85683c79f174", "sha512sum": "78e5aec9c9f09f8db53fbd96d0445bdfa62eaa56df268bda2c67d70859d61884e9b8f8fa64433ab4f266c384f2d1e52062a0ad32e3970d6123b97de18e000ecf", "size": ! "475193804"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T16:26:24.924134", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:04:49) [common]"}
- {"dt": "2023-03-23T16:26:24.924432", "lvl": "info", "msg": "end: 1 deployimages (duration 00:05:15) [common]"}
- {"dt": "2023-03-23T16:26:24.924787", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-23T16:26:24.925102", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-23T16:26:24.925393", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-23T16:26:24.926027", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-23T16:26:24.926485", "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-700464 -kernel /var/lib/lava/dispatcher/tmp/32402/deployimages-yq10a5d3/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/32402/deployimages-yq10a5d3/rootvd/agl-demo-platform-qemuarm64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32402/apply-overlay-guest-4lgt77xp/lava-guest.qcow2,media=disk,if=virtio,id=lavatest"}
- {"dt": "2023-03-23T16:26:25.808302", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-23T16:26:25.809293", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T16:26:25.809584", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.88", "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-23T16:26:25.810185", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T16:26:25.810506", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-23T16:26:25.810825", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-23T16:26:25.811082", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-23T16:26:25.831694", "lvl": "target", "msg": "qemu-system-aarch64: -soundhw: invalid option"}
- {"dt": "2023-03-23T16:26:25.848880", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-23T16:26:25.849165", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:26:25.849434", "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-23T16:26:25.849890", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-23T16:26:25.850165", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-23T16:26:25.850414", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T16:26:25.850673", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.93", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-23T16:26:25.851208", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-23T16:26:25.851576", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32402/deployimages-yq10a5d3/kernel"}
- {"dt": "2023-03-23T16:26:25.856444", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32402/deployimages-yq10a5d3/rootvd"}
- {"dt": "2023-03-23T16:26:26.362132", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-23T16:26:26.362581", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:26:26.362887", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-23T16:26:26.363508", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-23T16:26:26.363896", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-23T16:26:26.464922", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T16:26:26.465264", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32402"}
- {"dt": "2023-03-23T16:26:26.487932", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-23T16:26:26.488218", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
