- {"dt": "2023-04-11T16:12:40.077088", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-04-11T16:12:40.077569", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-04-11T16:12:40.077897", "lvl": "info", "msg": "Start time: 2023-04-11 16:12:40.077874+00:00 (UTC)"}
- {"dt": "2023-04-11T16:12:40.081549", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28635/1/qemuarm64/Image exists"}
- {"dt": "2023-04-11T16:12:40.286464", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28635/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz exists"}
- {"dt": "2023-04-11T16:12:40.514520", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.2+dfsg-1~bpo11+2, host architecture: amd64"}
- {"dt": "2023-04-11T16:12:40.516024", "lvl": "info", "msg": "validate duration: 0.44"}
- {"dt": "2023-04-11T16:12:40.516302", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-04-11T16:12:40.516797", "lvl": "info", "msg": "start: 1 deployimages (timeout 01:30:00) [common]"}
- {"dt": "2023-04-11T16:12:40.517103", "lvl": "debug", "msg": "start: 1.1 lava-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-04-11T16:12:40.517618", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet"}
- {"dt": "2023-04-11T16:12:40.518021", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin"}
- {"dt": "2023-04-11T16:12:40.518361", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/tests"}
- {"dt": "2023-04-11T16:12:40.518676", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/results"}
- {"dt": "2023-04-11T16:12:40.519023", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-add-keys"}
- {"dt": "2023-04-11T16:12:40.519466", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-add-sources"}
- {"dt": "2023-04-11T16:12:40.519885", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-background-process-start"}
- {"dt": "2023-04-11T16:12:40.520309", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-background-process-stop"}
- {"dt": "2023-04-11T16:12:40.520734", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-common-functions"}
- {"dt": "2023-04-11T16:12:40.521145", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-echo-ipv4"}
- {"dt": "2023-04-11T16:12:40.521555", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-install-packages"}
- {"dt": "2023-04-11T16:12:40.521958", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-installed-packages"}
- {"dt": "2023-04-11T16:12:40.522367", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-os-build"}
- {"dt": "2023-04-11T16:12:40.522801", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-probe-channel"}
- {"dt": "2023-04-11T16:12:40.523223", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-probe-ip"}
- {"dt": "2023-04-11T16:12:40.523626", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-target-ip"}
- {"dt": "2023-04-11T16:12:40.524026", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-target-mac"}
- {"dt": "2023-04-11T16:12:40.524426", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-target-storage"}
- {"dt": "2023-04-11T16:12:40.524845", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-test-case"}
- {"dt": "2023-04-11T16:12:40.525243", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-test-event"}
- {"dt": "2023-04-11T16:12:40.525642", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-test-feedback"}
- {"dt": "2023-04-11T16:12:40.526037", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-test-raise"}
- {"dt": "2023-04-11T16:12:40.526446", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-test-reference"}
- {"dt": "2023-04-11T16:12:40.526851", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-test-runner"}
- {"dt": "2023-04-11T16:12:40.527251", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-test-set"}
- {"dt": "2023-04-11T16:12:40.527651", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-test-shell"}
- {"dt": "2023-04-11T16:12:40.528057", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-install-packages (oe)"}
- {"dt": "2023-04-11T16:12:40.528545", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/bin/lava-installed-packages (oe)"}
- {"dt": "2023-04-11T16:12:40.528939", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/environment"}
- {"dt": "2023-04-11T16:12:40.529263", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-04-11T16:12:40.529507", "lvl": "debug", "msg": "- LAVA_JOB_ID=32839"}
- {"dt": "2023-04-11T16:12:40.529730", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=172.23.0.2"}
- {"dt": "2023-04-11T16:12:40.530047", "lvl": "debug", "msg": "start: 1.1.1 lava-vland-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-04-11T16:12:40.530275", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-04-11T16:12:40.530537", "lvl": "debug", "msg": "end: 1.1.1 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:40.530826", "lvl": "debug", "msg": "start: 1.1.2 lava-multinode-overlay (timeout 01:30:00) [common]"}
- {"dt": "2023-04-11T16:12:40.531044", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-04-11T16:12:40.531295", "lvl": "debug", "msg": "end: 1.1.2 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:40.531559", "lvl": "debug", "msg": "start: 1.1.3 test-definition (timeout 01:30:00) [common]"}
- {"dt": "2023-04-11T16:12:40.531936", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-04-11T16:12:40.532248", "lvl": "debug", "msg": "start: 1.1.3.1 git-repo-action (timeout 01:30:00) [common]"}
- {"dt": "2023-04-11T16:12:40.532515", "lvl": "debug", "msg": "Using /lava-32839 at stage 0"}
- {"dt": "2023-04-11T16:12:40.532851", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-04-11T16:12:40.533127", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/0_ci-busybox'"}
- {"dt": "2023-04-11T16:12:41.938833", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/0_ci-busybox"}
- {"dt": "2023-04-11T16:12:41.942347", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/0_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-04-11T16:12:41.943582", "lvl": "debug", "msg": "uuid=32839_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-04-11T16:12:41.943989", "lvl": "debug", "msg": "end: 1.1.3.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:12:41.944254", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.41", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "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-04-11T16:12:41.944831", "lvl": "debug", "msg": "start: 1.1.3.2 test-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-04-11T16:12:41.946933", "lvl": "debug", "msg": "end: 1.1.3.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:41.947180", "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": "32839_1.1.3.1"}, "level": "1.1.3.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:41.947762", "lvl": "debug", "msg": "start: 1.1.3.3 test-install-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-04-11T16:12:41.950480", "lvl": "debug", "msg": "end: 1.1.3.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:41.950745", "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": "32839_1.1.3.1", "uuid": "32839_1.1.3.1"}, "level": "1.1.3.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:41.951361", "lvl": "debug", "msg": "start: 1.1.3.4 test-runscript-overlay (timeout 01:29:59) [common]"}
- {"dt": "2023-04-11T16:12:41.954024", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/0_ci-busybox test_uuid 32839_1.1.3.1"}
- {"dt": "2023-04-11T16:12:41.954539", "lvl": "debug", "msg": "end: 1.1.3.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:41.954795", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/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": "32839_1.1.3.1"}, "level": "1.1.3.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:41.955408", "lvl": "debug", "msg": "start: 1.1.3.5 git-repo-action (timeout 01:29:59) [common]"}
- {"dt": "2023-04-11T16:12:41.955664", "lvl": "debug", "msg": "Using /lava-32839 at stage 0"}
- {"dt": "2023-04-11T16:12:41.956015", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-04-11T16:12:41.956282", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/1_ci-smoke-tests-basic'"}
- {"dt": "2023-04-11T16:12:43.357346", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/1_ci-smoke-tests-basic"}
- {"dt": "2023-04-11T16:12:43.360796", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/1_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-04-11T16:12:43.361836", "lvl": "debug", "msg": "uuid=32839_1.1.3.5 testdef=None"}
- {"dt": "2023-04-11T16:12:43.362217", "lvl": "debug", "msg": "end: 1.1.3.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:12:43.362482", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.41", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "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-04-11T16:12:43.363042", "lvl": "debug", "msg": "start: 1.1.3.6 test-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-04-11T16:12:43.364883", "lvl": "debug", "msg": "end: 1.1.3.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:43.365132", "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": "32839_1.1.3.5"}, "level": "1.1.3.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:43.365719", "lvl": "debug", "msg": "start: 1.1.3.7 test-install-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-04-11T16:12:43.368082", "lvl": "debug", "msg": "end: 1.1.3.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:43.368342", "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": "32839_1.1.3.5", "uuid": "32839_1.1.3.5"}, "level": "1.1.3.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:43.368975", "lvl": "debug", "msg": "start: 1.1.3.8 test-runscript-overlay (timeout 01:29:57) [common]"}
- {"dt": "2023-04-11T16:12:43.371289", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/1_ci-smoke-tests-basic test_uuid 32839_1.1.3.5"}
- {"dt": "2023-04-11T16:12:43.372252", "lvl": "debug", "msg": "end: 1.1.3.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:43.372526", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/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": "32839_1.1.3.5"}, "level": "1.1.3.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:43.373136", "lvl": "debug", "msg": "start: 1.1.3.9 git-repo-action (timeout 01:29:57) [common]"}
- {"dt": "2023-04-11T16:12:43.373394", "lvl": "debug", "msg": "Using /lava-32839 at stage 0"}
- {"dt": "2023-04-11T16:12:43.373754", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-04-11T16:12:43.374022", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/2_ci-service-check'"}
- {"dt": "2023-04-11T16:12:44.791778", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/2_ci-service-check"}
- {"dt": "2023-04-11T16:12:44.795200", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/2_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-04-11T16:12:44.796276", "lvl": "debug", "msg": "uuid=32839_1.1.3.9 testdef=None"}
- {"dt": "2023-04-11T16:12:44.796667", "lvl": "debug", "msg": "end: 1.1.3.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:12:44.796926", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.42", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "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-04-11T16:12:44.797498", "lvl": "debug", "msg": "start: 1.1.3.10 test-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-04-11T16:12:44.799371", "lvl": "debug", "msg": "end: 1.1.3.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:44.799621", "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": "32839_1.1.3.9"}, "level": "1.1.3.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:44.800200", "lvl": "debug", "msg": "start: 1.1.3.11 test-install-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-04-11T16:12:44.802685", "lvl": "debug", "msg": "end: 1.1.3.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:44.802947", "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": "32839_1.1.3.9", "uuid": "32839_1.1.3.9"}, "level": "1.1.3.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:44.803563", "lvl": "debug", "msg": "start: 1.1.3.12 test-runscript-overlay (timeout 01:29:56) [common]"}
- {"dt": "2023-04-11T16:12:44.806006", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0/tests/2_ci-service-check test_uuid 32839_1.1.3.9"}
- {"dt": "2023-04-11T16:12:44.806281", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-04-11T16:12:44.806507", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-04-11T16:12:44.806961", "lvl": "debug", "msg": "end: 1.1.3.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:44.807203", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/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": "32839_1.1.3.9"}, "level": "1.1.3.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:44.807803", "lvl": "debug", "msg": "start: 1.1.3.13 git-repo-action (timeout 01:29:56) [common]"}
- {"dt": "2023-04-11T16:12:44.808055", "lvl": "debug", "msg": "Using /lava-32839 at stage 1"}
- {"dt": "2023-04-11T16:12:44.808410", "lvl": "info", "msg": "Fetching tests from https://git.linaro.org/qa/test-definitions.git"}
- {"dt": "2023-04-11T16:12:44.808689", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.linaro.org/qa/test-definitions.git /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/1/tests/3_ci-linaro-ptest'"}
- {"dt": "2023-04-11T16:12:48.812670", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/1/tests/3_ci-linaro-ptest"}
- {"dt": "2023-04-11T16:12:48.819472", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/1/tests/3_ci-linaro-ptest/automated/linux/ptest/ptest.yaml"}
- {"dt": "2023-04-11T16:12:48.820691", "lvl": "debug", "msg": "uuid=32839_1.1.3.13 testdef=None"}
- {"dt": "2023-04-11T16:12:48.821081", "lvl": "debug", "msg": "end: 1.1.3.13 git-repo-action (duration 00:00:04) [common]"}
- {"dt": "2023-04-11T16:12:48.821343", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "4.01", "extra": {"commit": "ab5e9e5df70c1e79a93332158f982a3283938bba", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git"}, "level": "1.1.3.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:48.821885", "lvl": "debug", "msg": "start: 1.1.3.14 test-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-04-11T16:12:48.823985", "lvl": "debug", "msg": "end: 1.1.3.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:48.824233", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-linaro-ptest", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git", "uuid": "32839_1.1.3.13"}, "level": "1.1.3.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:48.824834", "lvl": "debug", "msg": "start: 1.1.3.15 test-install-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-04-11T16:12:48.827602", "lvl": "debug", "msg": "end: 1.1.3.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:48.827854", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-linaro-ptest", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git", "skipped test-install-overlay": "32839_1.1.3.13", "uuid": "32839_1.1.3.13"}, "level": "1.1.3.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:48.828469", "lvl": "debug", "msg": "start: 1.1.3.16 test-runscript-overlay (timeout 01:29:52) [common]"}
- {"dt": "2023-04-11T16:12:48.831143", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/1/tests/3_ci-linaro-ptest test_uuid 32839_1.1.3.13"}
- {"dt": "2023-04-11T16:12:48.831416", "lvl": "debug", "msg": "TESTS='xmlsec1 libxml2 lua'"}
- {"dt": "2023-04-11T16:12:48.831887", "lvl": "debug", "msg": "end: 1.1.3.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:48.832127", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/1/tests/3_ci-linaro-ptest/run.sh", "from": "git", "name": "ci-linaro-ptest", "path": "automated/linux/ptest/ptest.yaml", "repository": "https://git.linaro.org/qa/test-definitions.git", "uuid": "32839_1.1.3.13"}, "level": "1.1.3.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:48.832731", "lvl": "debug", "msg": "start: 1.1.3.17 git-repo-action (timeout 01:29:52) [common]"}
- {"dt": "2023-04-11T16:12:48.832983", "lvl": "debug", "msg": "Using /lava-32839 at stage 2"}
- {"dt": "2023-04-11T16:12:48.833334", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-04-11T16:12:48.833596", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/2/tests/4_pyagl-tests'"}
- {"dt": "2023-04-11T16:12:50.265935", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/2/tests/4_pyagl-tests/test-suites/short-smoke/pyagl-nohw-oneshot.yaml"}
- {"dt": "2023-04-11T16:12:50.267343", "lvl": "debug", "msg": "uuid=32839_1.1.3.17 testdef=None"}
- {"dt": "2023-04-11T16:12:50.267747", "lvl": "debug", "msg": "end: 1.1.3.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:12:50.268011", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.44", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:50.268605", "lvl": "debug", "msg": "start: 1.1.3.18 test-overlay (timeout 01:29:50) [common]"}
- {"dt": "2023-04-11T16:12:50.270251", "lvl": "debug", "msg": "end: 1.1.3.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:50.270505", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "pyagl-tests", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32839_1.1.3.17"}, "level": "1.1.3.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:50.271088", "lvl": "debug", "msg": "start: 1.1.3.19 test-install-overlay (timeout 01:29:50) [common]"}
- {"dt": "2023-04-11T16:12:50.273166", "lvl": "debug", "msg": "end: 1.1.3.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:50.273419", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "pyagl-tests", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32839_1.1.3.17", "uuid": "32839_1.1.3.17"}, "level": "1.1.3.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:50.274036", "lvl": "debug", "msg": "start: 1.1.3.20 test-runscript-overlay (timeout 01:29:50) [common]"}
- {"dt": "2023-04-11T16:12:50.276014", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/2/tests/4_pyagl-tests test_uuid 32839_1.1.3.17"}
- {"dt": "2023-04-11T16:12:50.276538", "lvl": "debug", "msg": "end: 1.1.3.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:50.276787", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/2/tests/4_pyagl-tests/run.sh", "from": "git", "name": "pyagl-tests", "path": "test-suites/short-smoke/pyagl-nohw-oneshot.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32839_1.1.3.17"}, "level": "1.1.3.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:50.277395", "lvl": "debug", "msg": "start: 1.1.3.21 git-repo-action (timeout 01:29:50) [common]"}
- {"dt": "2023-04-11T16:12:50.277651", "lvl": "debug", "msg": "Using /lava-32839 at stage 3"}
- {"dt": "2023-04-11T16:12:50.278012", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-04-11T16:12:50.278282", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/3/tests/5_screenshooter'"}
- {"dt": "2023-04-11T16:12:51.736234", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/3/tests/5_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-04-11T16:12:51.737626", "lvl": "debug", "msg": "uuid=32839_1.1.3.21 testdef=None"}
- {"dt": "2023-04-11T16:12:51.738040", "lvl": "debug", "msg": "end: 1.1.3.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:12:51.738305", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.46", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:51.738884", "lvl": "debug", "msg": "start: 1.1.3.22 test-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-04-11T16:12:51.740539", "lvl": "debug", "msg": "end: 1.1.3.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:51.740793", "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": "32839_1.1.3.21"}, "level": "1.1.3.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:51.741382", "lvl": "debug", "msg": "start: 1.1.3.23 test-install-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-04-11T16:12:51.743405", "lvl": "debug", "msg": "end: 1.1.3.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:51.743660", "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": "32839_1.1.3.21", "uuid": "32839_1.1.3.21"}, "level": "1.1.3.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:51.744278", "lvl": "debug", "msg": "start: 1.1.3.24 test-runscript-overlay (timeout 01:29:49) [common]"}
- {"dt": "2023-04-11T16:12:51.746236", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/3/tests/5_screenshooter test_uuid 32839_1.1.3.21"}
- {"dt": "2023-04-11T16:12:51.746743", "lvl": "debug", "msg": "end: 1.1.3.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:51.746991", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/3/tests/5_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32839_1.1.3.21"}, "level": "1.1.3.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:51.747598", "lvl": "debug", "msg": "start: 1.1.3.25 git-repo-action (timeout 01:29:49) [common]"}
- {"dt": "2023-04-11T16:12:51.747853", "lvl": "debug", "msg": "Using /lava-32839 at stage 4"}
- {"dt": "2023-04-11T16:12:51.748210", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-04-11T16:12:51.748490", "lvl": "debug", "msg": "Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/4/tests/6_ci-dumpjournal'"}
- {"dt": "2023-04-11T16:12:53.193318", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/4/tests/6_ci-dumpjournal"}
- {"dt": "2023-04-11T16:12:53.196968", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/4/tests/6_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-04-11T16:12:53.198148", "lvl": "debug", "msg": "uuid=32839_1.1.3.25 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-04-11T16:12:53.198561", "lvl": "debug", "msg": "end: 1.1.3.25 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:12:53.198834", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.45", "extra": {"commit": "75094d614243e04c0751532ec1680352a0c7eb6a", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.1.3.25", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:53.199400", "lvl": "debug", "msg": "start: 1.1.3.26 test-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-04-11T16:12:53.201654", "lvl": "debug", "msg": "end: 1.1.3.26 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:53.201898", "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": "32839_1.1.3.25"}, "level": "1.1.3.26", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:53.202478", "lvl": "debug", "msg": "start: 1.1.3.27 test-install-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-04-11T16:12:53.205092", "lvl": "debug", "msg": "end: 1.1.3.27 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:53.205347", "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": "32839_1.1.3.25", "uuid": "32839_1.1.3.25"}, "level": "1.1.3.27", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:53.205953", "lvl": "debug", "msg": "start: 1.1.3.28 test-runscript-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-04-11T16:12:53.208537", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/4/tests/6_ci-dumpjournal test_uuid 32839_1.1.3.25"}
- {"dt": "2023-04-11T16:12:53.209034", "lvl": "debug", "msg": "end: 1.1.3.28 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:12:53.209280", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/4/tests/6_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": "32839_1.1.3.25"}, "level": "1.1.3.28", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:12:53.209835", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-04-11T16:12:53.210060", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/0 for stage 0"}
- {"dt": "2023-04-11T16:12:53.210365", "lvl": "debug", "msg": "- 0_ci-busybox"}
- {"dt": "2023-04-11T16:12:53.210601", "lvl": "debug", "msg": "- 1_ci-smoke-tests-basic"}
- {"dt": "2023-04-11T16:12:53.210816", "lvl": "debug", "msg": "- 2_ci-service-check"}
- {"dt": "2023-04-11T16:12:53.211064", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/1 for stage 1"}
- {"dt": "2023-04-11T16:12:53.211366", "lvl": "debug", "msg": "- 3_ci-linaro-ptest"}
- {"dt": "2023-04-11T16:12:53.211626", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/2 for stage 2"}
- {"dt": "2023-04-11T16:12:53.211927", "lvl": "debug", "msg": "- 4_pyagl-tests"}
- {"dt": "2023-04-11T16:12:53.212183", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/3 for stage 3"}
- {"dt": "2023-04-11T16:12:53.212496", "lvl": "debug", "msg": "- 5_screenshooter"}
- {"dt": "2023-04-11T16:12:53.212757", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32839/lava-overlay-h_ivmnet/lava-32839/4 for stage 4"}
- {"dt": "2023-04-11T16:12:53.213053", "lvl": "debug", "msg": "- 6_ci-dumpjournal"}
- {"dt": "2023-04-11T16:12:53.213368", "lvl": "debug", "msg": "end: 1.1.3 test-definition (duration 00:00:13) [common]"}
- {"dt": "2023-04-11T16:12:53.213655", "lvl": "debug", "msg": "start: 1.1.4 compress-overlay (timeout 01:29:47) [common]"}
- {"dt": "2023-04-11T16:13:10.516554", "lvl": "debug", "msg": "end: 1.1.4 compress-overlay (duration 00:00:17) [common]"}
- {"dt": "2023-04-11T16:13:10.516964", "lvl": "debug", "msg": "start: 1.1.5 persistent-nfs-overlay (timeout 01:29:30) [common]"}
- {"dt": "2023-04-11T16:13:10.517252", "lvl": "debug", "msg": "end: 1.1.5 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:13:10.517550", "lvl": "debug", "msg": "end: 1.1 lava-overlay (duration 00:00:30) [common]"}
- {"dt": "2023-04-11T16:13:10.517830", "lvl": "debug", "msg": "start: 1.2 apply-overlay-guest (timeout 01:29:30) [common]"}
- {"dt": "2023-04-11T16:13:10.518078", "lvl": "debug", "msg": "Overlay: /var/lib/lava/dispatcher/tmp/32839/compress-overlay-b2v3ohfc/overlay-1.1.4.tar.gz"}
- {"dt": "2023-04-11T16:13:17.506724", "lvl": "debug", "msg": "end: 1.2 apply-overlay-guest (duration 00:00:07) [common]"}
- {"dt": "2023-04-11T16:13:17.507506", "lvl": "results", "msg": {"case": "apply-overlay-guest", "definition": "lava", "duration": "6.99", "extra": {"success": "326d1c80-7afd-4663-a131-2a91eda9e310"}, "level": "1.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:13:17.508070", "lvl": "debug", "msg": "start: 1.3 deploy-device-env (timeout 01:29:23) [common]"}
- {"dt": "2023-04-11T16:13:17.508377", "lvl": "debug", "msg": "end: 1.3 deploy-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:13:17.508698", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:23) [common]"}
- {"dt": "2023-04-11T16:13:17.509013", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:23) [common]"}
- {"dt": "2023-04-11T16:13:17.509531", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28635/1/qemuarm64/Image"}
- {"dt": "2023-04-11T16:13:17.509777", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32839/deployimages-6vykokwi/kernel/Image"}
- {"dt": "2023-04-11T16:13:17.510001", "lvl": "debug", "msg": "total size: 16343552 (15MB)"}
- {"dt": "2023-04-11T16:13:17.510218", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-04-11T16:13:17.808470", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-04-11T16:13:18.285648", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-04-11T16:13:18.573055", "lvl": "debug", "msg": "progress  10% (1MB)"}
- {"dt": "2023-04-11T16:13:18.859175", "lvl": "debug", "msg": "progress  15% (2MB)"}
- {"dt": "2023-04-11T16:13:19.144722", "lvl": "debug", "msg": "progress  20% (3MB)"}
- {"dt": "2023-04-11T16:13:19.425941", "lvl": "debug", "msg": "progress  25% (3MB)"}
- {"dt": "2023-04-11T16:13:19.619985", "lvl": "debug", "msg": "progress  30% (4MB)"}
- {"dt": "2023-04-11T16:13:19.813001", "lvl": "debug", "msg": "progress  35% (5MB)"}
- {"dt": "2023-04-11T16:13:20.004319", "lvl": "debug", "msg": "progress  40% (6MB)"}
- {"dt": "2023-04-11T16:13:20.193540", "lvl": "debug", "msg": "progress  45% (7MB)"}
- {"dt": "2023-04-11T16:13:20.383154", "lvl": "debug", "msg": "progress  50% (7MB)"}
- {"dt": "2023-04-11T16:13:20.570312", "lvl": "debug", "msg": "progress  55% (8MB)"}
- {"dt": "2023-04-11T16:13:20.672854", "lvl": "debug", "msg": "progress  60% (9MB)"}
- {"dt": "2023-04-11T16:13:20.859327", "lvl": "debug", "msg": "progress  65% (10MB)"}
- {"dt": "2023-04-11T16:13:21.038612", "lvl": "debug", "msg": "progress  70% (10MB)"}
- {"dt": "2023-04-11T16:13:21.144482", "lvl": "debug", "msg": "progress  75% (11MB)"}
- {"dt": "2023-04-11T16:13:21.244967", "lvl": "debug", "msg": "progress  80% (12MB)"}
- {"dt": "2023-04-11T16:13:21.422098", "lvl": "debug", "msg": "progress  85% (13MB)"}
- {"dt": "2023-04-11T16:13:21.525251", "lvl": "debug", "msg": "progress  90% (14MB)"}
- {"dt": "2023-04-11T16:13:21.623580", "lvl": "debug", "msg": "progress  95% (14MB)"}
- {"dt": "2023-04-11T16:13:21.721783", "lvl": "debug", "msg": "progress 100% (15MB)"}
- {"dt": "2023-04-11T16:13:21.722413", "lvl": "info", "msg": "15MB downloaded in 4.21s (3.70MB/s)"}
- {"dt": "2023-04-11T16:13:21.722865", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:00:04) [common]"}
- {"dt": "2023-04-11T16:13:21.723119", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "4.21", "extra": {"label": "kernel", "md5sum": "387101518ff260ddc10778b95ac05f15", "sha256sum": "7800ce44b6975fd7a2ed85bddf606a71922f3dc96b0cda728b713ad1c60c9a6d", "sha512sum": "17afc6987d5e680796702c733ff70d837f7e60c0d609b65ff866cc21bb57aacd5353df0d065b0717f28a162ba1d94514dda4c81e8ef7397d9c185703e9df14f0", "size": ! "16343552"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:13:21.723776", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:00:04) [common]"}
- {"dt": "2023-04-11T16:13:21.724072", "lvl": "debug", "msg": "start: 1.5 download-retry (timeout 01:29:19) [common]"}
- {"dt": "2023-04-11T16:13:21.724353", "lvl": "debug", "msg": "start: 1.5.1 http-download (timeout 01:29:19) [common]"}
- {"dt": "2023-04-11T16:13:21.724764", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28635/1/qemuarm64/agl-demo-platform-qemuarm64.ext4.xz"}
- {"dt": "2023-04-11T16:13:21.724995", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32839/deployimages-6vykokwi/rootvd/agl-demo-platform-qemuarm64.ext4"}
- {"dt": "2023-04-11T16:13:21.725210", "lvl": "debug", "msg": "total size: 538385332 (513MB)"}
- {"dt": "2023-04-11T16:13:21.725431", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-04-11T16:13:22.027718", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-04-11T16:13:26.963022", "lvl": "debug", "msg": "progress   5% (25MB)"}
- {"dt": "2023-04-11T16:13:29.914268", "lvl": "debug", "msg": "progress  10% (51MB)"}
- {"dt": "2023-04-11T16:13:34.379897", "lvl": "debug", "msg": "progress  15% (77MB)"}
- {"dt": "2023-04-11T16:13:39.610926", "lvl": "debug", "msg": "progress  20% (102MB)"}
- {"dt": "2023-04-11T16:13:44.015549", "lvl": "debug", "msg": "progress  25% (128MB)"}
- {"dt": "2023-04-11T16:13:48.865613", "lvl": "debug", "msg": "progress  30% (154MB)"}
- {"dt": "2023-04-11T16:13:55.060583", "lvl": "debug", "msg": "progress  35% (179MB)"}
- {"dt": "2023-04-11T16:14:01.988427", "lvl": "debug", "msg": "progress  40% (205MB)"}
- {"dt": "2023-04-11T16:14:08.998101", "lvl": "debug", "msg": "progress  45% (231MB)"}
- {"dt": "2023-04-11T16:14:15.230229", "lvl": "debug", "msg": "progress  50% (256MB)"}
- {"dt": "2023-04-11T16:14:20.476925", "lvl": "debug", "msg": "progress  55% (282MB)"}
- {"dt": "2023-04-11T16:14:24.813179", "lvl": "debug", "msg": "progress  60% (308MB)"}
- {"dt": "2023-04-11T16:14:29.239636", "lvl": "debug", "msg": "progress  65% (333MB)"}
- {"dt": "2023-04-11T16:14:35.002035", "lvl": "debug", "msg": "progress  70% (359MB)"}
- {"dt": "2023-04-11T16:14:40.395795", "lvl": "debug", "msg": "progress  75% (385MB)"}
- {"dt": "2023-04-11T16:14:45.383939", "lvl": "debug", "msg": "progress  80% (410MB)"}
- {"dt": "2023-04-11T16:14:49.201117", "lvl": "debug", "msg": "progress  85% (436MB)"}
- {"dt": "2023-04-11T16:14:52.829396", "lvl": "debug", "msg": "progress  90% (462MB)"}
- {"dt": "2023-04-11T16:14:56.368277", "lvl": "debug", "msg": "progress  95% (487MB)"}
- {"dt": "2023-04-11T16:15:02.489161", "lvl": "debug", "msg": "progress 100% (513MB)"}
- {"dt": "2023-04-11T16:15:04.469572", "lvl": "info", "msg": "513MB downloaded in 102.74s (5.00MB/s)"}
- {"dt": "2023-04-11T16:15:04.470650", "lvl": "debug", "msg": "end: 1.5.1 http-download (duration 00:01:43) [common]"}
- {"dt": "2023-04-11T16:15:04.470948", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "102.75", "extra": {"label": "rootvd", "md5sum": "e7fcecde200e6a5b859fea52c04ea69d", "sha256sum": "9fd8ea78633aa6f102c047be89731454c4002e665af8e53ea30d5d081d756326", "sha512sum": "b516694e61a194e4e898bf144fefdae66dff0172615ed6710c63e4ce1d283b7576bbd7f347baabc7788fb58fc2f77ea401c4edb4b26307c7c05363030c5c9b7b", "size": ! "538385332"}, "level": "1.5.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-04-11T16:15:04.471637", "lvl": "debug", "msg": "end: 1.5 download-retry (duration 00:01:43) [common]"}
- {"dt": "2023-04-11T16:15:04.471963", "lvl": "info", "msg": "end: 1 deployimages (duration 00:02:24) [common]"}
- {"dt": "2023-04-11T16:15:04.472307", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:20:00) [common]"}
- {"dt": "2023-04-11T16:15:04.472648", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:20:00) [common]"}
- {"dt": "2023-04-11T16:15:04.472957", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:20:00) [common]"}
- {"dt": "2023-04-11T16:15:04.473694", "lvl": "info", "msg": "Extending command line for qcow2 test overlay"}
- {"dt": "2023-04-11T16:15:04.474280", "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=64,edid=on -device qemu-xhci -device usb-tablet -device usb-kbd  -vnc unix:/tmp/vnc-AGL-qemuarm64-49895 -kernel /var/lib/lava/dispatcher/tmp/32839/deployimages-6vykokwi/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/32839/deployimages-6vykokwi/rootvd/agl-demo-platform-qemuarm64.ext4 -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/32839/apply-overlay-guest-7f57jisp/lava-guest.qcow2,media=disk,if=virtio,id=lavatest"}
- {"dt": "2023-04-11T16:15:05.365931", "lvl": "debug", "msg": "started a shell command"}
- {"dt": "2023-04-11T16:15:05.367089", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:15:05.367411", "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-04-11T16:15:05.368069", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:15:05.368436", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:19:59) [common]"}
- {"dt": "2023-04-11T16:15:05.368822", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-04-11T16:15:05.369102", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:20:00)"}
- {"dt": "2023-04-11T16:15:05.390489", "lvl": "target", "msg": "qemu-system-aarch64: -soundhw: invalid option"}
- {"dt": "2023-04-11T16:15:05.432878", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-04-11T16:15:05.433177", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:15:05.433452", "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-04-11T16:15:05.433923", "lvl": "error", "msg": "boot-image-retry failed: 1 of 1 attempts. 'Connection closed'"}
- {"dt": "2023-04-11T16:15:05.434224", "lvl": "exception", "msg": "Connection closed"}
- {"dt": "2023-04-11T16:15:05.434469", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:00:01) [common]"}
- {"dt": "2023-04-11T16:15:05.434720", "lvl": "results", "msg": {"case": "boot-image-retry", "definition": "lava", "duration": "0.96", "extra": {"fail": "Connection closed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-04-11T16:15:05.435259", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-04-11T16:15:05.435682", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32839/deployimages-6vykokwi/kernel"}
- {"dt": "2023-04-11T16:15:05.439451", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32839/deployimages-6vykokwi/rootvd"}
- {"dt": "2023-04-11T16:15:05.945226", "lvl": "debug", "msg": "start: 8.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-04-11T16:15:05.945828", "lvl": "debug", "msg": "end: 8.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:15:05.946144", "lvl": "debug", "msg": "start: 8.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2023-04-11T16:15:05.946879", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-04-11T16:15:05.947263", "lvl": "debug", "msg": "Already disconnected"}
- {"dt": "2023-04-11T16:15:06.048269", "lvl": "debug", "msg": "end: 8.2 read-feedback (duration 00:00:00) [common]"}
- {"dt": "2023-04-11T16:15:06.048610", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32839"}
- {"dt": "2023-04-11T16:15:06.151389", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-04-11T16:15:06.151727", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Connection closed", "error_type": "Infrastructure", "result": "fail"}}
