- {"dt": "2023-03-02T07:25:12.846367", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-02T07:25:12.848043", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-02T07:25:12.849183", "lvl": "info", "msg": "Start time: 2023-03-02 07:25:12.849140+00:00 (UTC)"}
- {"dt": "2023-03-02T07:25:12.850411", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/initramfs-netboot-image-bbe.ext4.gz exists"}
- {"dt": "2023-03-02T07:25:13.105265", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/zImage exists"}
- {"dt": "2023-03-02T07:25:13.317171", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/am335x-sancloud-bbe.dtb exists"}
- {"dt": "2023-03-02T07:25:13.524618", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/agl-telematics-demo-platform-bbe.ext4.xz exists"}
- {"dt": "2023-03-02T07:25:13.755493", "lvl": "info", "msg": "validate duration: 0.91"}
- {"dt": "2023-03-02T07:25:13.756871", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-02T07:25:13.758785", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T07:25:13.759702", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-03-02T07:25:13.760404", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-03-02T07:25:13.761082", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-03-02T07:25:13.762268", "lvl": "debug", "msg": "Set_port 61964"}
- {"dt": "2023-03-02T07:25:13.763295", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T07:25:13.764284", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-03-02T07:25:13.765673", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:25:13.766442", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/initrd/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:25:13.767209", "lvl": "debug", "msg": "total size: 1677454 (1MB)"}
- {"dt": "2023-03-02T07:25:13.767922", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-02T07:25:14.068658", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-03-02T07:25:14.263689", "lvl": "debug", "msg": "progress   7% (0MB)"}
- {"dt": "2023-03-02T07:25:14.359927", "lvl": "debug", "msg": "progress  13% (0MB)"}
- {"dt": "2023-03-02T07:25:14.369052", "lvl": "debug", "msg": "progress  19% (0MB)"}
- {"dt": "2023-03-02T07:25:14.450047", "lvl": "debug", "msg": "progress  25% (0MB)"}
- {"dt": "2023-03-02T07:25:14.459791", "lvl": "debug", "msg": "progress  31% (0MB)"}
- {"dt": "2023-03-02T07:25:14.471292", "lvl": "debug", "msg": "progress  37% (0MB)"}
- {"dt": "2023-03-02T07:25:14.544021", "lvl": "debug", "msg": "progress  42% (0MB)"}
- {"dt": "2023-03-02T07:25:14.551548", "lvl": "debug", "msg": "progress  48% (0MB)"}
- {"dt": "2023-03-02T07:25:14.557552", "lvl": "debug", "msg": "progress  54% (0MB)"}
- {"dt": "2023-03-02T07:25:14.562094", "lvl": "debug", "msg": "progress  60% (0MB)"}
- {"dt": "2023-03-02T07:25:14.566656", "lvl": "debug", "msg": "progress  66% (1MB)"}
- {"dt": "2023-03-02T07:25:14.571340", "lvl": "debug", "msg": "progress  72% (1MB)"}
- {"dt": "2023-03-02T07:25:14.575973", "lvl": "debug", "msg": "progress  78% (1MB)"}
- {"dt": "2023-03-02T07:25:14.638259", "lvl": "debug", "msg": "progress  83% (1MB)"}
- {"dt": "2023-03-02T07:25:14.649202", "lvl": "debug", "msg": "progress  89% (1MB)"}
- {"dt": "2023-03-02T07:25:14.654786", "lvl": "debug", "msg": "progress  95% (1MB)"}
- {"dt": "2023-03-02T07:25:14.659905", "lvl": "debug", "msg": "progress 100% (1MB)"}
- {"dt": "2023-03-02T07:25:14.661224", "lvl": "info", "msg": "1MB downloaded in 0.89s (1.79MB/s)"}
- {"dt": "2023-03-02T07:25:14.662526", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:25:14.663860", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.90", "extra": {"label": "initrd", "md5sum": "1313960d15f26459638b33960ec6d35e", "sha256sum": "d80af3752b72d95c41372529ebd048f07d7ea28a16be85804a762aa9c41c8a2e", "sha512sum": "08ffe8ce99350db7c338198bea7cd4d2525c66e89a3cd666af3bd072f85e6f088b69df917fbea102a5bc271fa2528832fd4af6af38aa8cbc16139b145b72237a", "size": ! "1677454"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:25:14.665568", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:25:14.666443", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-03-02T07:25:14.667408", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-03-02T07:25:14.668701", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/zImage"}
- {"dt": "2023-03-02T07:25:14.669398", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/kernel/zImage"}
- {"dt": "2023-03-02T07:25:14.669926", "lvl": "debug", "msg": "total size: 4571400 (4MB)"}
- {"dt": "2023-03-02T07:25:14.670543", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-02T07:25:14.973008", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-02T07:25:15.257916", "lvl": "debug", "msg": "progress   5% (0MB)"}
- {"dt": "2023-03-02T07:25:15.361531", "lvl": "debug", "msg": "progress  10% (0MB)"}
- {"dt": "2023-03-02T07:25:15.374095", "lvl": "debug", "msg": "progress  15% (0MB)"}
- {"dt": "2023-03-02T07:25:15.463616", "lvl": "debug", "msg": "progress  20% (0MB)"}
- {"dt": "2023-03-02T07:25:15.472739", "lvl": "debug", "msg": "progress  25% (1MB)"}
- {"dt": "2023-03-02T07:25:15.549791", "lvl": "debug", "msg": "progress  30% (1MB)"}
- {"dt": "2023-03-02T07:25:15.561294", "lvl": "debug", "msg": "progress  35% (1MB)"}
- {"dt": "2023-03-02T07:25:15.570375", "lvl": "debug", "msg": "progress  40% (1MB)"}
- {"dt": "2023-03-02T07:25:15.579505", "lvl": "debug", "msg": "progress  45% (1MB)"}
- {"dt": "2023-03-02T07:25:15.588589", "lvl": "debug", "msg": "progress  50% (2MB)"}
- {"dt": "2023-03-02T07:25:15.737740", "lvl": "debug", "msg": "progress  55% (2MB)"}
- {"dt": "2023-03-02T07:25:15.752740", "lvl": "debug", "msg": "progress  60% (2MB)"}
- {"dt": "2023-03-02T07:25:15.762426", "lvl": "debug", "msg": "progress  65% (2MB)"}
- {"dt": "2023-03-02T07:25:15.771518", "lvl": "debug", "msg": "progress  70% (3MB)"}
- {"dt": "2023-03-02T07:25:15.780554", "lvl": "debug", "msg": "progress  75% (3MB)"}
- {"dt": "2023-03-02T07:25:15.789600", "lvl": "debug", "msg": "progress  80% (3MB)"}
- {"dt": "2023-03-02T07:25:15.798648", "lvl": "debug", "msg": "progress  85% (3MB)"}
- {"dt": "2023-03-02T07:25:15.807784", "lvl": "debug", "msg": "progress  90% (3MB)"}
- {"dt": "2023-03-02T07:25:15.816877", "lvl": "debug", "msg": "progress  95% (4MB)"}
- {"dt": "2023-03-02T07:25:16.550714", "lvl": "debug", "msg": "progress 100% (4MB)"}
- {"dt": "2023-03-02T07:25:16.553800", "lvl": "info", "msg": "4MB downloaded in 1.88s (2.31MB/s)"}
- {"dt": "2023-03-02T07:25:16.556361", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:25:16.557795", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.89", "extra": {"label": "kernel", "md5sum": "23aa58d022b4cb2747ba3ec847471a60", "sha256sum": "6fc394d1b0e56cb4081c389bdfe7d46f14f8a3ea3fc10e9bb3a0697705db8dc7", "sha512sum": "26bcdbbcd24d7f4645e3d63034ec4ff3ba28e98a6bae966012cc736eefe8d3e5ee2df1d4488431d8af7a12ce2806fd86d80f3d37af27e78da4a05d92f9695b29", "size": ! "4571400"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:25:16.561173", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:25:16.562833", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T07:25:16.563877", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T07:25:16.565091", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/am335x-sancloud-bbe.dtb"}
- {"dt": "2023-03-02T07:25:16.565855", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/dtb/am335x-sancloud-bbe.dtb"}
- {"dt": "2023-03-02T07:25:16.566464", "lvl": "debug", "msg": "total size: 93513 (0MB)"}
- {"dt": "2023-03-02T07:25:16.567130", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-02T07:25:16.872129", "lvl": "debug", "msg": "progress  35% (0MB)"}
- {"dt": "2023-03-02T07:25:16.969319", "lvl": "debug", "msg": "progress  70% (0MB)"}
- {"dt": "2023-03-02T07:25:16.974498", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-03-02T07:25:16.979270", "lvl": "info", "msg": "0MB downloaded in 0.41s (0.22MB/s)"}
- {"dt": "2023-03-02T07:25:16.982010", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:25:16.983453", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.42", "extra": {"label": "dtb", "md5sum": "a750fd04e6952e022ccb214b1ce52d5d", "sha256sum": "da338a9ca99d5c9d3d8a8a9a394c3e23604018ea7da64b8017004785ffe6c9db", "sha512sum": "cb4cbc0aab541d48c1ca95ae7d100c64fb29b78de6032a8552eb292568d9459d7f247676dd795e26e6c48da46d904a300dde908eab311c1ffec09fe6e77b6435", "size": ! "93513"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:25:16.984899", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:25:16.985699", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T07:25:16.986506", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:57) [common]"}
- {"dt": "2023-03-02T07:25:16.987680", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/agl-telematics-demo-platform-bbe.ext4.xz"}
- {"dt": "2023-03-02T07:25:16.988388", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/nbdroot/agl-telematics-demo-platform-bbe.ext4"}
- {"dt": "2023-03-02T07:25:16.988951", "lvl": "debug", "msg": "total size: 242811716 (231MB)"}
- {"dt": "2023-03-02T07:25:16.989504", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-02T07:25:17.312382", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-02T07:25:20.571503", "lvl": "debug", "msg": "progress   5% (11MB)"}
- {"dt": "2023-03-02T07:25:23.255358", "lvl": "debug", "msg": "progress  10% (23MB)"}
- {"dt": "2023-03-02T07:25:26.519306", "lvl": "debug", "msg": "progress  15% (34MB)"}
- {"dt": "2023-03-02T07:25:29.845388", "lvl": "debug", "msg": "progress  20% (46MB)"}
- {"dt": "2023-03-02T07:25:33.111349", "lvl": "debug", "msg": "progress  25% (57MB)"}
- {"dt": "2023-03-02T07:25:36.283440", "lvl": "debug", "msg": "progress  30% (69MB)"}
- {"dt": "2023-03-02T07:25:38.987464", "lvl": "debug", "msg": "progress  35% (81MB)"}
- {"dt": "2023-03-02T07:25:42.655271", "lvl": "debug", "msg": "progress  40% (92MB)"}
- {"dt": "2023-03-02T07:25:46.207469", "lvl": "debug", "msg": "progress  45% (104MB)"}
- {"dt": "2023-03-02T07:25:49.711152", "lvl": "debug", "msg": "progress  50% (115MB)"}
- {"dt": "2023-03-02T07:25:53.399444", "lvl": "debug", "msg": "progress  55% (127MB)"}
- {"dt": "2023-03-02T07:25:56.360381", "lvl": "debug", "msg": "progress  60% (138MB)"}
- {"dt": "2023-03-02T07:25:58.827475", "lvl": "debug", "msg": "progress  65% (150MB)"}
- {"dt": "2023-03-02T07:26:01.723833", "lvl": "debug", "msg": "progress  70% (162MB)"}
- {"dt": "2023-03-02T07:26:05.238521", "lvl": "debug", "msg": "progress  75% (173MB)"}
- {"dt": "2023-03-02T07:26:08.601231", "lvl": "debug", "msg": "progress  80% (185MB)"}
- {"dt": "2023-03-02T07:26:11.875872", "lvl": "debug", "msg": "progress  85% (196MB)"}
- {"dt": "2023-03-02T07:26:14.445679", "lvl": "debug", "msg": "progress  90% (208MB)"}
- {"dt": "2023-03-02T07:26:17.301012", "lvl": "debug", "msg": "progress  95% (220MB)"}
- {"dt": "2023-03-02T07:26:22.703169", "lvl": "debug", "msg": "progress 100% (231MB)"}
- {"dt": "2023-03-02T07:26:25.918433", "lvl": "info", "msg": "231MB downloaded in 68.93s (3.36MB/s)"}
- {"dt": "2023-03-02T07:26:25.920384", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:09) [common]"}
- {"dt": "2023-03-02T07:26:25.921161", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "68.93", "extra": {"label": "nbdroot", "md5sum": "6f48d1f60bf6e046855125d40a257bc8", "sha256sum": "92d39e589ab6df3d4a536ea7eee6c1ecf05e5146fcba4e527997eb43d12221cb", "sha512sum": "66457ab7ff74d4979c479515c20517ddde3913441bca7d04b77fe0531e2a1d7dd6987d4920fc8ad2ecbd5133a1f406c342837ed36ff7026004674b11315a100e", "size": ! "242811716"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:25.923372", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:09) [common]"}
- {"dt": "2023-03-02T07:26:25.924313", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:28:48) [common]"}
- {"dt": "2023-03-02T07:26:25.925926", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc"}
- {"dt": "2023-03-02T07:26:25.927246", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin"}
- {"dt": "2023-03-02T07:26:25.928357", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/tests"}
- {"dt": "2023-03-02T07:26:25.929759", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/results"}
- {"dt": "2023-03-02T07:26:25.930850", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-add-keys"}
- {"dt": "2023-03-02T07:26:25.934273", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-add-sources"}
- {"dt": "2023-03-02T07:26:25.936482", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-background-process-start"}
- {"dt": "2023-03-02T07:26:25.938332", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-background-process-stop"}
- {"dt": "2023-03-02T07:26:25.940161", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-common-functions"}
- {"dt": "2023-03-02T07:26:25.941873", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-echo-ipv4"}
- {"dt": "2023-03-02T07:26:25.944000", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-install-packages"}
- {"dt": "2023-03-02T07:26:25.945769", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-installed-packages"}
- {"dt": "2023-03-02T07:26:25.947589", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-os-build"}
- {"dt": "2023-03-02T07:26:25.949311", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-probe-channel"}
- {"dt": "2023-03-02T07:26:25.951369", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-probe-ip"}
- {"dt": "2023-03-02T07:26:25.953151", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-target-ip"}
- {"dt": "2023-03-02T07:26:25.954860", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-target-mac"}
- {"dt": "2023-03-02T07:26:25.956565", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-target-storage"}
- {"dt": "2023-03-02T07:26:25.958254", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-case"}
- {"dt": "2023-03-02T07:26:25.960254", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-event"}
- {"dt": "2023-03-02T07:26:25.961996", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-feedback"}
- {"dt": "2023-03-02T07:26:25.963754", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-raise"}
- {"dt": "2023-03-02T07:26:25.965491", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-reference"}
- {"dt": "2023-03-02T07:26:25.967706", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-runner"}
- {"dt": "2023-03-02T07:26:25.969437", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-set"}
- {"dt": "2023-03-02T07:26:25.971144", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-shell"}
- {"dt": "2023-03-02T07:26:25.972841", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-02T07:26:25.975106", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-02T07:26:25.976833", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/environment"}
- {"dt": "2023-03-02T07:26:25.977975", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-02T07:26:25.978736", "lvl": "debug", "msg": "- LAVA_JOB_ID=32055"}
- {"dt": "2023-03-02T07:26:25.979473", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-03-02T07:26:25.980536", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:28:48) [common]"}
- {"dt": "2023-03-02T07:26:25.983468", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:25.984437", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:28:48) [common]"}
- {"dt": "2023-03-02T07:26:25.985101", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-02T07:26:25.985818", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:25.986639", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:28:48) [common]"}
- {"dt": "2023-03-02T07:26:25.987342", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-02T07:26:25.988076", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:25.988865", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:28:48) [common]"}
- {"dt": "2023-03-02T07:26:25.989979", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-02T07:26:25.991164", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:28:48) [common]"}
- {"dt": "2023-03-02T07:26:25.991954", "lvl": "debug", "msg": "Using /lava-32055 at stage 0"}
- {"dt": "2023-03-02T07:26:25.992968", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:26:25.993847", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/0/tests/0_network-veth'"}
- {"dt": "2023-03-02T07:26:29.385976", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-03-02T07:26:29.390041", "lvl": "debug", "msg": "uuid=32055_1.5.4.1 testdef=None"}
- {"dt": "2023-03-02T07:26:29.391340", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:26:29.392189", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "3.40", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:29.393781", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:28:44) [common]"}
- {"dt": "2023-03-02T07:26:29.399139", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:29.399936", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32055_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:29.401669", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:28:44) [common]"}
- {"dt": "2023-03-02T07:26:29.408651", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:29.409434", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32055_1.5.4.1", "uuid": "32055_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:29.411187", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:28:44) [common]"}
- {"dt": "2023-03-02T07:26:29.417929", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/0/tests/0_network-veth test_uuid 32055_1.5.4.1"}
- {"dt": "2023-03-02T07:26:29.419752", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:29.420555", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/0/tests/0_network-veth/run.sh", "from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32055_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:29.422236", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:28:44) [common]"}
- {"dt": "2023-03-02T07:26:29.423119", "lvl": "debug", "msg": "Using /lava-32055 at stage 1"}
- {"dt": "2023-03-02T07:26:29.424256", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:26:29.425130", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/1_ci-busybox'"}
- {"dt": "2023-03-02T07:26:31.075380", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/1_ci-busybox"}
- {"dt": "2023-03-02T07:26:31.090157", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-03-02T07:26:31.094102", "lvl": "debug", "msg": "uuid=32055_1.5.4.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-02T07:26:31.095373", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:26:31.096126", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.67", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.5", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:31.097584", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:28:43) [common]"}
- {"dt": "2023-03-02T07:26:31.104013", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:31.104697", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32055_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:31.106202", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:28:43) [common]"}
- {"dt": "2023-03-02T07:26:31.114643", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:31.115426", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "32055_1.5.4.5", "uuid": "32055_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:31.117009", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:28:43) [common]"}
- {"dt": "2023-03-02T07:26:31.124873", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/1_ci-busybox test_uuid 32055_1.5.4.5"}
- {"dt": "2023-03-02T07:26:31.126554", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:31.127364", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/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": "32055_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:31.128971", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:28:43) [common]"}
- {"dt": "2023-03-02T07:26:31.129749", "lvl": "debug", "msg": "Using /lava-32055 at stage 1"}
- {"dt": "2023-03-02T07:26:31.130834", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:26:31.131696", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-03-02T07:26:32.766973", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-03-02T07:26:32.781006", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-03-02T07:26:32.784536", "lvl": "debug", "msg": "uuid=32055_1.5.4.9 testdef=None"}
- {"dt": "2023-03-02T07:26:32.785647", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:26:32.786390", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.66", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.9", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:32.787973", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:28:41) [common]"}
- {"dt": "2023-03-02T07:26:32.793339", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:32.794038", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "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": "32055_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:32.795633", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:28:41) [common]"}
- {"dt": "2023-03-02T07:26:32.803415", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:32.804139", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "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": "32055_1.5.4.9", "uuid": "32055_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:32.805731", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:28:41) [common]"}
- {"dt": "2023-03-02T07:26:32.812634", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/2_ci-smoke-tests-basic test_uuid 32055_1.5.4.9"}
- {"dt": "2023-03-02T07:26:32.815429", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:32.816177", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/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": "32055_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:32.817809", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:28:41) [common]"}
- {"dt": "2023-03-02T07:26:32.818566", "lvl": "debug", "msg": "Using /lava-32055 at stage 1"}
- {"dt": "2023-03-02T07:26:32.819696", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:26:32.820507", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/3_ci-service-check'"}
- {"dt": "2023-03-02T07:26:34.442097", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/3_ci-service-check"}
- {"dt": "2023-03-02T07:26:34.455042", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-03-02T07:26:34.459092", "lvl": "debug", "msg": "uuid=32055_1.5.4.13 testdef=None"}
- {"dt": "2023-03-02T07:26:34.460166", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:26:34.460815", "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.5.4.13", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:34.462174", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:28:39) [common]"}
- {"dt": "2023-03-02T07:26:34.468087", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:34.468756", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32055_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:34.470202", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:28:39) [common]"}
- {"dt": "2023-03-02T07:26:34.477656", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:34.478347", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32055_1.5.4.13", "uuid": "32055_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:34.480298", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:28:39) [common]"}
- {"dt": "2023-03-02T07:26:34.488151", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/3_ci-service-check test_uuid 32055_1.5.4.13"}
- {"dt": "2023-03-02T07:26:34.488907", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-03-02T07:26:34.489384", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2023-03-02T07:26:34.490683", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:34.491474", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/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": "32055_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:34.492974", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:28:39) [common]"}
- {"dt": "2023-03-02T07:26:34.493596", "lvl": "debug", "msg": "Using /lava-32055 at stage 2"}
- {"dt": "2023-03-02T07:26:34.494474", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-03-02T07:26:34.495227", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal'"}
- {"dt": "2023-03-02T07:26:36.198949", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal"}
- {"dt": "2023-03-02T07:26:36.213076", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-03-02T07:26:36.216381", "lvl": "debug", "msg": "uuid=32055_1.5.4.17 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-03-02T07:26:36.217523", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:02) [common]"}
- {"dt": "2023-03-02T07:26:36.218292", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.72", "extra": {"commit": "d2778b4dc0a650344162490a3b62c8258fd1c7c0", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:36.219848", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:28:38) [common]"}
- {"dt": "2023-03-02T07:26:36.226049", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.226761", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32055_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:36.228416", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:28:38) [common]"}
- {"dt": "2023-03-02T07:26:36.235984", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.236705", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "32055_1.5.4.17", "uuid": "32055_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:36.238297", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:28:38) [common]"}
- {"dt": "2023-03-02T07:26:36.245635", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal test_uuid 32055_1.5.4.17"}
- {"dt": "2023-03-02T07:26:36.247315", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.248043", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal/run.sh", "from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "32055_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:36.249459", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-02T07:26:36.250077", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/0 for stage 0"}
- {"dt": "2023-03-02T07:26:36.251123", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-03-02T07:26:36.252043", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1 for stage 1"}
- {"dt": "2023-03-02T07:26:36.253077", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-03-02T07:26:36.253752", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-03-02T07:26:36.254330", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-03-02T07:26:36.255109", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2 for stage 2"}
- {"dt": "2023-03-02T07:26:36.256133", "lvl": "debug", "msg": "- 4_ci-dumpjournal"}
- {"dt": "2023-03-02T07:26:36.257190", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:10) [common]"}
- {"dt": "2023-03-02T07:26:36.258060", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:28:38) [common]"}
- {"dt": "2023-03-02T07:26:36.825138", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-03-02T07:26:36.826111", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:28:37) [common]"}
- {"dt": "2023-03-02T07:26:36.827013", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.827936", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:11) [common]"}
- {"dt": "2023-03-02T07:26:36.828779", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:28:37) [common]"}
- {"dt": "2023-03-02T07:26:36.829604", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:28:37) [common]"}
- {"dt": "2023-03-02T07:26:36.830476", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.831358", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.832230", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:28:37) [common]"}
- {"dt": "2023-03-02T07:26:36.832919", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-03-02T07:26:36.833583", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61964, NBD-ROOT: 32055/nbd-deploy-6piwujal/nbdroot/agl-telematics-demo-platform-bbe.ext4"}
- {"dt": "2023-03-02T07:26:36.837177", "lvl": "debug", "msg": "nbd-server 61964 /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/nbdroot/agl-telematics-demo-platform-bbe.ext4"}
- {"dt": "2023-03-02T07:26:36.875506", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-02T07:26:36.878216", "lvl": "debug", "msg": "output: ** (process:337): WARNING **: 07:26:36.873: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-03-02T07:26:36.879348", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-02T07:26:36.880333", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-03-02T07:26:36.882727", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.884142", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:01:23) [common]"}
- {"dt": "2023-03-02T07:26:36.885251", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T07:26:36.886355", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T07:26:36.887319", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.888233", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T07:26:36.889565", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-03-02T07:26:36.890282", "lvl": "debug", "msg": "- {BOOTX}: bootz 0x82000000 0x83000000:${initrd_size} 0x88000000"}
- {"dt": "2023-03-02T07:26:36.891095", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x88000000"}
- {"dt": "2023-03-02T07:26:36.891700", "lvl": "debug", "msg": "- {DTB}: 32055/nbd-deploy-6piwujal/dtb/am335x-sancloud-bbe.dtb"}
- {"dt": "2023-03-02T07:26:36.892252", "lvl": "debug", "msg": "- {INITRD}: 32055/nbd-deploy-6piwujal/initrd/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:26:36.892870", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x82000000"}
- {"dt": "2023-03-02T07:26:36.893454", "lvl": "debug", "msg": "- {KERNEL}: 32055/nbd-deploy-6piwujal/kernel/zImage"}
- {"dt": "2023-03-02T07:26:36.894033", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-03-02T07:26:36.894584", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-03-02T07:26:36.895216", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61964"}
- {"dt": "2023-03-02T07:26:36.895787", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-03-02T07:26:36.896414", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-03-02T07:26:36.897057", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x83000000"}
- {"dt": "2023-03-02T07:26:36.897558", "lvl": "debug", "msg": "- {RAMDISK}: 32055/nbd-deploy-6piwujal/initrd/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:26:36.898049", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-03-02T07:26:36.898517", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-03-02T07:26:36.899750", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-03-02T07:26:36.900392", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-03-02T07:26:36.901001", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-03-02T07:26:36.901588", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-03-02T07:26:36.902284", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-03-02T07:26:36.902960", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-02T07:26:36.903514", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-02T07:26:36.904003", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-03-02T07:26:36.904561", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-03-02T07:26:36.905152", "lvl": "info", "msg": "- tftp 0x82000000 32055/nbd-deploy-6piwujal/kernel/zImage"}
- {"dt": "2023-03-02T07:26:36.905750", "lvl": "info", "msg": "- tftp 0x83000000 32055/nbd-deploy-6piwujal/initrd/initramfs-netboot-image-bbe.ext4.gz"}
- {"dt": "2023-03-02T07:26:36.906314", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-03-02T07:26:36.906858", "lvl": "info", "msg": "- tftp 0x88000000 32055/nbd-deploy-6piwujal/dtb/am335x-sancloud-bbe.dtb"}
- {"dt": "2023-03-02T07:26:36.907467", "lvl": "info", "msg": "- setenv bootargs 'console=ttyO0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61964 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-03-02T07:26:36.908083", "lvl": "info", "msg": "- bootz 0x82000000 0x83000000:${initrd_size} 0x88000000"}
- {"dt": "2023-03-02T07:26:36.908820", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-02T07:26:36.909517", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x88000000", "kernel_addr": "0x82000000", "ramdisk_addr": "0x83000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-02T07:26:36.911253", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-03-02T07:26:36.911993", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63005'"}
- {"dt": "2023-03-02T07:26:40.073517", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-03-02T07:26:40.075746", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:26:40.076804", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-03-02T07:26:40.080996", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-03-02T07:26:40.082486", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-03-02T07:26:40.084137", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '4'"}
- {"dt": "2023-03-02T07:26:43.230860", "lvl": "debug", "msg": ">> /usr/bin/nice: \u2018/usr/local/bin/acme-cli\u2019: No such file or directory\r"}
- {"dt": "2023-03-02T07:26:43.233989", "lvl": "debug", "msg": "Returned 127 in 3 seconds"}
- {"dt": "2023-03-02T07:26:43.234696", "lvl": "error", "msg": "Unable to run 'nice' '['/usr/local/bin/acme-cli', '-s', '192.168.66.2', 'reset', '4']'"}
- {"dt": "2023-03-02T07:26:43.235723", "lvl": "exception", "msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}
- {"dt": "2023-03-02T07:26:43.236508", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:26:43.237287", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "3.15", "extra": {"fail": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}, "level": "2.4.1.1", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:26:43.238571", "lvl": "exception", "msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}
- {"dt": "2023-03-02T07:26:43.239444", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:26:43.240358", "lvl": "results", "msg": {"case": "reset-device", "definition": "lava", "duration": "3.16", "extra": {"fail": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}, "level": "2.4.1", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:26:43.241917", "lvl": "exception", "msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}
- {"dt": "2023-03-02T07:26:43.242838", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:26:43.243675", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "3.17", "extra": {"fail": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:26:43.244989", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed'"}
- {"dt": "2023-03-02T07:26:43.245918", "lvl": "exception", "msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}
- {"dt": "2023-03-02T07:26:43.246646", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:06) [common]"}
- {"dt": "2023-03-02T07:26:43.247442", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "6.36", "extra": {"fail": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:26:43.248998", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-02T07:26:43.249850", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/initrd"}
- {"dt": "2023-03-02T07:26:43.251492", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/kernel"}
- {"dt": "2023-03-02T07:26:43.253173", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/dtb"}
- {"dt": "2023-03-02T07:26:43.254614", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/nbdroot"}
- {"dt": "2023-03-02T07:26:43.540500", "lvl": "debug", "msg": "start: 6.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-02T07:26:43.541947", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '4'"}
- {"dt": "2023-03-02T07:26:46.666744", "lvl": "debug", "msg": ">> /usr/bin/nice: \u2018/usr/local/bin/acme-cli\u2019: No such file or directory\r"}
- {"dt": "2023-03-02T07:26:46.670265", "lvl": "debug", "msg": "Returned 127 in 3 seconds"}
- {"dt": "2023-03-02T07:26:46.671166", "lvl": "error", "msg": "Unable to run 'nice' '['/usr/local/bin/acme-cli', '-s', '192.168.66.2', 'switch_off', '4']'"}
- {"dt": "2023-03-02T07:26:46.671990", "lvl": "exception", "msg": "Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed"}
- {"dt": "2023-03-02T07:26:46.672766", "lvl": "debug", "msg": "end: 6.1 power-off (duration 00:00:03) [common]"}
- {"dt": "2023-03-02T07:26:46.673920", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.13", "extra": {"fail": "Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed"}, "level": "6.1", "namespace": "common", "result": "fail"}}
- {"dt": "2023-03-02T07:26:46.676863", "lvl": "error", "msg": "Failed to run 'finalize': Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed"}
- {"dt": "2023-03-02T07:26:46.682801", "lvl": "exception", "msg": "Traceback (most recent call last):\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/job.py\", line 252, in run\n    self._run()\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/job.py\", line 242, in _run\n    self.pipeline.run_actions(self.connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/logical.py\", line 68, in run\n    connection = self.pipeline.run_actions(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 825, in run\n    return self.pipeline.run_actions(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 825, in run\n    return self.pipeline.run_actions(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/power.py\", line 119, in run\n    self.run_cmd(cmd, error_msg=\"Unable to reboot: '%s' failed\" % cmd)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 695, in run_cmd\n    raise self.command_exception(error_msg)\nlava_common.exceptions.InfrastructureError: Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed\n\nDuring handling of the above exception, another exception occurred:\n\nTraceback (most recent call last):\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/power.py\", line 351, in run\n    connection = super().run(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 825, in run\n    return self.pipeline.run_actions(connection, max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 262, in run_actions\n    new_connection = action.run(connection, action_max_end_time)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/power.py\", line 244, in run\n    self.run_cmd(cmd, error_msg=\"Unable to power-off: '%s' failed\" % cmd)\n  File \"/usr/lib/python3/dist-packages/lava_dispatcher/action.py\", line 695, in run_cmd\n    raise self.command_exception(error_msg)\nlava_common.exceptions.InfrastructureError: Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed\n"}
- {"dt": "2023-03-02T07:26:46.785400", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-03-02T07:26:46.787415", "lvl": "debug", "msg": "clean NBD port 61964"}
- {"dt": "2023-03-02T07:26:50.028760", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/32055"}
- {"dt": "2023-03-02T07:26:50.108950", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32055"}
- {"dt": "2023-03-02T07:26:50.110176", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-03-02T07:26:50.110933", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed", "error_type": "Infrastructure", "result": "fail"}}
