- {"dt": "2023-03-29T03:09:00.868144", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-29T03:09:00.868674", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-29T03:09:00.869009", "lvl": "info", "msg": "Start time: 2023-03-29 03:09:00.868989+00:00 (UTC)"}
- {"dt": "2023-03-29T03:09:00.872655", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28581/1/qemuarm64/Image exists"}
- {"dt": "2023-03-29T03:09:01.078512", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28581/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz exists"}
- {"dt": "2023-03-29T03:09:01.315806", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-29T03:09:01.317503", "lvl": "info", "msg": "validate duration: 0.45"}
- {"dt": "2023-03-29T03:09:01.317783", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-29T03:09:01.318286", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.318590", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.319150", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1"}
- {"dt": "2023-03-29T03:09:01.319556", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin"}
- {"dt": "2023-03-29T03:09:01.319893", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/tests"}
- {"dt": "2023-03-29T03:09:01.320210", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/results"}
- {"dt": "2023-03-29T03:09:01.320570", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-add-keys"}
- {"dt": "2023-03-29T03:09:01.321029", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-add-sources"}
- {"dt": "2023-03-29T03:09:01.321453", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-background-process-start"}
- {"dt": "2023-03-29T03:09:01.321879", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-background-process-stop"}
- {"dt": "2023-03-29T03:09:01.322276", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-common-functions"}
- {"dt": "2023-03-29T03:09:01.322683", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-echo-ipv4"}
- {"dt": "2023-03-29T03:09:01.323082", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-install-packages"}
- {"dt": "2023-03-29T03:09:01.323484", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-installed-packages"}
- {"dt": "2023-03-29T03:09:01.323889", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-os-build"}
- {"dt": "2023-03-29T03:09:01.324316", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-probe-channel"}
- {"dt": "2023-03-29T03:09:01.324753", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-probe-ip"}
- {"dt": "2023-03-29T03:09:01.325159", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-target-ip"}
- {"dt": "2023-03-29T03:09:01.325558", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-target-mac"}
- {"dt": "2023-03-29T03:09:01.325949", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-target-storage"}
- {"dt": "2023-03-29T03:09:01.326347", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-test-case"}
- {"dt": "2023-03-29T03:09:01.326751", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-test-event"}
- {"dt": "2023-03-29T03:09:01.327153", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-test-feedback"}
- {"dt": "2023-03-29T03:09:01.327547", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-test-raise"}
- {"dt": "2023-03-29T03:09:01.327949", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-test-reference"}
- {"dt": "2023-03-29T03:09:01.328350", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-test-runner"}
- {"dt": "2023-03-29T03:09:01.328756", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-test-set"}
- {"dt": "2023-03-29T03:09:01.329152", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-test-shell"}
- {"dt": "2023-03-29T03:09:01.329557", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-29T03:09:01.330010", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-29T03:09:01.330397", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/environment"}
- {"dt": "2023-03-29T03:09:01.330716", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-29T03:09:01.330957", "lvl": "debug", "msg": "- LAVA_JOB_ID=32530"}
- {"dt": "2023-03-29T03:09:01.331180", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.23.0.2"}
- {"dt": "2023-03-29T03:09:01.331502", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.331731", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-29T03:09:01.331994", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:01.332274", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.332504", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-29T03:09:01.332759", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:01.333033", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.333440", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-29T03:09:01.333754", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.334018", "lvl": "debug", "msg": "Using /lava-32530 at stage 0"}
- {"dt": "2023-03-29T03:09:01.335090", "lvl": "debug", "msg": "uuid=32530_1.1.3.1 testdef=None"}
- {"dt": "2023-03-29T03:09:01.335386", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:01.335672", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.337382", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:01.337639", "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": "32530_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:01.338205", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.340262", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:01.340525", "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": "32530_1.1.3.1", "uuid": "32530_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:01.341118", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.373378", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/0/tests/0_fixup-weston-watchdog test_uuid 32530_1.1.3.1"}
- {"dt": "2023-03-29T03:09:01.373885", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:01.374133", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.03", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/0/tests/0_fixup-weston-watchdog/run.sh", "from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32530_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:01.374733", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-29T03:09:01.374980", "lvl": "debug", "msg": "Using /lava-32530 at stage 1"}
- {"dt": "2023-03-29T03:09:01.375303", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T03:09:01.375577", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-29T03:09:03.732558", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/1_ci-busybox"}
- {"dt": "2023-03-29T03:09:03.833169", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-29T03:09:03.834405", "lvl": "debug", "msg": "uuid=32530_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-29T03:09:03.834839", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-29T03:09:03.835108", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.46", "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-29T03:09:03.835674", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-29T03:09:03.837737", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:03.837986", "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": "32530_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:03.838579", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-29T03:09:03.841263", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:03.841523", "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": "32530_1.1.3.5", "uuid": "32530_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:03.842150", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-03-29T03:09:03.924047", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/1_ci-busybox test_uuid 32530_1.1.3.5"}
- {"dt": "2023-03-29T03:09:03.924583", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:03.924834", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.08", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/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": "32530_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:03.925449", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:57) [common]"}
- {"dt": "2023-03-29T03:09:03.925708", "lvl": "debug", "msg": "Using /lava-32530 at stage 1"}
- {"dt": "2023-03-29T03:09:03.926066", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T03:09:03.926337", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-29T03:09:05.816019", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-29T03:09:05.819158", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-29T03:09:05.820230", "lvl": "debug", "msg": "uuid=32530_1.1.3.9 testdef=None"}
- {"dt": "2023-03-29T03:09:05.820634", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-29T03:09:05.820894", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.90", "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-29T03:09:05.821455", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-29T03:09:05.823274", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:05.823515", "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": "32530_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:05.824103", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-29T03:09:05.826423", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:05.826682", "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": "32530_1.1.3.9", "uuid": "32530_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:05.827293", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-29T03:09:05.846387", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/2_ci-smoke-tests-basic test_uuid 32530_1.1.3.9"}
- {"dt": "2023-03-29T03:09:05.847384", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:05.847641", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/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": "32530_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:05.848251", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:55) [common]"}
- {"dt": "2023-03-29T03:09:05.848541", "lvl": "debug", "msg": "Using /lava-32530 at stage 1"}
- {"dt": "2023-03-29T03:09:05.848900", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T03:09:05.849168", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-29T03:09:08.503132", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/3_ci-service-check"}
- {"dt": "2023-03-29T03:09:08.631715", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-29T03:09:08.632851", "lvl": "debug", "msg": "uuid=32530_1.1.3.13 testdef=None"}
- {"dt": "2023-03-29T03:09:08.633245", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-29T03:09:08.633512", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.79", "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-29T03:09:08.634071", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-29T03:09:08.635981", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:08.636228", "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": "32530_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:08.636830", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-29T03:09:09.010361", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:09.010620", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.37", "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": "32530_1.1.3.13", "uuid": "32530_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:09.011243", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-03-29T03:09:09.158198", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1/tests/3_ci-service-check test_uuid 32530_1.1.3.13"}
- {"dt": "2023-03-29T03:09:09.158476", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-29T03:09:09.158699", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-29T03:09:09.159158", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:09.159402", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.15", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/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": "32530_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:09.160010", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:52) [common]"}
- {"dt": "2023-03-29T03:09:09.160269", "lvl": "debug", "msg": "Using /lava-32530 at stage 2"}
- {"dt": "2023-03-29T03:09:09.160651", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T03:09:09.160925", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/2/tests/4_screenshooter'"}
- {"dt": "2023-03-29T03:09:12.069966", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-29T03:09:12.071435", "lvl": "debug", "msg": "uuid=32530_1.1.3.17 testdef=None"}
- {"dt": "2023-03-29T03:09:12.071831", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-29T03:09:12.072093", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.91", "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-29T03:09:12.072679", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-29T03:09:12.074256", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:12.074497", "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": "32530_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:12.075092", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-29T03:09:12.077115", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:12.077373", "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": "32530_1.1.3.17", "uuid": "32530_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:12.078000", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-29T03:09:12.125142", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/2/tests/4_screenshooter test_uuid 32530_1.1.3.17"}
- {"dt": "2023-03-29T03:09:12.125668", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:12.125917", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.05", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/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": "32530_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:12.126533", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:49) [common]"}
- {"dt": "2023-03-29T03:09:12.126788", "lvl": "debug", "msg": "Using /lava-32530 at stage 3"}
- {"dt": "2023-03-29T03:09:12.127146", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-29T03:09:12.127411", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-03-29T03:09:14.980525", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-03-29T03:09:15.139491", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-29T03:09:15.140772", "lvl": "debug", "msg": "uuid=32530_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-29T03:09:15.141183", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-29T03:09:15.141446", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "3.01", "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-29T03:09:15.142015", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:46) [common]"}
- {"dt": "2023-03-29T03:09:15.144013", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:15.144259", "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": "32530_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:15.144858", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:46) [common]"}
- {"dt": "2023-03-29T03:09:15.157632", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:15.157899", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "32530_1.1.3.21", "uuid": "32530_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:15.158517", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:46) [common]"}
- {"dt": "2023-03-29T03:09:15.326131", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/3/tests/5_ci-dumpjournal test_uuid 32530_1.1.3.21"}
- {"dt": "2023-03-29T03:09:15.326655", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:15.326904", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.17", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/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": "32530_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:15.327470", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-29T03:09:15.327699", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/0 for stage 0"}
- {"dt": "2023-03-29T03:09:15.328004", "lvl": "debug", "msg": "- 0_fixup-weston-watchdog"}
- {"dt": "2023-03-29T03:09:15.328287", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/1 for stage 1"}
- {"dt": "2023-03-29T03:09:15.328606", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-29T03:09:15.328837", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-29T03:09:15.329052", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-29T03:09:15.329298", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/2 for stage 2"}
- {"dt": "2023-03-29T03:09:15.329597", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-03-29T03:09:15.329852", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32530/lava-overlay-yw2qr0h1/lava-32530/3 for stage 3"}
- {"dt": "2023-03-29T03:09:15.330150", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-03-29T03:09:15.330468", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:14) [common]"}
- {"dt": "2023-03-29T03:09:15.330764", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:46) [common]"}
- {"dt": "2023-03-29T03:09:15.600376", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:15.600893", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:46) [common]"}
- {"dt": "2023-03-29T03:09:15.601180", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:15.601491", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:14) [common]"}
- {"dt": "2023-03-29T03:09:15.601782", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:46) [common]"}
- {"dt": "2023-03-29T03:09:15.602036", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32530/compress-overlay-yldvo11b/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-29T03:09:20.886783", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:05) [common]"}
- {"dt": "2023-03-29T03:09:20.887572", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "5.28", "extra": {"success": "6de7d828-e667-4025-be34-63d858462f03"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:20.888144", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:40) [common]"}
- {"dt": "2023-03-29T03:09:20.888440", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:09:20.888757", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:40) [common]"}
- {"dt": "2023-03-29T03:09:20.889072", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:40) [common]"}
- {"dt": "2023-03-29T03:09:20.889568", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28581/1/qemuarm64/Image"}
- {"dt": "2023-03-29T03:09:20.889799", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32530/deployimages-z_huaqox/kernel/Image"}
- {"dt": "2023-03-29T03:09:20.890014", "lvl": "debug", "msg": "total size: 21885440 (20MB)"}
- {"dt": "2023-03-29T03:09:20.890223", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-29T03:09:21.197878", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-29T03:09:21.790522", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-29T03:09:21.891424", "lvl": "debug", "msg": "progress  10% (2MB)"}
- {"dt": "2023-03-29T03:09:22.086774", "lvl": "debug", "msg": "progress  15% (3MB)"}
- {"dt": "2023-03-29T03:09:22.277895", "lvl": "debug", "msg": "progress  20% (4MB)"}
- {"dt": "2023-03-29T03:09:22.485783", "lvl": "debug", "msg": "progress  25% (5MB)"}
- {"dt": "2023-03-29T03:09:22.758500", "lvl": "debug", "msg": "progress  30% (6MB)"}
- {"dt": "2023-03-29T03:09:22.962164", "lvl": "debug", "msg": "progress  35% (7MB)"}
- {"dt": "2023-03-29T03:09:23.169846", "lvl": "debug", "msg": "progress  40% (8MB)"}
- {"dt": "2023-03-29T03:09:23.436547", "lvl": "debug", "msg": "progress  45% (9MB)"}
- {"dt": "2023-03-29T03:09:23.636209", "lvl": "debug", "msg": "progress  50% (10MB)"}
- {"dt": "2023-03-29T03:09:23.834353", "lvl": "debug", "msg": "progress  55% (11MB)"}
- {"dt": "2023-03-29T03:09:24.030807", "lvl": "debug", "msg": "progress  60% (12MB)"}
- {"dt": "2023-03-29T03:09:24.231571", "lvl": "debug", "msg": "progress  65% (13MB)"}
- {"dt": "2023-03-29T03:09:24.432860", "lvl": "debug", "msg": "progress  70% (14MB)"}
- {"dt": "2023-03-29T03:09:24.671535", "lvl": "debug", "msg": "progress  75% (15MB)"}
- {"dt": "2023-03-29T03:09:24.894960", "lvl": "debug", "msg": "progress  80% (16MB)"}
- {"dt": "2023-03-29T03:09:25.092987", "lvl": "debug", "msg": "progress  85% (17MB)"}
- {"dt": "2023-03-29T03:09:25.290771", "lvl": "debug", "msg": "progress  90% (18MB)"}
- {"dt": "2023-03-29T03:09:25.498390", "lvl": "debug", "msg": "progress  95% (19MB)"}
- {"dt": "2023-03-29T03:09:25.697485", "lvl": "debug", "msg": "progress 100% (20MB)"}
- {"dt": "2023-03-29T03:09:25.698867", "lvl": "info", "msg": "20MB downloaded in 4.81s (4.34MB/s)"}
- {"dt": "2023-03-29T03:09:25.699498", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:05) [common]"}
- {"dt": "2023-03-29T03:09:25.699946", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "4.81", "extra": {"label": "kernel", "md5sum": "913e737c840e2883ed052129e8cf46fa", "sha256sum": "6fbed812adc76cfca00755b4674b66b318977ed06941469707d4fed92520f88c", "sha512sum": "7b62e502150ac01cc94dab7788db8d341d303a117cae230af67a947a333d9f4651b494eef3ac202d2678d7d27e64f56695247b2b7a809cc849703e17935155c3", "size": ! "21885440"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:09:25.701032", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:05) [common]"}
- {"dt": "2023-03-29T03:09:25.701585", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:36) [common]"}
- {"dt": "2023-03-29T03:09:25.702136", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:36) [common]"}
- {"dt": "2023-03-29T03:09:25.702830", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28581/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz"}
- {"dt": "2023-03-29T03:09:25.703197", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32530/deployimages-z_huaqox/rootvd/agl-demo-platform-qemuarm64.ext4"}
- {"dt": "2023-03-29T03:09:25.703551", "lvl": "debug", "msg": "total size: 475309852 (453MB)"}
- {"dt": "2023-03-29T03:09:25.703953", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-29T03:09:26.009064", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-29T03:09:32.504679", "lvl": "debug", "msg": "progress   5% (22MB)"}
- {"dt": "2023-03-29T03:09:38.446142", "lvl": "debug", "msg": "progress  10% (45MB)"}
- {"dt": "2023-03-29T03:09:42.797713", "lvl": "debug", "msg": "progress  15% (68MB)"}
- {"dt": "2023-03-29T03:09:46.875191", "lvl": "debug", "msg": "progress  20% (90MB)"}
- {"dt": "2023-03-29T03:09:50.997581", "lvl": "debug", "msg": "progress  25% (113MB)"}
- {"dt": "2023-03-29T03:09:55.560021", "lvl": "debug", "msg": "progress  30% (136MB)"}
- {"dt": "2023-03-29T03:10:00.974457", "lvl": "debug", "msg": "progress  35% (158MB)"}
- {"dt": "2023-03-29T03:10:07.181259", "lvl": "debug", "msg": "progress  40% (181MB)"}
- {"dt": "2023-03-29T03:10:13.552991", "lvl": "debug", "msg": "progress  45% (204MB)"}
- {"dt": "2023-03-29T03:10:19.018826", "lvl": "debug", "msg": "progress  50% (226MB)"}
- {"dt": "2023-03-29T03:10:23.186096", "lvl": "debug", "msg": "progress  55% (249MB)"}
- {"dt": "2023-03-29T03:10:27.709427", "lvl": "debug", "msg": "progress  60% (272MB)"}
- {"dt": "2023-03-29T03:10:31.808268", "lvl": "debug", "msg": "progress  65% (294MB)"}
- {"dt": "2023-03-29T03:10:35.562210", "lvl": "debug", "msg": "progress  70% (317MB)"}
- {"dt": "2023-03-29T03:10:39.631733", "lvl": "debug", "msg": "progress  75% (339MB)"}
- {"dt": "2023-03-29T03:10:45.687929", "lvl": "debug", "msg": "progress  80% (362MB)"}
- {"dt": "2023-03-29T03:10:50.474298", "lvl": "debug", "msg": "progress  85% (385MB)"}
- {"dt": "2023-03-29T03:10:54.441557", "lvl": "debug", "msg": "progress  90% (407MB)"}
- {"dt": "2023-03-29T03:10:59.039789", "lvl": "debug", "msg": "progress  95% (430MB)"}
- {"dt": "2023-03-29T03:11:05.392691", "lvl": "debug", "msg": "progress 100% (453MB)"}
- {"dt": "2023-03-29T03:11:07.453351", "lvl": "info", "msg": "453MB downloaded in 101.75s (4.45MB/s)"}
- {"dt": "2023-03-29T03:11:07.454750", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:42) [common]"}
- {"dt": "2023-03-29T03:11:07.455054", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "101.75", "extra": {"label": "rootvd", "md5sum": "95c3d196c28e7fc2baa7e7824868211e", "sha256sum": "9d1a9f417c72fbd4e560774e04a4383143ba8aa622e713ec5f83d4fa406efb18", "sha512sum": "b57c9186a582c71bac4135f140f129cf227a94939e23824894ac630dad6263144edf27998e9b24a36d1d398848e6913fb0cb86f922601c19b575410c856daa5b", "size": ! "475309852"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-29T03:11:07.455771", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:42) [common]"}
- {"dt": "2023-03-29T03:11:07.456127", "lvl": "info", "msg": "end: 1 deployimages (duration 00:02:06) [common]"}
- {"dt": "2023-03-29T03:11:07.456525", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-29T03:11:07.456869", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-29T03:11:07.457161", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-29T03:11:07.458033", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-29T03:11:07.458616", "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-744404 -kernel /var/lib/lava/dispatcher/tmp/32530/deployimages-z_huaqox/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/32530/deployimages-z_huaqox/rootvd/agl-demo-platform-qemuarm64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32530/apply-overlay-guest-pc_k61x2/lava-guest.qcow2,media=disk,if=virtio,id=lavatest"}
- {"dt": "2023-03-29T03:11:08.354856", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-29T03:11:08.355977", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-29T03:11:08.356272", "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-29T03:11:08.356908", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-29T03:11:08.357245", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-29T03:11:08.357638", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-29T03:11:08.357905", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-29T03:11:08.379603", "lvl": "target", "msg": "qemu-system-aarch64: -soundhw: invalid option"}
- {"dt": "2023-03-29T03:11:08.412916", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-29T03:11:08.413220", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:11:08.413487", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "0.06", "extra": {"fail": "Connection closed"}, "level": "2.2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-29T03:11:08.413956", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-29T03:11:08.414285", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-29T03:11:08.414533", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-29T03:11:08.414797", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.96", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-29T03:11:08.415383", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-29T03:11:08.415903", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32530/deployimages-z_huaqox/kernel"}
- {"dt": "2023-03-29T03:11:08.420820", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32530/deployimages-z_huaqox/rootvd"}
- {"dt": "2023-03-29T03:11:09.012559", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-29T03:11:09.013035", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:11:09.013341", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-29T03:11:09.013990", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-29T03:11:09.014386", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-29T03:11:09.115380", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-29T03:11:09.115714", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32530"}
- {"dt": "2023-03-29T03:11:09.135767", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-29T03:11:09.136034", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
