- {"dt": "2023-11-25T00:17:27.959396", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-11-25T00:17:27.960795", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-11-25T00:17:27.961811", "lvl": "info", "msg": "Start time: 2023-11-25 00:17:27.961753+00:00 (UTC)"}
- {"dt": "2023-11-25T00:17:27.962945", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29407/4/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-11-25T00:17:28.186029", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29407/4/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-11-25T00:17:28.397502", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29407/4/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-11-25T00:17:28.612259", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29407/4/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-11-25T00:17:28.842469", "lvl": "info", "msg": "validate duration: 0.88"}
- {"dt": "2023-11-25T00:17:28.843461", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-11-25T00:17:28.846144", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-11-25T00:17:28.847053", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-11-25T00:17:28.847922", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-11-25T00:17:28.848705", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-11-25T00:17:28.850638", "lvl": "debug", "msg": "Set_port 61989"}
- {"dt": "2023-11-25T00:17:28.851653", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-11-25T00:17:28.852697", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-11-25T00:17:28.854626", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29407/4/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-25T00:17:28.855443", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-25T00:17:28.856117", "lvl": "debug", "msg": "total size: 2062999 (1MB)"}
- {"dt": "2023-11-25T00:17:28.856898", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-25T00:17:29.171423", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-11-25T00:17:29.375767", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-11-25T00:17:29.476248", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-11-25T00:17:29.484160", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-11-25T00:17:29.577940", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-11-25T00:17:29.583232", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-11-25T00:17:29.589541", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-11-25T00:17:29.594315", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-11-25T00:17:29.680513", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-11-25T00:17:29.685883", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-11-25T00:17:29.691251", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-11-25T00:17:29.697571", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-11-25T00:17:29.702465", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-11-25T00:17:29.708506", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-11-25T00:17:29.713396", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-11-25T00:17:29.779023", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-11-25T00:17:29.787372", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-11-25T00:17:29.792545", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-11-25T00:17:29.800052", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-11-25T00:17:29.803764", "lvl": "info", "msg": "1MB downloaded in 0.95s (2.08MB/s)"}
- {"dt": "2023-11-25T00:17:29.805183", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:17:29.805931", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.95", "extra": {"label": "initrd", "md5sum": "cf687cfc567f6c13545ae72aaa44c43a", "sha256sum": "ae020dfc226a6367b68bd8cb8915e9763eab75067a36e104c034afc24a94fc23", "sha512sum": "d3a29033cdfe7bb58f35488b7d8e7a338216dc8810c4c6327fc6cc2a961d90725e970d7255175a23fbd14e3b02ae447af70f19df1182b43ffcab34a8db7ec9b6", "size": ! "2062999"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:17:29.807439", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:17:29.808299", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-11-25T00:17:29.809195", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-11-25T00:17:29.810279", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29407/4/h3ulcb-nogfx/Image"}
- {"dt": "2023-11-25T00:17:29.810988", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/kernel/Image"}
- {"dt": "2023-11-25T00:17:29.811551", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-11-25T00:17:29.812070", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-25T00:17:30.210456", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-11-25T00:17:30.814399", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-11-25T00:17:30.934320", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-11-25T00:17:31.057204", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-11-25T00:17:31.134244", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-11-25T00:17:31.236416", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-11-25T00:17:31.317200", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-11-25T00:17:31.392373", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-11-25T00:17:31.470417", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-11-25T00:17:31.546068", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-11-25T00:17:31.631040", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-11-25T00:17:31.713469", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-11-25T00:17:31.866034", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-11-25T00:17:31.940039", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-11-25T00:17:32.139709", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-11-25T00:17:32.217247", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-11-25T00:17:32.296180", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-11-25T00:17:32.440594", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-11-25T00:17:32.559296", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-11-25T00:17:32.675886", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-11-25T00:17:32.831584", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-11-25T00:17:32.833211", "lvl": "info", "msg": "37MB downloaded in 3.02s (12.37MB/s)"}
- {"dt": "2023-11-25T00:17:32.834362", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2023-11-25T00:17:32.835043", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "3.03", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:17:32.836563", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:03) [common]"}
- {"dt": "2023-11-25T00:17:32.837369", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:56) [common]"}
- {"dt": "2023-11-25T00:17:32.838129", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:56) [common]"}
- {"dt": "2023-11-25T00:17:32.839223", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29407/4/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-25T00:17:32.839931", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-25T00:17:32.840540", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-11-25T00:17:32.841059", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-11-25T00:17:33.152676", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-11-25T00:17:33.252607", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-11-25T00:17:33.255687", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-11-25T00:17:33.259754", "lvl": "info", "msg": "0MB downloaded in 0.42s (0.19MB/s)"}
- {"dt": "2023-11-25T00:17:33.261081", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:17:33.261878", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.42", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:17:33.263453", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:17:33.264340", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:56) [common]"}
- {"dt": "2023-11-25T00:17:33.265266", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:56) [common]"}
- {"dt": "2023-11-25T00:17:33.266423", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29407/4/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-11-25T00:17:33.267153", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-25T00:17:33.267740", "lvl": "debug", "msg": "total size: 453453852 (432MB)"}
- {"dt": "2023-11-25T00:17:33.268374", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-11-25T00:17:33.590696", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-11-25T00:17:40.756878", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-11-25T00:17:46.396720", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-11-25T00:17:52.836849", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-11-25T00:17:58.128990", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-11-25T00:18:04.809147", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-11-25T00:18:11.372893", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-11-25T00:18:18.768807", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-11-25T00:18:26.468919", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-11-25T00:18:33.268878", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-11-25T00:18:38.644804", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-11-25T00:18:44.051480", "lvl": "debug", "msg": "progress  55% (237MB)"}
- {"dt": "2023-11-25T00:18:49.467201", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-11-25T00:18:54.589771", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-11-25T00:19:00.142520", "lvl": "debug", "msg": "progress  70% (302MB)"}
- {"dt": "2023-11-25T00:19:05.141580", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-11-25T00:19:09.537828", "lvl": "debug", "msg": "progress  80% (345MB)"}
- {"dt": "2023-11-25T00:19:14.229453", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-11-25T00:19:18.701933", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-11-25T00:19:23.284754", "lvl": "debug", "msg": "progress  95% (410MB)"}
- {"dt": "2023-11-25T00:19:30.696838", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-11-25T00:19:34.108345", "lvl": "info", "msg": "432MB downloaded in 120.84s (3.58MB/s)"}
- {"dt": "2023-11-25T00:19:34.110257", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:01) [common]"}
- {"dt": "2023-11-25T00:19:34.111090", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "120.85", "extra": {"label": "nbdroot", "md5sum": "cfb6a2c872ec704bbd53550b849b1c4e", "sha256sum": "b120bda7e2c5ddeb14f9488540d9848688de6d930122cbc2937509f685eea676", "sha512sum": "862bbecddd8c4a1a040058fd093074b3b150955ec0075e87f967f04aba1c7ae5e96ab0426b4d83d92805f12469ce19e41996b110cf7aecc76d283fba9a6ffdc9", "size": ! "453453852"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:34.112837", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:01) [common]"}
- {"dt": "2023-11-25T00:19:34.113694", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-11-25T00:19:34.115214", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2"}
- {"dt": "2023-11-25T00:19:34.117008", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin"}
- {"dt": "2023-11-25T00:19:34.118211", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/tests"}
- {"dt": "2023-11-25T00:19:34.119284", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/results"}
- {"dt": "2023-11-25T00:19:34.120393", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-add-keys"}
- {"dt": "2023-11-25T00:19:34.121900", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-add-sources"}
- {"dt": "2023-11-25T00:19:34.123299", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-background-process-start"}
- {"dt": "2023-11-25T00:19:34.124873", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-background-process-stop"}
- {"dt": "2023-11-25T00:19:34.126328", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-common-functions"}
- {"dt": "2023-11-25T00:19:34.127736", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-echo-ipv4"}
- {"dt": "2023-11-25T00:19:34.129209", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-install-packages"}
- {"dt": "2023-11-25T00:19:34.130625", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-installed-packages"}
- {"dt": "2023-11-25T00:19:34.132012", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-os-build"}
- {"dt": "2023-11-25T00:19:34.133515", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-probe-channel"}
- {"dt": "2023-11-25T00:19:34.134912", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-probe-ip"}
- {"dt": "2023-11-25T00:19:34.136523", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-target-ip"}
- {"dt": "2023-11-25T00:19:34.138112", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-target-mac"}
- {"dt": "2023-11-25T00:19:34.139551", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-target-storage"}
- {"dt": "2023-11-25T00:19:34.141056", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-test-case"}
- {"dt": "2023-11-25T00:19:34.142482", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-test-event"}
- {"dt": "2023-11-25T00:19:34.143994", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-test-feedback"}
- {"dt": "2023-11-25T00:19:34.145888", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-test-raise"}
- {"dt": "2023-11-25T00:19:34.147350", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-test-reference"}
- {"dt": "2023-11-25T00:19:34.148834", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-test-runner"}
- {"dt": "2023-11-25T00:19:34.150241", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-test-set"}
- {"dt": "2023-11-25T00:19:34.151671", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-test-shell"}
- {"dt": "2023-11-25T00:19:34.153310", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-install-packages (oe)"}
- {"dt": "2023-11-25T00:19:34.154967", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/bin/lava-installed-packages (oe)"}
- {"dt": "2023-11-25T00:19:34.156442", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/environment"}
- {"dt": "2023-11-25T00:19:34.157612", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-11-25T00:19:34.158372", "lvl": "debug", "msg": "- LAVA_JOB_ID=33796"}
- {"dt": "2023-11-25T00:19:34.159034", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-11-25T00:19:34.160096", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:55) [common]"}
- {"dt": "2023-11-25T00:19:34.162686", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:34.163621", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-11-25T00:19:34.164398", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-11-25T00:19:34.165167", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:34.165970", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-11-25T00:19:34.166600", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-11-25T00:19:34.167377", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:34.168330", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:55) [common]"}
- {"dt": "2023-11-25T00:19:34.169124", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-11-25T00:19:34.170054", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:55) [common]"}
- {"dt": "2023-11-25T00:19:34.170950", "lvl": "debug", "msg": "Using /lava-33796 at stage 0"}
- {"dt": "2023-11-25T00:19:34.171939", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-25T00:19:34.173242", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/0/tests/0_network-veth'"}
- {"dt": "2023-11-25T00:19:34.728691", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-11-25T00:19:34.732653", "lvl": "debug", "msg": "uuid=33796_1.5.4.1 testdef=None"}
- {"dt": "2023-11-25T00:19:34.733812", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:19:34.734597", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.56", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "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-11-25T00:19:34.736054", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:54) [common]"}
- {"dt": "2023-11-25T00:19:34.741261", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:34.742024", "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": "33796_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:34.743592", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:54) [common]"}
- {"dt": "2023-11-25T00:19:34.750066", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:34.750841", "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": "33796_1.5.4.1", "uuid": "33796_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:34.752550", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:54) [common]"}
- {"dt": "2023-11-25T00:19:34.758799", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/0/tests/0_network-veth test_uuid 33796_1.5.4.1"}
- {"dt": "2023-11-25T00:19:34.760551", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:34.761315", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/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": "33796_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:34.762947", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:54) [common]"}
- {"dt": "2023-11-25T00:19:34.763694", "lvl": "debug", "msg": "Using /lava-33796 at stage 1"}
- {"dt": "2023-11-25T00:19:34.764756", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-25T00:19:34.765546", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/1_ci-busybox'"}
- {"dt": "2023-11-25T00:19:35.320033", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/1_ci-busybox"}
- {"dt": "2023-11-25T00:19:35.330513", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-11-25T00:19:35.335926", "lvl": "debug", "msg": "uuid=33796_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-11-25T00:19:35.337265", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:19:35.338113", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.57", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "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-11-25T00:19:35.339615", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:54) [common]"}
- {"dt": "2023-11-25T00:19:35.346874", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:35.347692", "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": "33796_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:35.349349", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:53) [common]"}
- {"dt": "2023-11-25T00:19:35.358561", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:35.359332", "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": "33796_1.5.4.5", "uuid": "33796_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:35.361424", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:53) [common]"}
- {"dt": "2023-11-25T00:19:35.370586", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/1_ci-busybox test_uuid 33796_1.5.4.5"}
- {"dt": "2023-11-25T00:19:35.372706", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:35.373473", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/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": "33796_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:35.375473", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:53) [common]"}
- {"dt": "2023-11-25T00:19:35.376365", "lvl": "debug", "msg": "Using /lava-33796 at stage 1"}
- {"dt": "2023-11-25T00:19:35.377488", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-25T00:19:35.378261", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-11-25T00:19:35.942705", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-11-25T00:19:35.954092", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-11-25T00:19:35.957251", "lvl": "debug", "msg": "uuid=33796_1.5.4.9 testdef=None"}
- {"dt": "2023-11-25T00:19:35.958386", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:19:35.959219", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.58", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "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-11-25T00:19:35.960796", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:53) [common]"}
- {"dt": "2023-11-25T00:19:35.966256", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:35.966952", "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": "33796_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:35.968579", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:53) [common]"}
- {"dt": "2023-11-25T00:19:35.976301", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:35.977007", "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": "33796_1.5.4.9", "uuid": "33796_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:35.978499", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:53) [common]"}
- {"dt": "2023-11-25T00:19:35.985599", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/2_ci-smoke-tests-basic test_uuid 33796_1.5.4.9"}
- {"dt": "2023-11-25T00:19:35.988548", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:35.989388", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/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": "33796_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:35.990986", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:53) [common]"}
- {"dt": "2023-11-25T00:19:35.991669", "lvl": "debug", "msg": "Using /lava-33796 at stage 1"}
- {"dt": "2023-11-25T00:19:35.992633", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-25T00:19:35.993380", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/3_ci-service-check'"}
- {"dt": "2023-11-25T00:19:36.569154", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/3_ci-service-check"}
- {"dt": "2023-11-25T00:19:36.583236", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-11-25T00:19:36.586976", "lvl": "debug", "msg": "uuid=33796_1.5.4.13 testdef=None"}
- {"dt": "2023-11-25T00:19:36.588070", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:19:36.589227", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.60", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "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-11-25T00:19:36.591278", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-11-25T00:19:36.597300", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:36.598007", "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": "33796_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:36.600008", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-11-25T00:19:36.608609", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:36.609425", "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": "33796_1.5.4.13", "uuid": "33796_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:36.611340", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-11-25T00:19:36.619013", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1/tests/3_ci-service-check test_uuid 33796_1.5.4.13"}
- {"dt": "2023-11-25T00:19:36.619786", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-11-25T00:19:36.620476", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-11-25T00:19:36.622393", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:36.623131", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/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": "33796_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:36.624921", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:52) [common]"}
- {"dt": "2023-11-25T00:19:36.625714", "lvl": "debug", "msg": "Using /lava-33796 at stage 2"}
- {"dt": "2023-11-25T00:19:36.626779", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-25T00:19:36.627550", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/2/tests/4_screenshooter'"}
- {"dt": "2023-11-25T00:19:37.205027", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-11-25T00:19:37.208978", "lvl": "debug", "msg": "uuid=33796_1.5.4.17 testdef=None"}
- {"dt": "2023-11-25T00:19:37.210432", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:19:37.211647", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.59", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.17", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:37.214400", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-11-25T00:19:37.220483", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:37.221214", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33796_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:37.222846", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-11-25T00:19:37.229306", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:37.230060", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "33796_1.5.4.17", "uuid": "33796_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:37.231710", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:52) [common]"}
- {"dt": "2023-11-25T00:19:37.238027", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/2/tests/4_screenshooter test_uuid 33796_1.5.4.17"}
- {"dt": "2023-11-25T00:19:37.239642", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:37.240451", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/2/tests/4_screenshooter/run.sh", "from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33796_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:37.242090", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:52) [common]"}
- {"dt": "2023-11-25T00:19:37.242854", "lvl": "debug", "msg": "Using /lava-33796 at stage 3"}
- {"dt": "2023-11-25T00:19:37.243876", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-11-25T00:19:37.244727", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-11-25T00:19:37.803479", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-11-25T00:19:37.815481", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-11-25T00:19:37.818967", "lvl": "debug", "msg": "uuid=33796_1.5.4.21 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\\\s(?P<result>\\\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}"}
- {"dt": "2023-11-25T00:19:37.821001", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:19:37.821853", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.58", "extra": {"commit": "b4aef27928ee7897aae9de1b5fa47d0aad61803c", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions"}, "level": "1.5.4.21", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:37.823425", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-11-25T00:19:37.830282", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:37.831343", "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": "33796_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:37.833940", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-11-25T00:19:37.843122", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:37.843887", "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": "33796_1.5.4.21", "uuid": "33796_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:37.846262", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-11-25T00:19:37.855281", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/3/tests/5_ci-dumpjournal test_uuid 33796_1.5.4.21"}
- {"dt": "2023-11-25T00:19:37.857409", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:37.858175", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/3/tests/5_ci-dumpjournal/run.sh", "from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "33796_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:37.859610", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-11-25T00:19:37.860754", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/0 for stage 0"}
- {"dt": "2023-11-25T00:19:37.862355", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-11-25T00:19:37.863269", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/1 for stage 1"}
- {"dt": "2023-11-25T00:19:37.864324", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-11-25T00:19:37.865042", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-11-25T00:19:37.865586", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-11-25T00:19:37.866281", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/2 for stage 2"}
- {"dt": "2023-11-25T00:19:37.867331", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-11-25T00:19:37.868548", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33796/lava-overlay-3uacc5c2/lava-33796/3 for stage 3"}
- {"dt": "2023-11-25T00:19:37.869646", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-11-25T00:19:37.870765", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-11-25T00:19:37.871654", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:51) [common]"}
- {"dt": "2023-11-25T00:19:38.679160", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:19:38.680137", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:50) [common]"}
- {"dt": "2023-11-25T00:19:38.681778", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:38.682650", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-11-25T00:19:38.683373", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:50) [common]"}
- {"dt": "2023-11-25T00:19:38.684060", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:50) [common]"}
- {"dt": "2023-11-25T00:19:38.684963", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:38.685690", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:38.686910", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:50) [common]"}
- {"dt": "2023-11-25T00:19:38.687555", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-11-25T00:19:38.688127", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61989, NBD-ROOT: 33796/nbd-deploy-990xpfy1/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-25T00:19:38.691599", "lvl": "debug", "msg": "nbd-server 61989 /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-11-25T00:19:38.711615", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-11-25T00:19:38.713560", "lvl": "debug", "msg": "output: ** (process:14560): WARNING **: 00:19:38.709: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-11-25T00:19:38.714206", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-11-25T00:19:38.714717", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-11-25T00:19:38.715618", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:38.716489", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:10) [common]"}
- {"dt": "2023-11-25T00:19:38.717360", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-11-25T00:19:38.718239", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-11-25T00:19:38.719079", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:38.719846", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-11-25T00:19:38.721170", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-11-25T00:19:38.721836", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-25T00:19:38.722375", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-11-25T00:19:38.722965", "lvl": "debug", "msg": "- {DTB}: 33796/nbd-deploy-990xpfy1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-25T00:19:38.723592", "lvl": "debug", "msg": "- {INITRD}: 33796/nbd-deploy-990xpfy1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-25T00:19:38.724154", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-11-25T00:19:38.724713", "lvl": "debug", "msg": "- {KERNEL}: 33796/nbd-deploy-990xpfy1/kernel/Image"}
- {"dt": "2023-11-25T00:19:38.725192", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-11-25T00:19:38.725638", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-11-25T00:19:38.726084", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61989"}
- {"dt": "2023-11-25T00:19:38.726535", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-11-25T00:19:38.726984", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-11-25T00:19:38.727424", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-11-25T00:19:38.727863", "lvl": "debug", "msg": "- {RAMDISK}: 33796/nbd-deploy-990xpfy1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-25T00:19:38.728349", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-11-25T00:19:38.728810", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-11-25T00:19:38.729362", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-11-25T00:19:38.729900", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-11-25T00:19:38.730388", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-11-25T00:19:38.730843", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-11-25T00:19:38.731312", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-11-25T00:19:38.731788", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-25T00:19:38.732336", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-25T00:19:38.732829", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-11-25T00:19:38.733288", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-11-25T00:19:38.733747", "lvl": "info", "msg": "- tftp 0x68080000 33796/nbd-deploy-990xpfy1/kernel/Image"}
- {"dt": "2023-11-25T00:19:38.734203", "lvl": "info", "msg": "- tftp 0x4a000000 33796/nbd-deploy-990xpfy1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-25T00:19:38.734680", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-11-25T00:19:38.735154", "lvl": "info", "msg": "- tftp 0x68000000 33796/nbd-deploy-990xpfy1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-25T00:19:38.735719", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61989 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-11-25T00:19:38.736379", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-25T00:19:38.737082", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-11-25T00:19:38.737676", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:38.739119", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-11-25T00:19:38.739742", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-11-25T00:19:42.589461", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-11-25T00:19:42.591777", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:04) [common]"}
- {"dt": "2023-11-25T00:19:42.595883", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:56) [common]"}
- {"dt": "2023-11-25T00:19:42.596851", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:56) [common]"}
- {"dt": "2023-11-25T00:19:42.597838", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:56) [common]"}
- {"dt": "2023-11-25T00:19:42.599077", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-11-25T00:19:46.898023", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-25T00:19:47.966751", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-25T00:19:48.003882", "lvl": "debug", "msg": "Returned 0 in 5 seconds"}
- {"dt": "2023-11-25T00:19:48.106588", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:06) [common]"}
- {"dt": "2023-11-25T00:19:48.107506", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "5.51", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:19:48.109036", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:06) [common]"}
- {"dt": "2023-11-25T00:19:48.110027", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:51) [common]"}
- {"dt": "2023-11-25T00:19:48.110807", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-11-25T00:19:48.111444", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-11-25T00:19:48.113350", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-11-25T00:19:48.114127", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-11-25T00:19:48.114844", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-11-25T00:19:48.178311", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Initia\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.179107", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.220607", "lvl": "target", "msg": "NOTICE:  BL2: B\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.221426", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.324333", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier R\0\0\0\0NOTICE:  \uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.325108", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.366368", "lvl": "target", "msg": "NOTICE\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.367217", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.413702", "lvl": "target", "msg": "NOTICE:  BL2: Board is Start\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.414735", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.462610", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.463381", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.465857", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-25T00:19:48.513638", "lvl": "target", "msg": "NOTICE:  BL2: Boot\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.514377", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.514887", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-25T00:19:48.568837", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80M\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.569583", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.570487", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-25T00:19:48.572385", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-25T00:19:48.573309", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-11-25T00:19:48.637037", "lvl": "target", "msg": "NOTICE:  A\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.637936", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.638926", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-25T00:19:48.639667", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-25T00:19:48.640313", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-11-25T00:19:48.640898", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-11-25T00:19:48.641384", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-11-25T00:19:48.641982", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-11-25T00:19:48.714981", "lvl": "target", "msg": "\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-11-25T00:19:48.716465", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-11-25T00:19:48.717887", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-11-25T00:19:48.718761", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-11-25T00:19:48.719313", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-11-25T00:19:48.719800", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-11-25T00:19:48.720303", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-11-25T00:19:48.720909", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-11-25T00:19:48.761337", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-11-25T00:19:48.762429", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-11-25T00:19:48.763298", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-11-25T00:19:48.764135", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-11-25T00:19:48.765194", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-11-25T00:19:48.766207", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-11-25T00:19:48.767055", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-11-25T00:19:48.767919", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-11-25T00:19:48.768830", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-11-25T00:19:48.769660", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-11-25T00:19:48.770859", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-11-25T00:19:48.804218", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-11-25T00:19:48.805253", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-11-25T00:19:48.806051", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-11-25T00:19:48.806800", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-11-25T00:19:48.807529", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-11-25T00:19:48.808308", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-11-25T00:19:48.809669", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-11-25T00:19:48.843823", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-11-25T00:19:48.844960", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-11-25T00:19:48.847038", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-11-25T00:19:49.174654", "lvl": "target", "msg": ""}
- {"dt": "2023-11-25T00:19:49.176084", "lvl": "target", "msg": ""}
- {"dt": "2023-11-25T00:19:49.177441", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-11-25T00:19:49.178828", "lvl": "target", "msg": ""}
- {"dt": "2023-11-25T00:19:49.180044", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-11-25T00:19:49.182609", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-11-25T00:19:49.369644", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-11-25T00:19:49.393560", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-11-25T00:19:49.394668", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-11-25T00:19:49.395741", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-11-25T00:19:49.398738", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-11-25T00:19:49.399717", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-11-25T00:19:49.400733", "lvl": "target", "msg": ""}
- {"dt": "2023-11-25T00:19:49.449332", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-11-25T00:19:49.612231", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-11-25T00:19:49.613716", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-11-25T00:19:49.614991", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-11-25T00:19:49.616729", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-11-25T00:19:49.663215", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-11-25T00:19:49.679167", "lvl": "input", "msg": "\n"}
- {"dt": "2023-11-25T00:19:49.730595", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-11-25T00:19:49.731700", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-11-25T00:19:49.732663", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:49) [common]"}
- {"dt": "2023-11-25T00:19:49.733395", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-11-25T00:19:49.734126", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:49)"}
- {"dt": "2023-11-25T00:19:49.758248", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-11-25T00:19:49.761116", "lvl": "debug", "msg": "Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-11-25T00:19:49.762114", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-11-25T00:19:49.863819", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-11-25T00:19:49.865057", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2023-11-25T00:19:49.872514", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-11-25T00:19:49.873550", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-11-25T00:19:49.974975", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-25T00:19:49.975839", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2023-11-25T00:19:49.982952", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-11-25T00:19:49.984096", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-11-25T00:19:50.085744", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-25T00:19:50.087107", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2023-11-25T00:19:50.094074", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-11-25T00:19:50.095334", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-11-25T00:19:50.197003", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-11-25T00:19:50.198274", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2023-11-25T00:19:50.205781", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-11-25T00:19:53.291385", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-11-25T00:19:53.307335", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-11-25T00:19:53.547146", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-11-25T00:19:54.059324", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-11-25T00:19:54.330567", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1031 ms)"}
- {"dt": "2023-11-25T00:19:54.331671", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-11-25T00:19:54.433472", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-11-25T00:19:54.434383", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:44)"}
- {"dt": "2023-11-25T00:19:54.443184", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-11-25T00:19:54.444394", "lvl": "input", "msg": "tftp 0x68080000 33796/nbd-deploy-990xpfy1/kernel/Image\n"}
- {"dt": "2023-11-25T00:19:54.546023", "lvl": "target", "msg": "=> tftp 0x68080000 33796/nbd-deploy-990xpfy1/kernel/Image"}
- {"dt": "2023-11-25T00:19:54.547194", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:44)"}
- {"dt": "2023-11-25T00:19:54.554276", "lvl": "target", "msg": "tftp 0x68080000 33796/nbd-deploy-990xpfy1/kernel/Image"}
- {"dt": "2023-11-25T00:19:54.579402", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-25T00:19:54.580573", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-25T00:19:54.582730", "lvl": "target", "msg": "Filename '33796/nbd-deploy-990xpfy1/kernel/Image'."}
- {"dt": "2023-11-25T00:19:54.583925", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-11-25T00:19:55.924485", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-11-25T00:19:56.819898", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:19:57.363108", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:19:57.891524", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:19:58.434940", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:19:58.961642", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:19:59.490067", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:00.049243", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:00.625258", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:01.168103", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:01.743484", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:02.352254", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:02.959497", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:03.486217", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:04.014076", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:04.606865", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:05.212864", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:05.773560", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:06.336075", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:06.907690", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:07.579996", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:08.347507", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:08.939127", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:09.609674", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:10.201499", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:10.872732", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:11.625246", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:12.263646", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:12.824638", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:13.384639", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:13.959324", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:14.539020", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:15.111792", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:15.653996", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:16.212821", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:16.788543", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:17.348114", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:17.891816", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:18.468677", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:19.042631", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:19.570442", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:19.626030", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-11-25T00:20:19.626854", "lvl": "target", "msg": "\t 1.5 MiB/s"}
- {"dt": "2023-11-25T00:20:19.627550", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-25T00:20:19.629347", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-11-25T00:20:19.631991", "lvl": "input", "msg": "tftp 0x4a000000 33796/nbd-deploy-990xpfy1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-11-25T00:20:19.733808", "lvl": "target", "msg": "=> tftp 0x4a000000 33796/nbd-deploy-990xpfy1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-25T00:20:19.734794", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:19)"}
- {"dt": "2023-11-25T00:20:19.741798", "lvl": "target", "msg": "tftp 0x4a000000 33796/nbd-deploy-990xpfy1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-11-25T00:20:19.771159", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-25T00:20:19.772168", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-25T00:20:19.773184", "lvl": "target", "msg": "Filename '33796/nbd-deploy-990xpfy1/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-11-25T00:20:19.774797", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-11-25T00:20:20.284748", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-11-25T00:20:20.796763", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-11-25T00:20:20.892749", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-11-25T00:20:20.893586", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-11-25T00:20:20.894421", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-25T00:20:20.908274", "lvl": "target", "msg": "Bytes transferred = 2062999 (1f7a97 hex)"}
- {"dt": "2023-11-25T00:20:20.909475", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-11-25T00:20:21.011188", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-11-25T00:20:21.012843", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:18)"}
- {"dt": "2023-11-25T00:20:21.021023", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-11-25T00:20:21.022675", "lvl": "input", "msg": "tftp 0x68000000 33796/nbd-deploy-990xpfy1/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-11-25T00:20:21.124618", "lvl": "target", "msg": "=> tftp 0x68000000 33796/nbd-deploy-990xpfy1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-25T00:20:21.125610", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:18)"}
- {"dt": "2023-11-25T00:20:21.131175", "lvl": "target", "msg": "tftp 0x68000000 33796/nbd-deploy-990xpfy1/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-11-25T00:20:21.163134", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-11-25T00:20:21.164026", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-11-25T00:20:21.164846", "lvl": "target", "msg": "Filename '33796/nbd-deploy-990xpfy1/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-11-25T00:20:21.165542", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-11-25T00:20:21.225975", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-11-25T00:20:21.227656", "lvl": "target", "msg": "\t 1.4 MiB/s"}
- {"dt": "2023-11-25T00:20:21.228686", "lvl": "target", "msg": "done"}
- {"dt": "2023-11-25T00:20:21.229564", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-11-25T00:20:21.232441", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61989 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'\n"}
- {"dt": "2023-11-25T00:20:21.336296", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61989 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-11-25T00:20:21.337309", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:17)"}
- {"dt": "2023-11-25T00:20:21.356403", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61989 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-11-25T00:20:21.357818", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-11-25T00:20:21.460628", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-25T00:20:21.462522", "lvl": "debug", "msg": "Setting prompt string to ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-11-25T00:20:21.463784", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:17)"}
- {"dt": "2023-11-25T00:20:21.467955", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-11-25T00:20:21.484765", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-11-25T00:20:21.630993", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-11-25T00:20:21.631841", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-11-25T00:20:21.632704", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffffa97 ... OK"}
- {"dt": "2023-11-25T00:20:21.633482", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-11-25T00:20:21.634238", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-11-25T00:20:21.635013", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-11-25T00:20:21.635786", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-11-25T00:20:21.637214", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-11-25T00:20:21.638831", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-11-25T00:20:21.675120", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-11-25T00:20:21.675926", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-11-25T00:20:21.677140", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-11-25T00:20:21.677864", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-11-25T00:20:21.678521", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-11-25T00:20:21.679184", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-11-25T00:20:21.679820", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-11-25T00:20:21.681010", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-11-25T00:20:21.681716", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-11-25T00:20:21.682355", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-11-25T00:20:21.683127", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-11-25T00:20:21.696425", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-11-25T00:20:21.697508", "lvl": "target", "msg": ""}
- {"dt": "2023-11-25T00:20:21.699142", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-11-25T00:20:21.700127", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-11-25T00:20:21.701978", "lvl": "target", "msg": ""}
- {"dt": "2023-11-25T00:20:21.703647", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-25T00:20:21.704909", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:32) [common]"}
- {"dt": "2023-11-25T00:20:21.705987", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "31.97", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-25T00:20:21.707977", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-25T00:20:21.708854", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:39) [common]"}
- {"dt": "2023-11-25T00:20:21.709717", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "39.11", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-25T00:20:21.710878", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-11-25T00:20:21.711704", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-11-25T00:20:21.712530", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:43) [common]"}
- {"dt": "2023-11-25T00:20:21.713281", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "42.99", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-11-25T00:20:21.714653", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-11-25T00:20:21.715377", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/initrd"}
- {"dt": "2023-11-25T00:20:21.717361", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/kernel"}
- {"dt": "2023-11-25T00:20:21.728982", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/dtb"}
- {"dt": "2023-11-25T00:20:21.730428", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33796/nbd-deploy-990xpfy1/nbdroot"}
- {"dt": "2023-11-25T00:20:21.937314", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-11-25T00:20:21.938500", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-11-25T00:20:25.384386", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-11-25T00:20:25.421000", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-11-25T00:20:25.525242", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-11-25T00:20:25.527564", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.59", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-11-25T00:20:25.532005", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-11-25T00:20:25.536767", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-11-25T00:20:25.537750", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.538480", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.539028", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.539544", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.540043", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.540601", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.541165", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.541667", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.542182", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.542674", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.543178", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.543668", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.544154", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.544692", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.545190", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.545683", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.546171", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.546656", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.547143", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.547634", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.548125", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.548658", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.549157", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.549653", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.550181", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.550700", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.551207", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.551714", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.552241", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.552745", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.553242", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.553731", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.554272", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.554771", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.555257", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.555741", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.556257", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.556797", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.557291", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.557775", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.558256", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.558739", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.559226", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.559707", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.560209", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.560706", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.561187", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.561671", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.562149", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-11-25T00:20:25.562962", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-11-25T00:20:26.536889", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-11-25T00:20:26.539134", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-11-25T00:20:26.540892", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-11-25T00:20:26.643685", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-11-25T00:20:26.646031", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-11-25T00:20:26.647800", "lvl": "debug", "msg": "clean NBD port 61989"}
- {"dt": "2023-11-25T00:20:29.928340", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/33796"}
- {"dt": "2023-11-25T00:20:30.020923", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/33796"}
- {"dt": "2023-11-25T00:20:30.022016", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-11-25T00:20:30.022632", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
