- {"dt": "2023-03-23T03:12:25.100052", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-23T03:12:25.100639", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-23T03:12:25.101001", "lvl": "info", "msg": "Start time: 2023-03-23 03:12:25.100976+00:00 (UTC)"}
- {"dt": "2023-03-23T03:12:25.104790", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28518/6/qemux86-64/bzImage exists"}
- {"dt": "2023-03-23T03:12:25.339960", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28518/6/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz exists"}
- {"dt": "2023-03-23T03:12:25.567404", "lvl": "info", "msg": "qemu-system-x86, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-23T03:12:25.569149", "lvl": "info", "msg": "validate duration: 0.47"}
- {"dt": "2023-03-23T03:12:25.569455", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-23T03:12:25.569968", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T03:12:25.570302", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T03:12:25.625172", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0"}
- {"dt": "2023-03-23T03:12:25.625699", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin"}
- {"dt": "2023-03-23T03:12:25.626067", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/tests"}
- {"dt": "2023-03-23T03:12:25.626406", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/results"}
- {"dt": "2023-03-23T03:12:25.626799", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-add-keys"}
- {"dt": "2023-03-23T03:12:25.627302", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-add-sources"}
- {"dt": "2023-03-23T03:12:25.627737", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-background-process-start"}
- {"dt": "2023-03-23T03:12:25.628209", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-background-process-stop"}
- {"dt": "2023-03-23T03:12:25.628683", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-common-functions"}
- {"dt": "2023-03-23T03:12:25.629121", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-echo-ipv4"}
- {"dt": "2023-03-23T03:12:25.629567", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-install-packages"}
- {"dt": "2023-03-23T03:12:25.630005", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-installed-packages"}
- {"dt": "2023-03-23T03:12:25.630438", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-os-build"}
- {"dt": "2023-03-23T03:12:25.630863", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-probe-channel"}
- {"dt": "2023-03-23T03:12:25.631288", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-probe-ip"}
- {"dt": "2023-03-23T03:12:25.631723", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-target-ip"}
- {"dt": "2023-03-23T03:12:25.632174", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-target-mac"}
- {"dt": "2023-03-23T03:12:25.632637", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-target-storage"}
- {"dt": "2023-03-23T03:12:25.633085", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-test-case"}
- {"dt": "2023-03-23T03:12:25.633515", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-test-event"}
- {"dt": "2023-03-23T03:12:25.633945", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-test-feedback"}
- {"dt": "2023-03-23T03:12:25.634370", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-test-raise"}
- {"dt": "2023-03-23T03:12:25.634809", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-test-reference"}
- {"dt": "2023-03-23T03:12:25.635240", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-test-runner"}
- {"dt": "2023-03-23T03:12:25.635668", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-test-set"}
- {"dt": "2023-03-23T03:12:25.636100", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-test-shell"}
- {"dt": "2023-03-23T03:12:25.636554", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-23T03:12:25.648128", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-23T03:12:25.648580", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/environment"}
- {"dt": "2023-03-23T03:12:25.648929", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-23T03:12:25.649189", "lvl": "debug", "msg": "- LAVA_JOB_ID=32360"}
- {"dt": "2023-03-23T03:12:25.649435", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.22.0.2"}
- {"dt": "2023-03-23T03:12:25.649806", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T03:12:25.650051", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-23T03:12:25.650332", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:25.650637", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T03:12:25.650871", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-23T03:12:25.651138", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:25.651423", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T03:12:25.651850", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-23T03:12:25.652188", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-23T03:12:25.652471", "lvl": "debug", "msg": "Using /lava-32360 at stage 0"}
- {"dt": "2023-03-23T03:12:25.652835", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T03:12:25.653140", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/0_ci-busybox'"}
- {"dt": "2023-03-23T03:12:31.199855", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/0_ci-busybox"}
- {"dt": "2023-03-23T03:12:31.203389", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/0_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-23T03:12:31.204685", "lvl": "debug", "msg": "uuid=32360_1.1.3.1 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-23T03:12:31.205115", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:06) [common]"}
- {"dt": "2023-03-23T03:12:31.205393", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "5.55", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:31.205968", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-23T03:12:31.208101", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:31.208347", "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": "32360_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:31.208965", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-23T03:12:31.385317", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:31.385639", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.18", "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": "32360_1.1.3.1", "uuid": "32360_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:31.386259", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:29:54) [common]"}
- {"dt": "2023-03-23T03:12:31.626290", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/0_ci-busybox test_uuid 32360_1.1.3.1"}
- {"dt": "2023-03-23T03:12:31.626981", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:31.627242", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.24", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/0_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": "32360_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:31.627871", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:29:54) [common]"}
- {"dt": "2023-03-23T03:12:31.628132", "lvl": "debug", "msg": "Using /lava-32360 at stage 0"}
- {"dt": "2023-03-23T03:12:31.628527", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T03:12:31.628812", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/1_ci-smoke-tests-basic'"}
- {"dt": "2023-03-23T03:12:34.270494", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/1_ci-smoke-tests-basic"}
- {"dt": "2023-03-23T03:12:34.273729", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/1_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-23T03:12:34.274839", "lvl": "debug", "msg": "uuid=32360_1.1.3.5 testdef=None"}
- {"dt": "2023-03-23T03:12:34.275249", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-23T03:12:34.275515", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.65", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:34.276085", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-23T03:12:34.277932", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:34.278182", "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": "32360_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:34.278768", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-23T03:12:34.417084", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:34.417395", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.14", "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": "32360_1.1.3.5", "uuid": "32360_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:34.418027", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-23T03:12:34.468111", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/1_ci-smoke-tests-basic test_uuid 32360_1.1.3.5"}
- {"dt": "2023-03-23T03:12:34.581172", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:34.581496", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.16", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/1_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": "32360_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:34.582129", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:51) [common]"}
- {"dt": "2023-03-23T03:12:34.582394", "lvl": "debug", "msg": "Using /lava-32360 at stage 0"}
- {"dt": "2023-03-23T03:12:34.582773", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T03:12:34.583054", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/2_ci-service-check'"}
- {"dt": "2023-03-23T03:12:37.195817", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/2_ci-service-check"}
- {"dt": "2023-03-23T03:12:37.264500", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/2_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-23T03:12:37.265637", "lvl": "debug", "msg": "uuid=32360_1.1.3.9 testdef=None"}
- {"dt": "2023-03-23T03:12:37.266041", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-23T03:12:37.266302", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.68", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:37.266857", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:48) [common]"}
- {"dt": "2023-03-23T03:12:37.268769", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:37.269076", "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": "32360_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:37.269869", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:48) [common]"}
- {"dt": "2023-03-23T03:12:37.280299", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:37.280562", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32360_1.1.3.9", "uuid": "32360_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:37.281173", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:48) [common]"}
- {"dt": "2023-03-23T03:12:37.391188", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/2_ci-service-check test_uuid 32360_1.1.3.9"}
- {"dt": "2023-03-23T03:12:37.391477", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-23T03:12:37.391693", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-23T03:12:37.392149", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:37.392392", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.11", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0/tests/2_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": "32360_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:37.393018", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:48) [common]"}
- {"dt": "2023-03-23T03:12:37.393271", "lvl": "debug", "msg": "Using /lava-32360 at stage 1"}
- {"dt": "2023-03-23T03:12:37.393627", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T03:12:37.393901", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/1/tests/3_screenshooter'"}
- {"dt": "2023-03-23T03:12:40.656475", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/1/tests/3_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-23T03:12:40.657975", "lvl": "debug", "msg": "uuid=32360_1.1.3.13 testdef=None"}
- {"dt": "2023-03-23T03:12:40.658406", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-23T03:12:40.658681", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "3.27", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:40.659239", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:45) [common]"}
- {"dt": "2023-03-23T03:12:40.660859", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:40.661112", "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": "32360_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:40.661692", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:45) [common]"}
- {"dt": "2023-03-23T03:12:40.858391", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:40.858651", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.20", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32360_1.1.3.13", "uuid": "32360_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:40.859268", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:45) [common]"}
- {"dt": "2023-03-23T03:12:40.987949", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/1/tests/3_screenshooter test_uuid 32360_1.1.3.13"}
- {"dt": "2023-03-23T03:12:40.988469", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:40.988721", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.13", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/1/tests/3_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32360_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:40.989333", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:45) [common]"}
- {"dt": "2023-03-23T03:12:40.989613", "lvl": "debug", "msg": "Using /lava-32360 at stage 2"}
- {"dt": "2023-03-23T03:12:40.989976", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-23T03:12:40.990256", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/2/tests/4_ci-dumpjournal'"}
- {"dt": "2023-03-23T03:12:43.676002", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/2/tests/4_ci-dumpjournal"}
- {"dt": "2023-03-23T03:12:43.835031", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/2/tests/4_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-23T03:12:43.836378", "lvl": "debug", "msg": "uuid=32360_1.1.3.17 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-23T03:12:43.836834", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-23T03:12:43.837104", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.85", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:43.837654", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:42) [common]"}
- {"dt": "2023-03-23T03:12:43.839651", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:43.839889", "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": "32360_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:43.840478", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:42) [common]"}
- {"dt": "2023-03-23T03:12:43.843061", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:43.843307", "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": "32360_1.1.3.17", "uuid": "32360_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:43.843903", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:42) [common]"}
- {"dt": "2023-03-23T03:12:43.996977", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/2/tests/4_ci-dumpjournal test_uuid 32360_1.1.3.17"}
- {"dt": "2023-03-23T03:12:43.997488", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:43.997738", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.15", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/2/tests/4_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": "32360_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:43.998296", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-23T03:12:43.998522", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/0 for stage 0"}
- {"dt": "2023-03-23T03:12:43.998834", "lvl": "debug", "msg": "- 0_ci-busybox"}
- {"dt": "2023-03-23T03:12:43.999083", "lvl": "debug", "msg": "- 1_ci-smoke-tests-basic"}
- {"dt": "2023-03-23T03:12:43.999298", "lvl": "debug", "msg": "- 2_ci-service-check"}
- {"dt": "2023-03-23T03:12:43.999544", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/1 for stage 1"}
- {"dt": "2023-03-23T03:12:43.999843", "lvl": "debug", "msg": "- 3_screenshooter"}
- {"dt": "2023-03-23T03:12:44.000099", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32360/lava-overlay-n4flkcz0/lava-32360/2 for stage 2"}
- {"dt": "2023-03-23T03:12:44.000395", "lvl": "debug", "msg": "- 4_ci-dumpjournal"}
- {"dt": "2023-03-23T03:12:44.000732", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:18) [common]"}
- {"dt": "2023-03-23T03:12:44.001027", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:42) [common]"}
- {"dt": "2023-03-23T03:12:44.241975", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:44.242316", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:41) [common]"}
- {"dt": "2023-03-23T03:12:44.242611", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:44.242920", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:19) [common]"}
- {"dt": "2023-03-23T03:12:44.243205", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:41) [common]"}
- {"dt": "2023-03-23T03:12:44.243455", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32360/compress-overlay-ogc5pirj/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-23T03:12:50.067096", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:06) [common]"}
- {"dt": "2023-03-23T03:12:50.067889", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "5.82", "extra": {"success": "de065ce7-4459-4a18-81c9-a0d0ba1a6312"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:50.068442", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:36) [common]"}
- {"dt": "2023-03-23T03:12:50.068754", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:12:50.069054", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:36) [common]"}
- {"dt": "2023-03-23T03:12:50.069368", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:36) [common]"}
- {"dt": "2023-03-23T03:12:50.069863", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28518/6/qemux86-64/bzImage"}
- {"dt": "2023-03-23T03:12:50.070098", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32360/deployimages-w30obx38/kernel/bzImage"}
- {"dt": "2023-03-23T03:12:50.070320", "lvl": "debug", "msg": "total size: 10516096 (10MB)"}
- {"dt": "2023-03-23T03:12:50.070534", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-23T03:12:50.369643", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-23T03:12:50.815657", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-03-23T03:12:50.860567", "lvl": "debug", "msg": "progress  10% (1MB)"}
- {"dt": "2023-03-23T03:12:51.053060", "lvl": "debug", "msg": "progress  15% (1MB)"}
- {"dt": "2023-03-23T03:12:51.152806", "lvl": "debug", "msg": "progress  20% (2MB)"}
- {"dt": "2023-03-23T03:12:51.343483", "lvl": "debug", "msg": "progress  25% (2MB)"}
- {"dt": "2023-03-23T03:12:51.450197", "lvl": "debug", "msg": "progress  30% (3MB)"}
- {"dt": "2023-03-23T03:12:51.679775", "lvl": "debug", "msg": "progress  35% (3MB)"}
- {"dt": "2023-03-23T03:12:51.772076", "lvl": "debug", "msg": "progress  40% (4MB)"}
- {"dt": "2023-03-23T03:12:51.920107", "lvl": "debug", "msg": "progress  45% (4MB)"}
- {"dt": "2023-03-23T03:12:52.103707", "lvl": "debug", "msg": "progress  50% (5MB)"}
- {"dt": "2023-03-23T03:12:52.203043", "lvl": "debug", "msg": "progress  55% (5MB)"}
- {"dt": "2023-03-23T03:12:52.490219", "lvl": "debug", "msg": "progress  60% (6MB)"}
- {"dt": "2023-03-23T03:12:52.535776", "lvl": "debug", "msg": "progress  65% (6MB)"}
- {"dt": "2023-03-23T03:12:52.683093", "lvl": "debug", "msg": "progress  70% (7MB)"}
- {"dt": "2023-03-23T03:12:52.827488", "lvl": "debug", "msg": "progress  75% (7MB)"}
- {"dt": "2023-03-23T03:12:52.927967", "lvl": "debug", "msg": "progress  80% (8MB)"}
- {"dt": "2023-03-23T03:12:53.116861", "lvl": "debug", "msg": "progress  85% (8MB)"}
- {"dt": "2023-03-23T03:12:53.220934", "lvl": "debug", "msg": "progress  90% (9MB)"}
- {"dt": "2023-03-23T03:12:53.363779", "lvl": "debug", "msg": "progress  95% (9MB)"}
- {"dt": "2023-03-23T03:12:53.510886", "lvl": "debug", "msg": "progress 100% (10MB)"}
- {"dt": "2023-03-23T03:12:53.512009", "lvl": "info", "msg": "10MB downloaded in 3.44s (2.91MB/s)"}
- {"dt": "2023-03-23T03:12:53.512674", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2023-03-23T03:12:53.513066", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "3.44", "extra": {"label": "kernel", "md5sum": "41b1fca475cb115d84636f04e777bbb2", "sha256sum": "ed11377b90eb134b9ef89c2f9383effcc72b0b07cfd84de5da9887fba60b5044", "sha512sum": "e3bef8e5d991b50ea3bf6417493f9edc0fc37fa8f0950f97d4476e0bfe2ce8d7d9234b9e3bacd6151a21e0ede841cc92c5aec58548e0c5048fa58c72076056f8", "size": ! "10516096"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:12:53.513961", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:03) [common]"}
- {"dt": "2023-03-23T03:12:53.514381", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:32) [common]"}
- {"dt": "2023-03-23T03:12:53.514798", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:32) [common]"}
- {"dt": "2023-03-23T03:12:53.515377", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28518/6/qemux86-64/agl-demo-platform-qemux86-64.ext4.xz"}
- {"dt": "2023-03-23T03:12:53.515718", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32360/deployimages-w30obx38/rootvd/agl-demo-platform-qemux86-64.ext4"}
- {"dt": "2023-03-23T03:12:53.516041", "lvl": "debug", "msg": "total size: 544711872 (519MB)"}
- {"dt": "2023-03-23T03:12:53.516369", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-23T03:12:53.820667", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-23T03:12:59.182845", "lvl": "debug", "msg": "progress   5% (26MB)"}
- {"dt": "2023-03-23T03:13:03.357849", "lvl": "debug", "msg": "progress  10% (51MB)"}
- {"dt": "2023-03-23T03:13:08.105795", "lvl": "debug", "msg": "progress  15% (77MB)"}
- {"dt": "2023-03-23T03:13:12.329209", "lvl": "debug", "msg": "progress  20% (103MB)"}
- {"dt": "2023-03-23T03:13:16.468974", "lvl": "debug", "msg": "progress  25% (129MB)"}
- {"dt": "2023-03-23T03:13:20.609933", "lvl": "debug", "msg": "progress  30% (155MB)"}
- {"dt": "2023-03-23T03:13:25.400856", "lvl": "debug", "msg": "progress  35% (181MB)"}
- {"dt": "2023-03-23T03:13:30.888166", "lvl": "debug", "msg": "progress  40% (207MB)"}
- {"dt": "2023-03-23T03:13:36.419558", "lvl": "debug", "msg": "progress  45% (233MB)"}
- {"dt": "2023-03-23T03:13:40.995894", "lvl": "debug", "msg": "progress  50% (259MB)"}
- {"dt": "2023-03-23T03:13:44.853636", "lvl": "debug", "msg": "progress  55% (285MB)"}
- {"dt": "2023-03-23T03:13:49.294500", "lvl": "debug", "msg": "progress  60% (311MB)"}
- {"dt": "2023-03-23T03:13:54.013622", "lvl": "debug", "msg": "progress  65% (337MB)"}
- {"dt": "2023-03-23T03:13:58.509394", "lvl": "debug", "msg": "progress  70% (363MB)"}
- {"dt": "2023-03-23T03:14:02.776297", "lvl": "debug", "msg": "progress  75% (389MB)"}
- {"dt": "2023-03-23T03:14:06.398423", "lvl": "debug", "msg": "progress  80% (415MB)"}
- {"dt": "2023-03-23T03:14:10.185882", "lvl": "debug", "msg": "progress  85% (441MB)"}
- {"dt": "2023-03-23T03:14:14.881535", "lvl": "debug", "msg": "progress  90% (467MB)"}
- {"dt": "2023-03-23T03:14:19.625094", "lvl": "debug", "msg": "progress  95% (493MB)"}
- {"dt": "2023-03-23T03:14:26.196817", "lvl": "debug", "msg": "progress 100% (519MB)"}
- {"dt": "2023-03-23T03:14:28.450047", "lvl": "info", "msg": "519MB downloaded in 94.93s (5.47MB/s)"}
- {"dt": "2023-03-23T03:14:28.452088", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:35) [common]"}
- {"dt": "2023-03-23T03:14:28.452437", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "94.94", "extra": {"label": "rootvd", "md5sum": "fd8e89e7f743cdf471e3c585880159b8", "sha256sum": "3c41348dca2ac74adabfb42c20f73fd8852c80ffd5e6878deb3bb2faa9c55e3b", "sha512sum": "1321d8c4434d2baa260e30f7f87c7da6257cbccc42891124f24d34b796baf7f170b6ba34785b45330ae4be420e3c8823cfe85103875c9bccd5f0d03f6c790e45", "size": ! "544711872"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:14:28.453251", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:35) [common]"}
- {"dt": "2023-03-23T03:14:28.453630", "lvl": "info", "msg": "end: 1 deployimages (duration 00:02:03) [common]"}
- {"dt": "2023-03-23T03:14:28.454071", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-23T03:14:28.454428", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-23T03:14:28.454788", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-23T03:14:28.455970", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-23T03:14:28.456620", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-x86_64 -cpu host -enable-kvm -nographic -net nic,model=virtio,macaddr=52:54:00:12:34:58 -net user -m 512 -monitor none -machine q35 -cpu qemu64,+ssse3,+sse4.1,+sse4.2,+popcnt -smp 2 -m 2048 -soundhw hda -device usb-ehci -device virtio-rng-pci -vga virtio -device qemu-xhci -device usb-tablet -device usb-kbd  -vnc unix:/tmp/vnc-AGL-qemux86-64-522006 -kernel /var/lib/lava/dispatcher/tmp/32360/deployimages-w30obx38/kernel/bzImage -append \"console=ttyS0,115200 root=/dev/sda verbose systemd.log_color=false rw fstab=no no_timer_check\" -drive format=raw,file=/var/lib/lava/dispatcher/tmp/32360/deployimages-w30obx38/rootvd/agl-demo-platform-qemux86-64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32360/apply-overlay-guest-_n3graz0/lava-guest.qcow2,media=disk,if=ide,id=lavatest"}
- {"dt": "2023-03-23T03:14:29.356318", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-23T03:14:29.357547", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T03:14:29.357880", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.90", "extra": {"host_arch": "amd64", "job_arch": "x86_64", "qemu_version": "1:7.2+dfsg-1~bpo11+2"}, "level": "2.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-23T03:14:29.358489", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T03:14:29.358842", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-23T03:14:29.359267", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-23T03:14:29.359547", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-23T03:14:29.379759", "lvl": "target", "msg": "qemu-system-x86_64: -soundhw: invalid option"}
- {"dt": "2023-03-23T03:14:29.409069", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-23T03:14:29.409529", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:14:29.409818", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "0.05", "extra": {"fail": "Connection closed"}, "level": "2.2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-23T03:14:29.410299", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-23T03:14:29.410637", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-23T03:14:29.410886", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-23T03:14:29.411139", "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-23T03:14:29.411768", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-23T03:14:29.412333", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32360/deployimages-w30obx38/kernel"}
- {"dt": "2023-03-23T03:14:29.487225", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32360/deployimages-w30obx38/rootvd"}
- {"dt": "2023-03-23T03:14:30.020088", "lvl": "debug", "msg": "start: 6.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-23T03:14:30.020678", "lvl": "debug", "msg": "end: 6.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:14:30.021154", "lvl": "debug", "msg": "start: 6.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-23T03:14:30.022062", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-23T03:14:30.022566", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-23T03:14:30.123623", "lvl": "debug", "msg": "end: 6.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-23T03:14:30.124014", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32360"}
- {"dt": "2023-03-23T03:14:30.144725", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-23T03:14:30.144992", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
