- {"dt": "2023-03-08T19:11:45.117935", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-08T19:11:45.118432", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-08T19:11:45.118767", "lvl": "info", "msg": "Start time: 2023-03-08 19:11:45.118739+00:00 (UTC)"}
- {"dt": "2023-03-08T19:11:45.122392", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/qemuarm64/Image exists"}
- {"dt": "2023-03-08T19:11:45.354068", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz exists"}
- {"dt": "2023-03-08T19:11:45.579069", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-03-08T19:11:45.580723", "lvl": "info", "msg": "validate duration: 0.46"}
- {"dt": "2023-03-08T19:11:45.581003", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-08T19:11:45.581501", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.581816", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.582377", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0"}
- {"dt": "2023-03-08T19:11:45.582786", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin"}
- {"dt": "2023-03-08T19:11:45.583120", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/tests"}
- {"dt": "2023-03-08T19:11:45.583438", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/results"}
- {"dt": "2023-03-08T19:11:45.583783", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-add-keys"}
- {"dt": "2023-03-08T19:11:45.584245", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-add-sources"}
- {"dt": "2023-03-08T19:11:45.584687", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-background-process-start"}
- {"dt": "2023-03-08T19:11:45.585116", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-background-process-stop"}
- {"dt": "2023-03-08T19:11:45.585521", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-common-functions"}
- {"dt": "2023-03-08T19:11:45.585921", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-echo-ipv4"}
- {"dt": "2023-03-08T19:11:45.586330", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-install-packages"}
- {"dt": "2023-03-08T19:11:45.586747", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-installed-packages"}
- {"dt": "2023-03-08T19:11:45.587160", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-os-build"}
- {"dt": "2023-03-08T19:11:45.587570", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-probe-channel"}
- {"dt": "2023-03-08T19:11:45.587981", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-probe-ip"}
- {"dt": "2023-03-08T19:11:45.588401", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-target-ip"}
- {"dt": "2023-03-08T19:11:45.588857", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-target-mac"}
- {"dt": "2023-03-08T19:11:45.589275", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-target-storage"}
- {"dt": "2023-03-08T19:11:45.589686", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-test-case"}
- {"dt": "2023-03-08T19:11:45.590094", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-test-event"}
- {"dt": "2023-03-08T19:11:45.590494", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-test-feedback"}
- {"dt": "2023-03-08T19:11:45.590892", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-test-raise"}
- {"dt": "2023-03-08T19:11:45.591301", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-test-reference"}
- {"dt": "2023-03-08T19:11:45.591705", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-test-runner"}
- {"dt": "2023-03-08T19:11:45.592107", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-test-set"}
- {"dt": "2023-03-08T19:11:45.592520", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-test-shell"}
- {"dt": "2023-03-08T19:11:45.592932", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-08T19:11:45.593406", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-08T19:11:45.593798", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/environment"}
- {"dt": "2023-03-08T19:11:45.594121", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-08T19:11:45.594357", "lvl": "debug", "msg": "- LAVA_JOB_ID=32177"}
- {"dt": "2023-03-08T19:11:45.594582", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.21.0.2"}
- {"dt": "2023-03-08T19:11:45.594907", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.595137", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-08T19:11:45.595401", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:45.595682", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.595898", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-08T19:11:45.596149", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:45.596419", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.596828", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-08T19:11:45.597144", "lvl": "debug", "msg": "start: 1.1.3.1 inline-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.597404", "lvl": "debug", "msg": "Using /lava-32177 at stage 0"}
- {"dt": "2023-03-08T19:11:45.598448", "lvl": "debug", "msg": "uuid=32177_1.1.3.1 testdef=None"}
- {"dt": "2023-03-08T19:11:45.598744", "lvl": "debug", "msg": "end: 1.1.3.1 inline-repo-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:45.599024", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.600684", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:45.600939", "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": "32177_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:45.601500", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.603546", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:45.603804", "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": "32177_1.1.3.1", "uuid": "32177_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:45.604391", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.606373", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/0/tests/0_fixup-weston-watchdog test_uuid 32177_1.1.3.1"}
- {"dt": "2023-03-08T19:11:45.606869", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:45.607114", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/0/tests/0_fixup-weston-watchdog/run.sh", "from": "inline", "name": "fixup-weston-watchdog", "path": "inline/fixup-weston-watchdog.yaml", "uuid": "32177_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:45.607706", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-03-08T19:11:45.607956", "lvl": "debug", "msg": "Using /lava-32177 at stage 1"}
- {"dt": "2023-03-08T19:11:45.608275", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-08T19:11:45.608558", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-08T19:11:49.317108", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/1_ci-busybox"}
- {"dt": "2023-03-08T19:11:49.320439", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-08T19:11:49.321703", "lvl": "debug", "msg": "uuid=32177_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-08T19:11:49.322146", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:04) [common]"}
- {"dt": "2023-03-08T19:11:49.322430", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "3.71", "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-08T19:11:49.323009", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-08T19:11:49.325185", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:49.325460", "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": "32177_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:49.326082", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-08T19:11:49.328974", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:49.329246", "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": "32177_1.1.3.5", "uuid": "32177_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:49.329893", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-03-08T19:11:49.332650", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/1_ci-busybox test_uuid 32177_1.1.3.5"}
- {"dt": "2023-03-08T19:11:49.333192", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:49.333454", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/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": "32177_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:49.334083", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:56) [common]"}
- {"dt": "2023-03-08T19:11:49.334354", "lvl": "debug", "msg": "Using /lava-32177 at stage 1"}
- {"dt": "2023-03-08T19:11:49.334729", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-08T19:11:49.335009", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-08T19:11:50.844662", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-08T19:11:50.847727", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-08T19:11:50.848822", "lvl": "debug", "msg": "uuid=32177_1.1.3.9 testdef=None"}
- {"dt": "2023-03-08T19:11:50.849224", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-08T19:11:50.849502", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.52", "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-08T19:11:50.850073", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-08T19:11:50.851981", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:50.852254", "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": "32177_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:50.852884", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-08T19:11:50.855394", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:50.855667", "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": "32177_1.1.3.9", "uuid": "32177_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:50.856311", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:55) [common]"}
- {"dt": "2023-03-08T19:11:50.858763", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/2_ci-smoke-tests-basic test_uuid 32177_1.1.3.9"}
- {"dt": "2023-03-08T19:11:50.859779", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:50.860047", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/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": "32177_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:50.860687", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:55) [common]"}
- {"dt": "2023-03-08T19:11:50.860954", "lvl": "debug", "msg": "Using /lava-32177 at stage 1"}
- {"dt": "2023-03-08T19:11:50.861324", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-08T19:11:50.861601", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-08T19:11:52.496056", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/3_ci-service-check"}
- {"dt": "2023-03-08T19:11:52.499001", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-08T19:11:52.500091", "lvl": "debug", "msg": "uuid=32177_1.1.3.13 testdef=None"}
- {"dt": "2023-03-08T19:11:52.500489", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-08T19:11:52.500757", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.64", "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-08T19:11:52.501315", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-08T19:11:52.503254", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:52.503517", "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": "32177_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:52.504111", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-08T19:11:52.506628", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:52.506894", "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": "32177_1.1.3.13", "uuid": "32177_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:52.507524", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:53) [common]"}
- {"dt": "2023-03-08T19:11:52.509955", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1/tests/3_ci-service-check test_uuid 32177_1.1.3.13"}
- {"dt": "2023-03-08T19:11:52.510233", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-08T19:11:52.510464", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-08T19:11:52.510935", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:52.511182", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/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": "32177_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:52.511808", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:53) [common]"}
- {"dt": "2023-03-08T19:11:52.512065", "lvl": "debug", "msg": "Using /lava-32177 at stage 2"}
- {"dt": "2023-03-08T19:11:52.512421", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-08T19:11:52.512704", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/2/tests/4_screenshooter'"}
- {"dt": "2023-03-08T19:11:54.927433", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-03-08T19:11:54.928856", "lvl": "debug", "msg": "uuid=32177_1.1.3.17 testdef=None"}
- {"dt": "2023-03-08T19:11:54.929282", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-08T19:11:54.929577", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "2.42", "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-08T19:11:54.930185", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-08T19:11:54.931876", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:54.932153", "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": "32177_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:54.932797", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-08T19:11:54.934932", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:54.935218", "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": "32177_1.1.3.17", "uuid": "32177_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:54.935870", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:51) [common]"}
- {"dt": "2023-03-08T19:11:54.937929", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/2/tests/4_screenshooter test_uuid 32177_1.1.3.17"}
- {"dt": "2023-03-08T19:11:54.938483", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:54.938747", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/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": "32177_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:54.939379", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:51) [common]"}
- {"dt": "2023-03-08T19:11:54.939653", "lvl": "debug", "msg": "Using /lava-32177 at stage 3"}
- {"dt": "2023-03-08T19:11:54.940049", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-08T19:11:54.940333", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-03-08T19:11:56.905761", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-03-08T19:11:56.908667", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-08T19:11:56.909822", "lvl": "debug", "msg": "uuid=32177_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-08T19:11:56.910220", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-08T19:11:56.910482", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.97", "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-08T19:11:56.911033", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-08T19:11:56.913057", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:56.913310", "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": "32177_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:56.913898", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-08T19:11:56.916526", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:56.916779", "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": "32177_1.1.3.21", "uuid": "32177_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:56.917384", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-08T19:11:56.919913", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/3/tests/5_ci-dumpjournal test_uuid 32177_1.1.3.21"}
- {"dt": "2023-03-08T19:11:56.920419", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:56.920679", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/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": "32177_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:11:56.921235", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-08T19:11:56.921459", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/0 for stage 0"}
- {"dt": "2023-03-08T19:11:56.921767", "lvl": "debug", "msg": "- 0_fixup-weston-watchdog"}
- {"dt": "2023-03-08T19:11:56.922051", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/1 for stage 1"}
- {"dt": "2023-03-08T19:11:56.922357", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-08T19:11:56.922596", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-08T19:11:56.922803", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-08T19:11:56.923047", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/2 for stage 2"}
- {"dt": "2023-03-08T19:11:56.923343", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-03-08T19:11:56.923592", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32177/lava-overlay-ro8e5ve0/lava-32177/3 for stage 3"}
- {"dt": "2023-03-08T19:11:56.923886", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-03-08T19:11:56.924200", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:11) [common]"}
- {"dt": "2023-03-08T19:11:56.924497", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-03-08T19:11:57.163729", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:57.164052", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:48) [common]"}
- {"dt": "2023-03-08T19:11:57.164329", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:11:57.164642", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:12) [common]"}
- {"dt": "2023-03-08T19:11:57.164924", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:48) [common]"}
- {"dt": "2023-03-08T19:11:57.165163", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32177/compress-overlay-t158vvd6/overlay-1.1.4.tar.gz"}
- {"dt": "2023-03-08T19:12:01.023595", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:04) [common]"}
- {"dt": "2023-03-08T19:12:01.024325", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "3.86", "extra": {"success": "761826b1-787d-4f5f-acfb-c56ada2c32d6"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:12:01.024905", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:45) [common]"}
- {"dt": "2023-03-08T19:12:01.025211", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:12:01.025517", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:45) [common]"}
- {"dt": "2023-03-08T19:12:01.025828", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:45) [common]"}
- {"dt": "2023-03-08T19:12:01.026309", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/qemuarm64/Image"}
- {"dt": "2023-03-08T19:12:01.026552", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32177/deployimages-i2c701dv/kernel/Image"}
- {"dt": "2023-03-08T19:12:01.026776", "lvl": "debug", "msg": "total size: 21883392 (20MB)"}
- {"dt": "2023-03-08T19:12:01.026997", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-08T19:12:01.332659", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-08T19:12:01.824630", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-08T19:12:02.116017", "lvl": "debug", "msg": "progress  10% (2MB)"}
- {"dt": "2023-03-08T19:12:02.405927", "lvl": "debug", "msg": "progress  15% (3MB)"}
- {"dt": "2023-03-08T19:12:02.604522", "lvl": "debug", "msg": "progress  20% (4MB)"}
- {"dt": "2023-03-08T19:12:02.887732", "lvl": "debug", "msg": "progress  25% (5MB)"}
- {"dt": "2023-03-08T19:12:03.087720", "lvl": "debug", "msg": "progress  30% (6MB)"}
- {"dt": "2023-03-08T19:12:03.284365", "lvl": "debug", "msg": "progress  35% (7MB)"}
- {"dt": "2023-03-08T19:12:03.479875", "lvl": "debug", "msg": "progress  40% (8MB)"}
- {"dt": "2023-03-08T19:12:03.673556", "lvl": "debug", "msg": "progress  45% (9MB)"}
- {"dt": "2023-03-08T19:12:03.865668", "lvl": "debug", "msg": "progress  50% (10MB)"}
- {"dt": "2023-03-08T19:12:04.058152", "lvl": "debug", "msg": "progress  55% (11MB)"}
- {"dt": "2023-03-08T19:12:04.167970", "lvl": "debug", "msg": "progress  60% (12MB)"}
- {"dt": "2023-03-08T19:12:04.356415", "lvl": "debug", "msg": "progress  65% (13MB)"}
- {"dt": "2023-03-08T19:12:04.538887", "lvl": "debug", "msg": "progress  70% (14MB)"}
- {"dt": "2023-03-08T19:12:04.649728", "lvl": "debug", "msg": "progress  75% (15MB)"}
- {"dt": "2023-03-08T19:12:04.826780", "lvl": "debug", "msg": "progress  80% (16MB)"}
- {"dt": "2023-03-08T19:12:04.941388", "lvl": "debug", "msg": "progress  85% (17MB)"}
- {"dt": "2023-03-08T19:12:05.052585", "lvl": "debug", "msg": "progress  90% (18MB)"}
- {"dt": "2023-03-08T19:12:05.231683", "lvl": "debug", "msg": "progress  95% (19MB)"}
- {"dt": "2023-03-08T19:12:05.348679", "lvl": "debug", "msg": "progress 100% (20MB)"}
- {"dt": "2023-03-08T19:12:05.349403", "lvl": "info", "msg": "20MB downloaded in 4.32s (4.83MB/s)"}
- {"dt": "2023-03-08T19:12:05.349941", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:04) [common]"}
- {"dt": "2023-03-08T19:12:05.350233", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "4.32", "extra": {"label": "kernel", "md5sum": "8ee52a0cd28aaedff3554aba69aa4d5f", "sha256sum": "7f2a230a1bbe7f5b598d94bd5b1ae6d02ff033ffd7111af8b2335b23bf361d8f", "sha512sum": "c5aed59c6c342560953fda21dcbf25d28e61e1722105f80f9cd5d445cb2bf75ca1a68abf295f53b37b999c24a6f22933b9a1a928070edbfc9beea0618a3720cb", "size": ! "21883392"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:12:05.350895", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:04) [common]"}
- {"dt": "2023-03-08T19:12:05.351241", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:40) [common]"}
- {"dt": "2023-03-08T19:12:05.351570", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:40) [common]"}
- {"dt": "2023-03-08T19:12:05.352037", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz"}
- {"dt": "2023-03-08T19:12:05.352299", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32177/deployimages-i2c701dv/rootvd/agl-demo-platform-qemuarm64.ext4"}
- {"dt": "2023-03-08T19:12:05.352565", "lvl": "debug", "msg": "total size: 476880168 (454MB)"}
- {"dt": "2023-03-08T19:12:05.352816", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-08T19:12:05.662005", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-08T19:12:10.382978", "lvl": "debug", "msg": "progress   5% (22MB)"}
- {"dt": "2023-03-08T19:12:14.440722", "lvl": "debug", "msg": "progress  10% (45MB)"}
- {"dt": "2023-03-08T19:12:18.543633", "lvl": "debug", "msg": "progress  15% (68MB)"}
- {"dt": "2023-03-08T19:12:23.938389", "lvl": "debug", "msg": "progress  20% (90MB)"}
- {"dt": "2023-03-08T19:12:28.973496", "lvl": "debug", "msg": "progress  25% (113MB)"}
- {"dt": "2023-03-08T19:12:32.628679", "lvl": "debug", "msg": "progress  30% (136MB)"}
- {"dt": "2023-03-08T19:12:36.969415", "lvl": "debug", "msg": "progress  35% (159MB)"}
- {"dt": "2023-03-08T19:12:40.890967", "lvl": "debug", "msg": "progress  40% (181MB)"}
- {"dt": "2023-03-08T19:12:44.178238", "lvl": "debug", "msg": "progress  45% (204MB)"}
- {"dt": "2023-03-08T19:12:47.940996", "lvl": "debug", "msg": "progress  50% (227MB)"}
- {"dt": "2023-03-08T19:12:52.043246", "lvl": "debug", "msg": "progress  55% (250MB)"}
- {"dt": "2023-03-08T19:12:56.013917", "lvl": "debug", "msg": "progress  60% (272MB)"}
- {"dt": "2023-03-08T19:13:00.060271", "lvl": "debug", "msg": "progress  65% (295MB)"}
- {"dt": "2023-03-08T19:13:04.714126", "lvl": "debug", "msg": "progress  70% (318MB)"}
- {"dt": "2023-03-08T19:13:09.984073", "lvl": "debug", "msg": "progress  75% (341MB)"}
- {"dt": "2023-03-08T19:13:14.785562", "lvl": "debug", "msg": "progress  80% (363MB)"}
- {"dt": "2023-03-08T19:13:19.105681", "lvl": "debug", "msg": "progress  85% (386MB)"}
- {"dt": "2023-03-08T19:13:22.308125", "lvl": "debug", "msg": "progress  90% (409MB)"}
- {"dt": "2023-03-08T19:13:25.478596", "lvl": "debug", "msg": "progress  95% (432MB)"}
- {"dt": "2023-03-08T19:13:32.294310", "lvl": "debug", "msg": "progress 100% (454MB)"}
- {"dt": "2023-03-08T19:13:34.543682", "lvl": "info", "msg": "454MB downloaded in 89.19s (5.10MB/s)"}
- {"dt": "2023-03-08T19:13:34.544758", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:29) [common]"}
- {"dt": "2023-03-08T19:13:34.545054", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "89.19", "extra": {"label": "rootvd", "md5sum": "8c25c9799ba0db04dea63849643efe0c", "sha256sum": "033b495bfec7a8891a3a7cd9445ce61755803b93c8e295a8660fc7542866acca", "sha512sum": "9b6173e087e7a57d65cf595047c6340ff98580feddd5e687bbb48a4d3c9d0605e0889204eeab81389284f33e05e657d47203a9326d13c0d03e81921dc3ce69e7", "size": ! "476880168"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-08T19:13:34.545761", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:29) [common]"}
- {"dt": "2023-03-08T19:13:34.546086", "lvl": "info", "msg": "end: 1 deployimages (duration 00:01:49) [common]"}
- {"dt": "2023-03-08T19:13:34.546426", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-03-08T19:13:34.546758", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-03-08T19:13:34.547065", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-03-08T19:13:34.547779", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-03-08T19:13:34.548314", "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-621478 -kernel /var/lib/lava/dispatcher/tmp/32177/deployimages-i2c701dv/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/32177/deployimages-i2c701dv/rootvd/agl-demo-platform-qemuarm64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32177/apply-overlay-guest-3h_0yai7/lava-guest.qcow2,media=disk,if=virtio,id=lavatest"}
- {"dt": "2023-03-08T19:13:35.435479", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-03-08T19:13:35.436617", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-03-08T19:13:35.437004", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.89", "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-08T19:13:35.437745", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-03-08T19:13:35.438138", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-03-08T19:13:35.438564", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-08T19:13:35.438907", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-03-08T19:13:35.459407", "lvl": "target", "msg": "qemu-system-aarch64: -soundhw: invalid option"}
- {"dt": "2023-03-08T19:13:35.484867", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-08T19:13:35.485158", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:13:35.485421", "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-08T19:13:35.485895", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-03-08T19:13:35.486187", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-03-08T19:13:35.486432", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-08T19:13:35.486686", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.94", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-08T19:13:35.487245", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-08T19:13:35.487650", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32177/deployimages-i2c701dv/kernel"}
- {"dt": "2023-03-08T19:13:35.492554", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32177/deployimages-i2c701dv/rootvd"}
- {"dt": "2023-03-08T19:13:36.557404", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-08T19:13:36.557873", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:13:36.558176", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-03-08T19:13:36.558810", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-08T19:13:36.559200", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-03-08T19:13:36.660216", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-03-08T19:13:36.660635", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32177"}
- {"dt": "2023-03-08T19:13:36.761653", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-08T19:13:36.761933", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
