- {"dt": "2023-03-29T14:56:23.278106", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-29T14:56:23.278611", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-29T14:56:23.278945", "lvl": "info", "msg": "Start time: 2023-03-29 14:56:23.278922+00:00 (UTC)"}
- {"dt": "2023-03-29T14:56:23.282582", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28583/1/qemuarm64/Image exists"}
- {"dt": "2023-03-29T14:56:23.486932", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28583/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz exists"}
- {"dt": "2023-03-29T14:56:23.727153", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-29T14:56:23.728746", "lvl": "info", "msg": "validate duration: 0.45"}
- {"dt": "2023-03-29T14:56:23.729028", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-29T14:56:23.729518", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.729820", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.730374", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65"}
- {"dt": "2023-03-29T14:56:23.730782", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin"}
- {"dt": "2023-03-29T14:56:23.731118", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/tests"}
- {"dt": "2023-03-29T14:56:23.731431", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/results"}
- {"dt": "2023-03-29T14:56:23.731774", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-add-keys"}
- {"dt": "2023-03-29T14:56:23.732230", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-add-sources"}
- {"dt": "2023-03-29T14:56:23.732660", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-background-process-start"}
- {"dt": "2023-03-29T14:56:23.733084", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-background-process-stop"}
- {"dt": "2023-03-29T14:56:23.733487", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-common-functions"}
- {"dt": "2023-03-29T14:56:23.733884", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-echo-ipv4"}
- {"dt": "2023-03-29T14:56:23.734297", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-install-packages"}
- {"dt": "2023-03-29T14:56:23.734705", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-installed-packages"}
- {"dt": "2023-03-29T14:56:23.735108", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-os-build"}
- {"dt": "2023-03-29T14:56:23.735510", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-probe-channel"}
- {"dt": "2023-03-29T14:56:23.735916", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-probe-ip"}
- {"dt": "2023-03-29T14:56:23.736331", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-target-ip"}
- {"dt": "2023-03-29T14:56:23.736769", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-target-mac"}
- {"dt": "2023-03-29T14:56:23.737180", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-target-storage"}
- {"dt": "2023-03-29T14:56:23.737584", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-test-case"}
- {"dt": "2023-03-29T14:56:23.737988", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-test-event"}
- {"dt": "2023-03-29T14:56:23.738386", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-test-feedback"}
- {"dt": "2023-03-29T14:56:23.738784", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-test-raise"}
- {"dt": "2023-03-29T14:56:23.739189", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-test-reference"}
- {"dt": "2023-03-29T14:56:23.739589", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-test-runner"}
- {"dt": "2023-03-29T14:56:23.739986", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-test-set"}
- {"dt": "2023-03-29T14:56:23.740392", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-test-shell"}
- {"dt": "2023-03-29T14:56:23.740817", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-29T14:56:23.741289", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-29T14:56:23.741685", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/environment"}
- {"dt": "2023-03-29T14:56:23.742008", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-29T14:56:23.742246", "lvl": "debug", "msg": "- LAVA_JOB_ID=32561"}
- {"dt": "2023-03-29T14:56:23.742476", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.23.0.2"}
- {"dt": "2023-03-29T14:56:23.742797", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.743027", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-29T14:56:23.743290", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:23.743566", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.743785", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-29T14:56:23.744036", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:23.744297", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.744720", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-29T14:56:23.745030", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.745288", "lvl": "debug", "msg": "Using /lava-32561 at stage 0"}
- {"dt": "2023-03-29T14:56:23.746315", "lvl": "debug", "msg": "uuid=32561_1.1.3.1 testdef=None"}
- {"dt": "2023-03-29T14:56:23.746608", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:23.746885", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.748541", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:23.748798", "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": "32561_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:23.749361", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.751384", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:23.751636", "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": "32561_1.1.3.1", "uuid": "32561_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:23.752216", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.754168", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/0/tests/0_fixup-weston-watchdog test_uuid 32561_1.1.3.1"}
- {"dt": "2023-03-29T14:56:23.754661", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:23.754910", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/0/tests/0_fixup-weston-watchdog/run.sh", "from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32561_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:23.755503", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T14:56:23.755755", "lvl": "debug", "msg": "Using /lava-32561 at stage 1"}
- {"dt": "2023-03-29T14:56:23.756076", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T14:56:23.756348", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-29T14:56:28.469175", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/1_ci-busybox"}
- {"dt": "2023-03-29T14:56:28.472644", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-29T14:56:28.473914", "lvl": "debug", "msg": "uuid=32561_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-29T14:56:28.474375", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:05) [common]"}
- {"dt": "2023-03-29T14:56:28.474667", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "4.72", "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-29T14:56:28.475252", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-29T14:56:28.477412", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:28.477678", "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": "32561_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:28.478288", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-29T14:56:28.481122", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:28.481390", "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": "32561_1.1.3.5", "uuid": "32561_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:28.482051", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-29T14:56:28.487571", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/1_ci-busybox test_uuid 32561_1.1.3.5"}
- {"dt": "2023-03-29T14:56:28.488108", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:28.488368", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/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": "32561_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:28.489017", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:55) [common]"}
- {"dt": "2023-03-29T14:56:28.489286", "lvl": "debug", "msg": "Using /lava-32561 at stage 1"}
- {"dt": "2023-03-29T14:56:28.489662", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T14:56:28.489940", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-29T14:56:30.665165", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-29T14:56:30.668525", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-29T14:56:30.669630", "lvl": "debug", "msg": "uuid=32561_1.1.3.9 testdef=None"}
- {"dt": "2023-03-29T14:56:30.670061", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-29T14:56:30.670356", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.18", "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-29T14:56:30.670945", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-29T14:56:30.672898", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:30.673176", "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": "32561_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:30.673789", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-29T14:56:30.676291", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:30.676596", "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": "32561_1.1.3.9", "uuid": "32561_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:30.677237", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-29T14:56:30.679629", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/2_ci-smoke-tests-basic test_uuid 32561_1.1.3.9"}
- {"dt": "2023-03-29T14:56:30.680680", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:30.680955", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/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": "32561_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:30.681577", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:53) [common]"}
- {"dt": "2023-03-29T14:56:30.681850", "lvl": "debug", "msg": "Using /lava-32561 at stage 1"}
- {"dt": "2023-03-29T14:56:30.682233", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T14:56:30.682521", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-29T14:56:32.728516", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/3_ci-service-check"}
- {"dt": "2023-03-29T14:56:32.731798", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-29T14:56:32.732964", "lvl": "debug", "msg": "uuid=32561_1.1.3.13 testdef=None"}
- {"dt": "2023-03-29T14:56:32.733390", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-29T14:56:32.733677", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.05", "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-29T14:56:32.734265", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-29T14:56:32.736275", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:32.736563", "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": "32561_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:32.737180", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-29T14:56:32.739760", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:32.740042", "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": "32561_1.1.3.13", "uuid": "32561_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:32.740725", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-29T14:56:32.743201", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1/tests/3_ci-service-check test_uuid 32561_1.1.3.13"}
- {"dt": "2023-03-29T14:56:32.743494", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-29T14:56:32.743735", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-29T14:56:32.744230", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:32.744510", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/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": "32561_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:32.745137", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:51) [common]"}
- {"dt": "2023-03-29T14:56:32.745409", "lvl": "debug", "msg": "Using /lava-32561 at stage 2"}
- {"dt": "2023-03-29T14:56:32.745787", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T14:56:32.746070", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/2/tests/4_screenshooter'"}
- {"dt": "2023-03-29T14:56:34.731932", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-29T14:56:34.733635", "lvl": "debug", "msg": "uuid=32561_1.1.3.17 testdef=None"}
- {"dt": "2023-03-29T14:56:34.734075", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-29T14:56:34.734367", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.99", "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-29T14:56:34.734958", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-29T14:56:34.736680", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:34.736946", "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": "32561_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:34.737564", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-29T14:56:34.739687", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:34.739966", "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": "32561_1.1.3.17", "uuid": "32561_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:34.740621", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-29T14:56:34.742654", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/2/tests/4_screenshooter test_uuid 32561_1.1.3.17"}
- {"dt": "2023-03-29T14:56:34.743195", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:34.743455", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/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": "32561_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:34.744076", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:49) [common]"}
- {"dt": "2023-03-29T14:56:34.744343", "lvl": "debug", "msg": "Using /lava-32561 at stage 3"}
- {"dt": "2023-03-29T14:56:34.744773", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T14:56:34.745056", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-03-29T14:56:36.836739", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-03-29T14:56:36.839972", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-29T14:56:36.841218", "lvl": "debug", "msg": "uuid=32561_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-29T14:56:36.841669", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-29T14:56:36.841960", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.10", "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-29T14:56:36.842552", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-03-29T14:56:36.844685", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:36.844959", "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": "32561_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:36.845581", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-03-29T14:56:36.848353", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:36.848645", "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": "32561_1.1.3.21", "uuid": "32561_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:36.849281", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-03-29T14:56:36.851900", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/3/tests/5_ci-dumpjournal test_uuid 32561_1.1.3.21"}
- {"dt": "2023-03-29T14:56:36.852446", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:36.852736", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/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": "32561_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:36.853312", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-29T14:56:36.853553", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/0 for stage 0"}
- {"dt": "2023-03-29T14:56:36.853879", "lvl": "debug", "msg": "- 0_fixup-weston-watchdog"}
- {"dt": "2023-03-29T14:56:36.854180", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/1 for stage 1"}
- {"dt": "2023-03-29T14:56:36.854512", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-29T14:56:36.854757", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-29T14:56:36.854990", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-29T14:56:36.855255", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/2 for stage 2"}
- {"dt": "2023-03-29T14:56:36.855578", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-03-29T14:56:36.855852", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32561/lava-overlay-01vznr65/lava-32561/3 for stage 3"}
- {"dt": "2023-03-29T14:56:36.856172", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-03-29T14:56:36.856525", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:13) [common]"}
- {"dt": "2023-03-29T14:56:36.856843", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-03-29T14:56:37.104118", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:37.104522", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-03-29T14:56:37.104814", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:37.105141", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:13) [common]"}
- {"dt": "2023-03-29T14:56:37.105427", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:47) [common]"}
- {"dt": "2023-03-29T14:56:37.105676", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32561/compress-overlay-3e5ylugr/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-29T14:56:41.277376", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:04) [common]"}
- {"dt": "2023-03-29T14:56:41.278135", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "4.17", "extra": {"success": "4c7b0d43-03bf-4f2f-abc4-c76b2a6c46a9"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:41.278698", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:42) [common]"}
- {"dt": "2023-03-29T14:56:41.278990", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:56:41.279293", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:42) [common]"}
- {"dt": "2023-03-29T14:56:41.279602", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:42) [common]"}
- {"dt": "2023-03-29T14:56:41.280075", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28583/1/qemuarm64/Image"}
- {"dt": "2023-03-29T14:56:41.280308", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32561/deployimages-0b2t21ab/kernel/Image"}
- {"dt": "2023-03-29T14:56:41.280561", "lvl": "debug", "msg": "total size: 21885440 (20MB)"}
- {"dt": "2023-03-29T14:56:41.280781", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-29T14:56:41.584454", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-29T14:56:42.270278", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-29T14:56:42.764383", "lvl": "debug", "msg": "progress  10% (2MB)"}
- {"dt": "2023-03-29T14:56:43.249922", "lvl": "debug", "msg": "progress  15% (3MB)"}
- {"dt": "2023-03-29T14:56:43.640188", "lvl": "debug", "msg": "progress  20% (4MB)"}
- {"dt": "2023-03-29T14:56:43.937073", "lvl": "debug", "msg": "progress  25% (5MB)"}
- {"dt": "2023-03-29T14:56:44.323968", "lvl": "debug", "msg": "progress  30% (6MB)"}
- {"dt": "2023-03-29T14:56:44.620266", "lvl": "debug", "msg": "progress  35% (7MB)"}
- {"dt": "2023-03-29T14:56:44.913170", "lvl": "debug", "msg": "progress  40% (8MB)"}
- {"dt": "2023-03-29T14:56:45.299401", "lvl": "debug", "msg": "progress  45% (9MB)"}
- {"dt": "2023-03-29T14:56:45.601698", "lvl": "debug", "msg": "progress  50% (10MB)"}
- {"dt": "2023-03-29T14:56:45.988109", "lvl": "debug", "msg": "progress  55% (11MB)"}
- {"dt": "2023-03-29T14:56:46.381789", "lvl": "debug", "msg": "progress  60% (12MB)"}
- {"dt": "2023-03-29T14:56:46.862831", "lvl": "debug", "msg": "progress  65% (13MB)"}
- {"dt": "2023-03-29T14:56:47.262767", "lvl": "debug", "msg": "progress  70% (14MB)"}
- {"dt": "2023-03-29T14:56:47.752770", "lvl": "debug", "msg": "progress  75% (15MB)"}
- {"dt": "2023-03-29T14:56:48.342625", "lvl": "debug", "msg": "progress  80% (16MB)"}
- {"dt": "2023-03-29T14:56:49.025997", "lvl": "debug", "msg": "progress  85% (17MB)"}
- {"dt": "2023-03-29T14:56:49.704792", "lvl": "debug", "msg": "progress  90% (18MB)"}
- {"dt": "2023-03-29T14:56:50.315181", "lvl": "debug", "msg": "progress  95% (19MB)"}
- {"dt": "2023-03-29T14:56:50.954150", "lvl": "debug", "msg": "progress 100% (20MB)"}
- {"dt": "2023-03-29T14:56:50.954887", "lvl": "info", "msg": "20MB downloaded in 9.67s (2.16MB/s)"}
- {"dt": "2023-03-29T14:56:50.955411", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:10) [common]"}
- {"dt": "2023-03-29T14:56:50.955707", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "9.68", "extra": {"label": "kernel", "md5sum": "48303aec3e8019f21bc725444b4ed6e6", "sha256sum": "b50dc761bd62f83daea4b5718c1ba0bbf9db024fb619c452187ded96b36658c4", "sha512sum": "b939292618c842586b14d01a7c570197e065e6fd4e85c04909e57e35ed3e7f013a4755a32705c8f77401a25790937496411c11eaf5711d1d1c3235c119087ba9", "size": ! "21885440"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:56:50.956366", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:10) [common]"}
- {"dt": "2023-03-29T14:56:50.956734", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:33) [common]"}
- {"dt": "2023-03-29T14:56:50.957063", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:33) [common]"}
- {"dt": "2023-03-29T14:56:50.957511", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28583/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz"}
- {"dt": "2023-03-29T14:56:50.957785", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32561/deployimages-0b2t21ab/rootvd/agl-demo-platform-qemuarm64.ext4"}
- {"dt": "2023-03-29T14:56:50.958036", "lvl": "debug", "msg": "total size: 477251980 (455MB)"}
- {"dt": "2023-03-29T14:56:50.958293", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-29T14:56:51.273489", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-29T14:56:57.619803", "lvl": "debug", "msg": "progress   5% (22MB)"}
- {"dt": "2023-03-29T14:57:01.246025", "lvl": "debug", "msg": "progress  10% (45MB)"}
- {"dt": "2023-03-29T14:57:09.650743", "lvl": "debug", "msg": "progress  15% (68MB)"}
- {"dt": "2023-03-29T14:57:17.747142", "lvl": "debug", "msg": "progress  20% (91MB)"}
- {"dt": "2023-03-29T14:57:26.603161", "lvl": "debug", "msg": "progress  25% (113MB)"}
- {"dt": "2023-03-29T14:57:34.846198", "lvl": "debug", "msg": "progress  30% (136MB)"}
- {"dt": "2023-03-29T14:57:39.861820", "lvl": "debug", "msg": "progress  35% (159MB)"}
- {"dt": "2023-03-29T14:57:43.902267", "lvl": "debug", "msg": "progress  40% (182MB)"}
- {"dt": "2023-03-29T14:57:48.456691", "lvl": "debug", "msg": "progress  45% (204MB)"}
- {"dt": "2023-03-29T14:57:53.833091", "lvl": "debug", "msg": "progress  50% (227MB)"}
- {"dt": "2023-03-29T14:57:59.894796", "lvl": "debug", "msg": "progress  55% (250MB)"}
- {"dt": "2023-03-29T14:58:05.733976", "lvl": "debug", "msg": "progress  60% (273MB)"}
- {"dt": "2023-03-29T14:58:11.463942", "lvl": "debug", "msg": "progress  65% (295MB)"}
- {"dt": "2023-03-29T14:58:16.878034", "lvl": "debug", "msg": "progress  70% (318MB)"}
- {"dt": "2023-03-29T14:58:21.791229", "lvl": "debug", "msg": "progress  75% (341MB)"}
- {"dt": "2023-03-29T14:58:26.236089", "lvl": "debug", "msg": "progress  80% (364MB)"}
- {"dt": "2023-03-29T14:58:31.102177", "lvl": "debug", "msg": "progress  85% (386MB)"}
- {"dt": "2023-03-29T14:58:35.768802", "lvl": "debug", "msg": "progress  90% (409MB)"}
- {"dt": "2023-03-29T14:58:39.651522", "lvl": "debug", "msg": "progress  95% (432MB)"}
- {"dt": "2023-03-29T14:58:44.875104", "lvl": "debug", "msg": "progress 100% (455MB)"}
- {"dt": "2023-03-29T14:58:47.345261", "lvl": "info", "msg": "455MB downloaded in 116.39s (3.91MB/s)"}
- {"dt": "2023-03-29T14:58:47.346087", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:56) [common]"}
- {"dt": "2023-03-29T14:58:47.346366", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "116.39", "extra": {"label": "rootvd", "md5sum": "c42f45eddb8666c4adcfc2edf9235b2b", "sha256sum": "7d1161528cb91363a502b8a9bbb78e6c323f5733ece683fe40253de86dd00e2c", "sha512sum": "5e9ba468665a19709a13d7cdc4c6030da7a1ec1674503a78fee99d9d595a92c81080f1785679ad7c7794b5386638e3e3c7412379e193d08a430194a5d79c7206", "size": ! "477251980"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T14:58:47.347007", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:56) [common]"}
- {"dt": "2023-03-29T14:58:47.347304", "lvl": "info", "msg": "end: 1 deployimages (duration 00:02:24) [common]"}
- {"dt": "2023-03-29T14:58:47.347608", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-29T14:58:47.347916", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-29T14:58:47.348201", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-29T14:58:47.348856", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-29T14:58:47.349320", "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-757530 -kernel /var/lib/lava/dispatcher/tmp/32561/deployimages-0b2t21ab/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/32561/deployimages-0b2t21ab/rootvd/agl-demo-platform-qemuarm64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32561/apply-overlay-guest-c05auf7u/lava-guest.qcow2,media=disk,if=virtio,id=lavatest"}
- {"dt": "2023-03-29T14:58:48.228925", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-29T14:58:48.229955", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-29T14:58:48.230251", "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-29T14:58:48.230852", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-29T14:58:48.231162", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-29T14:58:48.231468", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-29T14:58:48.231719", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-29T14:58:48.252913", "lvl": "target", "msg": "qemu-system-aarch64: -soundhw: invalid option"}
- {"dt": "2023-03-29T14:58:48.268840", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-29T14:58:48.269127", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:58:48.269388", "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-29T14:58:48.269841", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-29T14:58:48.270116", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-29T14:58:48.270356", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-29T14:58:48.270603", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.92", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-29T14:58:48.271128", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-29T14:58:48.271471", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32561/deployimages-0b2t21ab/kernel"}
- {"dt": "2023-03-29T14:58:48.276372", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32561/deployimages-0b2t21ab/rootvd"}
- {"dt": "2023-03-29T14:58:48.810731", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-29T14:58:48.811189", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:58:48.811495", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-29T14:58:48.812118", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-29T14:58:48.812508", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-29T14:58:48.913502", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T14:58:48.913871", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32561"}
- {"dt": "2023-03-29T14:58:48.933620", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-29T14:58:48.933883", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
