- {"dt": "2023-12-15T22:09:41.514382", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-15T22:09:41.515711", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-15T22:09:41.516769", "lvl": "info", "msg": "Start time: 2023-12-15 22:09:41.516709+00:00 (UTC)"}
- {"dt": "2023-12-15T22:09:41.517905", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29537/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-15T22:09:41.744231", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29537/2/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-15T22:09:41.968404", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29537/2/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-15T22:09:42.174358", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29537/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-15T22:09:42.405249", "lvl": "info", "msg": "validate duration: 0.89"}
- {"dt": "2023-12-15T22:09:42.406071", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-15T22:09:42.407297", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-15T22:09:42.407984", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-15T22:09:42.408612", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-15T22:09:42.409214", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-15T22:09:42.410224", "lvl": "debug", "msg": "Set_port 61999"}
- {"dt": "2023-12-15T22:09:42.411126", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-15T22:09:42.411995", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-15T22:09:42.413264", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29537/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T22:09:42.413991", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T22:09:42.414614", "lvl": "debug", "msg": "total size: 2061485 (1MB)"}
- {"dt": "2023-12-15T22:09:42.415202", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-15T22:09:42.721596", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-15T22:09:42.920226", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-15T22:09:43.019967", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-15T22:09:43.111393", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-15T22:09:43.124024", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-15T22:09:43.128436", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-15T22:09:43.213528", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-15T22:09:43.221864", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-15T22:09:43.227535", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-15T22:09:43.232063", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-15T22:09:43.237290", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-15T22:09:43.243257", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-15T22:09:43.307608", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-15T22:09:43.319297", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-15T22:09:43.323852", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-15T22:09:43.328273", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-15T22:09:43.333946", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-15T22:09:43.338345", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-15T22:09:43.343985", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-15T22:09:43.347548", "lvl": "info", "msg": "1MB downloaded in 0.93s (2.11MB/s)"}
- {"dt": "2023-12-15T22:09:43.348771", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:09:43.349462", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.94", "extra": {"label": "initrd", "md5sum": "5907bd33e8fedc7be85bef50ead9b092", "sha256sum": "048318d9c52e953ee0434be6cc4cd1a20356aa9adf925841dcdcf36c2ffaa5a5", "sha512sum": "b67420199afbf021f5b1e6158684ba015e5d004f2cc574dfa1a9e29659bec70b9d501e091a97be2d819188de8ef9792e91d235a2329cf3035addef0bbc61f633", "size": ! "2061485"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:09:43.350993", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:09:43.351854", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-15T22:09:43.352648", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-15T22:09:43.353731", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29537/2/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-15T22:09:43.354413", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/kernel/Image"}
- {"dt": "2023-12-15T22:09:43.354977", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-15T22:09:43.355595", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-15T22:09:43.676680", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-15T22:09:44.311745", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-15T22:09:44.594273", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-15T22:09:44.817182", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-15T22:09:45.035697", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-15T22:09:45.311022", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-15T22:09:45.527611", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-15T22:09:45.740725", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-15T22:09:45.951262", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-15T22:09:46.161138", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-15T22:09:46.362918", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-15T22:09:46.565092", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-15T22:09:46.761900", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-15T22:09:46.949329", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-15T22:09:47.106221", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-15T22:09:47.296309", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-15T22:09:47.477578", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-15T22:09:47.626927", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-15T22:09:47.802073", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-15T22:09:47.945557", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-15T22:09:48.119908", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-15T22:09:48.121464", "lvl": "info", "msg": "37MB downloaded in 4.77s (7.84MB/s)"}
- {"dt": "2023-12-15T22:09:48.122949", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:05) [common]"}
- {"dt": "2023-12-15T22:09:48.123745", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "4.77", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:09:48.125234", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:05) [common]"}
- {"dt": "2023-12-15T22:09:48.126374", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:54) [common]"}
- {"dt": "2023-12-15T22:09:48.127284", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:54) [common]"}
- {"dt": "2023-12-15T22:09:48.128409", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29537/2/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T22:09:48.129122", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T22:09:48.129642", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-15T22:09:48.130639", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-15T22:09:48.443368", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-15T22:09:48.544788", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-15T22:09:48.547320", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-15T22:09:48.549273", "lvl": "info", "msg": "0MB downloaded in 0.42s (0.19MB/s)"}
- {"dt": "2023-12-15T22:09:48.550585", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:09:48.551401", "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-12-15T22:09:48.553007", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:09:48.553912", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:54) [common]"}
- {"dt": "2023-12-15T22:09:48.556593", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:54) [common]"}
- {"dt": "2023-12-15T22:09:48.557785", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29537/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-15T22:09:48.558480", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-15T22:09:48.559049", "lvl": "debug", "msg": "total size: 453876960 (432MB)"}
- {"dt": "2023-12-15T22:09:48.560657", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-15T22:09:48.883195", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-15T22:09:54.941385", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-15T22:09:59.323068", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-15T22:10:05.130509", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-15T22:10:10.357297", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-15T22:10:16.324524", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-15T22:10:22.367005", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-15T22:10:28.645786", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-15T22:10:35.572725", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-15T22:10:42.305341", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-15T22:10:47.294652", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-15T22:10:52.610840", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-15T22:10:58.043649", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-15T22:11:02.743627", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-15T22:11:07.885761", "lvl": "debug", "msg": "progress  70% (303MB)"}
- {"dt": "2023-12-15T22:11:12.820630", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-15T22:11:17.101543", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-15T22:11:21.833802", "lvl": "debug", "msg": "progress  85% (367MB)"}
- {"dt": "2023-12-15T22:11:26.805720", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-15T22:11:31.635098", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-15T22:11:39.615584", "lvl": "debug", "msg": "progress 100% (432MB)"}
- {"dt": "2023-12-15T22:11:43.172993", "lvl": "info", "msg": "432MB downloaded in 114.61s (3.78MB/s)"}
- {"dt": "2023-12-15T22:11:43.174948", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:55) [common]"}
- {"dt": "2023-12-15T22:11:43.175906", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "114.62", "extra": {"label": "nbdroot", "md5sum": "c542fa9d808985550798517a3b8b818f", "sha256sum": "cd45187ec41eaaabb5ffbc963b770525baabe9364ba84c059dcb8e49f79f7bdc", "sha512sum": "fb4230af4d0387efc0215d905f4a761246a160f774676dd366fd6ac3c4eae2bd049ebd32604f06c81c7b38672346cb1399f7dca828341afb83e0afcfdf2dd5c0", "size": ! "453876960"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:43.177704", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:55) [common]"}
- {"dt": "2023-12-15T22:11:43.178684", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.180388", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87"}
- {"dt": "2023-12-15T22:11:43.181683", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin"}
- {"dt": "2023-12-15T22:11:43.182723", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/tests"}
- {"dt": "2023-12-15T22:11:43.183753", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/results"}
- {"dt": "2023-12-15T22:11:43.184750", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-add-keys"}
- {"dt": "2023-12-15T22:11:43.186098", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-add-sources"}
- {"dt": "2023-12-15T22:11:43.187594", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-background-process-start"}
- {"dt": "2023-12-15T22:11:43.189151", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-background-process-stop"}
- {"dt": "2023-12-15T22:11:43.190503", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-common-functions"}
- {"dt": "2023-12-15T22:11:43.191851", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-echo-ipv4"}
- {"dt": "2023-12-15T22:11:43.193077", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-install-packages"}
- {"dt": "2023-12-15T22:11:43.194266", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-installed-packages"}
- {"dt": "2023-12-15T22:11:43.195518", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-os-build"}
- {"dt": "2023-12-15T22:11:43.196903", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-probe-channel"}
- {"dt": "2023-12-15T22:11:43.198218", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-probe-ip"}
- {"dt": "2023-12-15T22:11:43.199536", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-target-ip"}
- {"dt": "2023-12-15T22:11:43.200844", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-target-mac"}
- {"dt": "2023-12-15T22:11:43.202060", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-target-storage"}
- {"dt": "2023-12-15T22:11:43.203359", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-test-case"}
- {"dt": "2023-12-15T22:11:43.204708", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-test-event"}
- {"dt": "2023-12-15T22:11:43.206157", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-test-feedback"}
- {"dt": "2023-12-15T22:11:43.207553", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-test-raise"}
- {"dt": "2023-12-15T22:11:43.208882", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-test-reference"}
- {"dt": "2023-12-15T22:11:43.210221", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-test-runner"}
- {"dt": "2023-12-15T22:11:43.211627", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-test-set"}
- {"dt": "2023-12-15T22:11:43.212963", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-test-shell"}
- {"dt": "2023-12-15T22:11:43.214351", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-15T22:11:43.215876", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-15T22:11:43.217170", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/environment"}
- {"dt": "2023-12-15T22:11:43.218245", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-15T22:11:43.219011", "lvl": "debug", "msg": "- LAVA_JOB_ID=34356"}
- {"dt": "2023-12-15T22:11:43.219694", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-15T22:11:43.220633", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.222881", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:43.223841", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.224433", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-15T22:11:43.225161", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:43.225820", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.226326", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-15T22:11:43.226940", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:43.227716", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.228380", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-15T22:11:43.229152", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.229881", "lvl": "debug", "msg": "Using /lava-34356 at stage 0"}
- {"dt": "2023-12-15T22:11:43.230796", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T22:11:43.231573", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/0/tests/0_network-veth'"}
- {"dt": "2023-12-15T22:11:43.848737", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-15T22:11:43.853335", "lvl": "debug", "msg": "uuid=34356_1.5.4.1 testdef=None"}
- {"dt": "2023-12-15T22:11:43.854578", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:11:43.855470", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.63", "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-12-15T22:11:43.857736", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.863827", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:43.864624", "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": "34356_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:43.866516", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.873724", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:43.874489", "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": "34356_1.5.4.1", "uuid": "34356_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:43.876607", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.883073", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/0/tests/0_network-veth test_uuid 34356_1.5.4.1"}
- {"dt": "2023-12-15T22:11:43.884772", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:43.885513", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/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": "34356_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:43.887114", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:59) [common]"}
- {"dt": "2023-12-15T22:11:43.888298", "lvl": "debug", "msg": "Using /lava-34356 at stage 1"}
- {"dt": "2023-12-15T22:11:43.889440", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T22:11:43.890260", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-15T22:11:44.472537", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/1_ci-busybox"}
- {"dt": "2023-12-15T22:11:44.485718", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-15T22:11:44.489376", "lvl": "debug", "msg": "uuid=34356_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-12-15T22:11:44.490593", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:11:44.491454", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.60", "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-12-15T22:11:44.492943", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-15T22:11:44.498790", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:44.499591", "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": "34356_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:44.501121", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-15T22:11:44.509279", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:44.509998", "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": "34356_1.5.4.5", "uuid": "34356_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:44.511680", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:58) [common]"}
- {"dt": "2023-12-15T22:11:44.519222", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/1_ci-busybox test_uuid 34356_1.5.4.5"}
- {"dt": "2023-12-15T22:11:44.520866", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:44.521584", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/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": "34356_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:44.523174", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:58) [common]"}
- {"dt": "2023-12-15T22:11:44.523944", "lvl": "debug", "msg": "Using /lava-34356 at stage 1"}
- {"dt": "2023-12-15T22:11:44.524988", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T22:11:44.525772", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-15T22:11:45.135054", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-15T22:11:45.150175", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-15T22:11:45.153153", "lvl": "debug", "msg": "uuid=34356_1.5.4.9 testdef=None"}
- {"dt": "2023-12-15T22:11:45.154247", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:11:45.155024", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.63", "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-12-15T22:11:45.156617", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-15T22:11:45.161844", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:45.162561", "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": "34356_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:45.164154", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-15T22:11:45.171693", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:45.172409", "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": "34356_1.5.4.9", "uuid": "34356_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:45.173989", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-15T22:11:45.180819", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/2_ci-smoke-tests-basic test_uuid 34356_1.5.4.9"}
- {"dt": "2023-12-15T22:11:45.183637", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:45.184392", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/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": "34356_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:45.186004", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:57) [common]"}
- {"dt": "2023-12-15T22:11:45.186748", "lvl": "debug", "msg": "Using /lava-34356 at stage 1"}
- {"dt": "2023-12-15T22:11:45.187838", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T22:11:45.188614", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-15T22:11:45.794119", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/3_ci-service-check"}
- {"dt": "2023-12-15T22:11:45.806667", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-15T22:11:45.810623", "lvl": "debug", "msg": "uuid=34356_1.5.4.13 testdef=None"}
- {"dt": "2023-12-15T22:11:45.811777", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:11:45.812474", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.63", "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-12-15T22:11:45.813786", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-15T22:11:45.819938", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:45.820724", "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": "34356_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:45.822869", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-15T22:11:45.830865", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:45.831617", "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": "34356_1.5.4.13", "uuid": "34356_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:45.833255", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-12-15T22:11:45.841254", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1/tests/3_ci-service-check test_uuid 34356_1.5.4.13"}
- {"dt": "2023-12-15T22:11:45.842033", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-15T22:11:45.842534", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-15T22:11:45.843897", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:45.844637", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/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": "34356_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:45.846609", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:57) [common]"}
- {"dt": "2023-12-15T22:11:45.847442", "lvl": "debug", "msg": "Using /lava-34356 at stage 2"}
- {"dt": "2023-12-15T22:11:45.848395", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T22:11:45.849094", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/2/tests/4_screenshooter'"}
- {"dt": "2023-12-15T22:11:46.441367", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-15T22:11:46.445207", "lvl": "debug", "msg": "uuid=34356_1.5.4.17 testdef=None"}
- {"dt": "2023-12-15T22:11:46.446428", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:11:46.447314", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.60", "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-12-15T22:11:46.448898", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:56) [common]"}
- {"dt": "2023-12-15T22:11:46.453989", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:46.454755", "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": "34356_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:46.456481", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:56) [common]"}
- {"dt": "2023-12-15T22:11:46.462928", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:46.463738", "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": "34356_1.5.4.17", "uuid": "34356_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:46.465411", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:56) [common]"}
- {"dt": "2023-12-15T22:11:46.471734", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/2/tests/4_screenshooter test_uuid 34356_1.5.4.17"}
- {"dt": "2023-12-15T22:11:46.473438", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:46.474165", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/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": "34356_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:46.475876", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:56) [common]"}
- {"dt": "2023-12-15T22:11:46.476676", "lvl": "debug", "msg": "Using /lava-34356 at stage 3"}
- {"dt": "2023-12-15T22:11:46.477757", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-15T22:11:46.478605", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-15T22:11:47.081091", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-15T22:11:47.091537", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-15T22:11:47.094814", "lvl": "debug", "msg": "uuid=34356_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-12-15T22:11:47.095997", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:11:47.096762", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.62", "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-12-15T22:11:47.098272", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-15T22:11:47.104470", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.105263", "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": "34356_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:47.106831", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-15T22:11:47.115239", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.115966", "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": "34356_1.5.4.21", "uuid": "34356_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:47.117667", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-15T22:11:47.125446", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/3/tests/5_ci-dumpjournal test_uuid 34356_1.5.4.21"}
- {"dt": "2023-12-15T22:11:47.127048", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.127828", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/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": "34356_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:47.129377", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-15T22:11:47.130026", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/0 for stage 0"}
- {"dt": "2023-12-15T22:11:47.131022", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-15T22:11:47.131974", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/1 for stage 1"}
- {"dt": "2023-12-15T22:11:47.133028", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-15T22:11:47.133737", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-15T22:11:47.134350", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-15T22:11:47.135173", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/2 for stage 2"}
- {"dt": "2023-12-15T22:11:47.136341", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-15T22:11:47.137170", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34356/lava-overlay-s_psuo87/lava-34356/3 for stage 3"}
- {"dt": "2023-12-15T22:11:47.138058", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-15T22:11:47.139012", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:04) [common]"}
- {"dt": "2023-12-15T22:11:47.139849", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-15T22:11:47.856550", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:11:47.857467", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:55) [common]"}
- {"dt": "2023-12-15T22:11:47.858629", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.859521", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:05) [common]"}
- {"dt": "2023-12-15T22:11:47.860285", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:55) [common]"}
- {"dt": "2023-12-15T22:11:47.860965", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:55) [common]"}
- {"dt": "2023-12-15T22:11:47.861675", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.862292", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.862918", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:55) [common]"}
- {"dt": "2023-12-15T22:11:47.863569", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-15T22:11:47.864127", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61999, NBD-ROOT: 34356/nbd-deploy-axp5wzl2/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-15T22:11:47.867595", "lvl": "debug", "msg": "nbd-server 61999 /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-15T22:11:47.890212", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-15T22:11:47.892793", "lvl": "debug", "msg": "output: ** (process:15285): WARNING **: 22:11:47.888: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-15T22:11:47.893558", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-15T22:11:47.894170", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-15T22:11:47.895556", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.896838", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:05) [common]"}
- {"dt": "2023-12-15T22:11:47.898164", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-15T22:11:47.899531", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-15T22:11:47.900597", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.901607", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-15T22:11:47.903406", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-15T22:11:47.904173", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-15T22:11:47.904852", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-15T22:11:47.905437", "lvl": "debug", "msg": "- {DTB}: 34356/nbd-deploy-axp5wzl2/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T22:11:47.906144", "lvl": "debug", "msg": "- {INITRD}: 34356/nbd-deploy-axp5wzl2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T22:11:47.906796", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-15T22:11:47.907407", "lvl": "debug", "msg": "- {KERNEL}: 34356/nbd-deploy-axp5wzl2/kernel/Image"}
- {"dt": "2023-12-15T22:11:47.908016", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-15T22:11:47.908569", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-15T22:11:47.909115", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61999"}
- {"dt": "2023-12-15T22:11:47.909645", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-15T22:11:47.910170", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-15T22:11:47.910705", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-15T22:11:47.911291", "lvl": "debug", "msg": "- {RAMDISK}: 34356/nbd-deploy-axp5wzl2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T22:11:47.911908", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-15T22:11:47.912585", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-15T22:11:47.913088", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-15T22:11:47.913665", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-15T22:11:47.914226", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-15T22:11:47.914758", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-15T22:11:47.915329", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-15T22:11:47.915887", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-15T22:11:47.916408", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-15T22:11:47.916965", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-15T22:11:47.917495", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-15T22:11:47.918029", "lvl": "info", "msg": "- tftp 0x68080000 34356/nbd-deploy-axp5wzl2/kernel/Image"}
- {"dt": "2023-12-15T22:11:47.918689", "lvl": "info", "msg": "- tftp 0x4a000000 34356/nbd-deploy-axp5wzl2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T22:11:47.919309", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-15T22:11:47.919903", "lvl": "info", "msg": "- tftp 0x68000000 34356/nbd-deploy-axp5wzl2/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T22:11:47.920489", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 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-12-15T22:11:47.921083", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-15T22:11:47.921826", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-15T22:11:47.922513", "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-12-15T22:11:47.924163", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-15T22:11:47.925005", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-15T22:11:51.069720", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-15T22:11:51.072863", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-12-15T22:11:51.073967", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-12-15T22:11:51.074870", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-12-15T22:11:51.075889", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-12-15T22:11:51.077198", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-15T22:11:54.544644", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-15T22:11:55.615048", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-15T22:11:55.650750", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-12-15T22:11:55.752697", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-12-15T22:11:55.753776", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.68", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:11:55.755956", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-15T22:11:55.757015", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-12-15T22:11:55.757870", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-15T22:11:55.758650", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-15T22:11:55.760548", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-15T22:11:55.761322", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-15T22:11:55.761863", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-15T22:11:55.827731", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Initial\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:55.828932", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T22:11:55.871572", "lvl": "target", "msg": "NOTICE:  BL2: Board\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:55.872819", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T22:11:55.973806", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0\0NOTICE:  BL2: R-Car Gen3 Initial \uFFFD\0\0\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:56.016256", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:56.017035", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T22:11:56.063803", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starte\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:56.064572", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T22:11:56.067284", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T22:11:56.113063", "lvl": "target", "msg": "\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:56.113812", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T22:11:56.116592", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T22:11:56.166196", "lvl": "target", "msg": "NOTICE:  BL2: Boot device i\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:56.166968", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T22:11:56.168034", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T22:11:56.169600", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-15T22:11:56.227277", "lvl": "target", "msg": "NOTICE:  BL2: LCM \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:56.228028", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T22:11:56.228571", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T22:11:56.229152", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-15T22:11:56.229722", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-15T22:11:56.230876", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-15T22:11:56.295035", "lvl": "target", "msg": "NOTICE:  BL2: DDR32\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-15T22:11:56.295885", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-15T22:11:56.296569", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-15T22:11:56.297340", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-15T22:11:56.298019", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-15T22:11:56.298718", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-15T22:11:56.299376", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-15T22:11:56.300039", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-15T22:11:56.341736", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-15T22:11:56.342524", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-15T22:11:56.343110", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-15T22:11:56.343734", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-15T22:11:56.344265", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-15T22:11:56.344772", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-15T22:11:56.345322", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-15T22:11:56.345807", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-15T22:11:56.346303", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-15T22:11:56.346807", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-15T22:11:56.347924", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-15T22:11:56.384436", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-15T22:11:56.385250", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-15T22:11:56.385891", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-15T22:11:56.386454", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-15T22:11:56.386981", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-15T22:11:56.387596", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-15T22:11:56.388708", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-15T22:11:56.423455", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-15T22:11:56.424251", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-15T22:11:56.426901", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-15T22:11:56.755204", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T22:11:56.756013", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T22:11:56.756704", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-15T22:11:56.757313", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T22:11:56.758769", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-15T22:11:56.759715", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-15T22:11:56.949620", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-15T22:11:56.974006", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-15T22:11:56.974801", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-15T22:11:56.975900", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-15T22:11:56.977555", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-15T22:11:56.978335", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-15T22:11:56.978982", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T22:11:57.027910", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-15T22:11:57.193890", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-15T22:11:57.194675", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-15T22:11:57.195351", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-15T22:11:57.197144", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-15T22:11:57.243928", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-15T22:11:57.260376", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-15T22:11:57.311601", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-12-15T22:11:57.312732", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-15T22:11:57.313722", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:51) [common]"}
- {"dt": "2023-12-15T22:11:57.314518", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-15T22:11:57.315355", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)"}
- {"dt": "2023-12-15T22:11:57.340013", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-12-15T22:11:57.341567", "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-12-15T22:11:57.342430", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-15T22:11:57.443972", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-15T22:11:57.444888", "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:50)"}
- {"dt": "2023-12-15T22:11:57.451904", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-15T22:11:57.453211", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-15T22:11:57.554808", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-15T22:11:57.556098", "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:50)"}
- {"dt": "2023-12-15T22:11:57.563950", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-15T22:11:57.565156", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-15T22:11:57.666728", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-15T22:11:57.667967", "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:50)"}
- {"dt": "2023-12-15T22:11:57.675760", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-15T22:11:57.676835", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-15T22:11:57.778398", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-15T22:11:57.779417", "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:50)"}
- {"dt": "2023-12-15T22:11:57.787751", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-15T22:12:00.936974", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete....... done"}
- {"dt": "2023-12-15T22:12:00.937762", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-15T22:12:01.192792", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-15T22:12:01.688798", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-15T22:12:01.960408", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1028 ms)"}
- {"dt": "2023-12-15T22:12:01.962148", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-15T22:12:02.064483", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-15T22:12:02.065798", "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:46)"}
- {"dt": "2023-12-15T22:12:02.071924", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-15T22:12:02.073166", "lvl": "input", "msg": "tftp 0x68080000 34356/nbd-deploy-axp5wzl2/kernel/Image\n"}
- {"dt": "2023-12-15T22:12:02.174942", "lvl": "target", "msg": "=> tftp 0x68080000 34356/nbd-deploy-axp5wzl2/kernel/Image"}
- {"dt": "2023-12-15T22:12:02.176508", "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:46)"}
- {"dt": "2023-12-15T22:12:02.184706", "lvl": "target", "msg": "tftp 0x68080000 34356/nbd-deploy-axp5wzl2/kernel/Image"}
- {"dt": "2023-12-15T22:12:02.208890", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-15T22:12:02.209651", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-15T22:12:02.212119", "lvl": "target", "msg": "Filename '34356/nbd-deploy-axp5wzl2/kernel/Image'."}
- {"dt": "2023-12-15T22:12:02.213010", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-15T22:12:02.707641", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-15T22:12:03.251054", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:03.778583", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:04.305233", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:04.817016", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:05.329565", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:05.824277", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:06.351665", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:06.847341", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:07.327265", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:07.790940", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:08.286768", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:08.750307", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:09.198028", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:09.693435", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:10.253487", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:10.812882", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:11.372621", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:11.916315", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:12.459619", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:13.003728", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:13.562787", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:14.106552", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:14.650135", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:15.130533", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:15.609450", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:16.153888", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:17.432296", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:18.231229", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:18.823033", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:19.366093", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:19.909641", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:20.437845", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:20.997793", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:21.667840", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:22.259667", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:22.834872", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:23.425951", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:24.001839", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:24.593204", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:25.808149", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:25.962075", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-15T22:12:25.963508", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-15T22:12:25.964490", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-15T22:12:25.966025", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-15T22:12:25.967899", "lvl": "input", "msg": "tftp 0x4a000000 34356/nbd-deploy-axp5wzl2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-15T22:12:26.070049", "lvl": "target", "msg": "=> tftp 0x4a000000 34356/nbd-deploy-axp5wzl2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T22:12:26.071538", "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:22)"}
- {"dt": "2023-12-15T22:12:26.076153", "lvl": "target", "msg": "tftp 0x4a000000 34356/nbd-deploy-axp5wzl2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-15T22:12:26.107768", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-15T22:12:26.109253", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-15T22:12:26.110782", "lvl": "target", "msg": "Filename '34356/nbd-deploy-axp5wzl2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-15T22:12:26.112471", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-15T22:12:27.420922", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-15T22:12:28.012394", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-15T22:12:28.118983", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-15T22:12:28.120999", "lvl": "target", "msg": "\t 1004.9 KiB/s"}
- {"dt": "2023-12-15T22:12:28.122792", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-15T22:12:28.125550", "lvl": "target", "msg": "Bytes transferred = 2061485 (1f74ad hex)"}
- {"dt": "2023-12-15T22:12:28.127833", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-15T22:12:28.229221", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-15T22:12:28.231050", "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:20)"}
- {"dt": "2023-12-15T22:12:28.249018", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-15T22:12:28.251277", "lvl": "input", "msg": "tftp 0x68000000 34356/nbd-deploy-axp5wzl2/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-15T22:12:28.353987", "lvl": "target", "msg": "=> tftp 0x68000000 34356/nbd-deploy-axp5wzl2/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T22:12:28.356772", "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:20)"}
- {"dt": "2023-12-15T22:12:28.361305", "lvl": "target", "msg": "tftp 0x68000000 34356/nbd-deploy-axp5wzl2/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-15T22:12:28.393539", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-15T22:12:28.395871", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-15T22:12:28.397847", "lvl": "target", "msg": "Filename '34356/nbd-deploy-axp5wzl2/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-15T22:12:28.399477", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-15T22:12:28.455630", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-15T22:12:28.457032", "lvl": "target", "msg": "\t 1.4 MiB/s"}
- {"dt": "2023-12-15T22:12:28.458258", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-15T22:12:28.459676", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-15T22:12:28.461831", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 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-12-15T22:12:28.564625", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 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-12-15T22:12:28.566961", "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-12-15T22:12:28.585667", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61999 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-12-15T22:12:28.588097", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-15T22:12:28.690272", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-15T22:12:28.692546", "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-12-15T22:12:28.694598", "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:19)"}
- {"dt": "2023-12-15T22:12:28.698541", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-15T22:12:28.714054", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-15T22:12:28.859838", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-15T22:12:28.862226", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-15T22:12:28.864201", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff4ad ... OK"}
- {"dt": "2023-12-15T22:12:28.865960", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-15T22:12:28.867901", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-15T22:12:28.869657", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-15T22:12:28.871568", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-15T22:12:28.873321", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-15T22:12:28.875060", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-15T22:12:28.904439", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-15T22:12:28.905626", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-15T22:12:28.906692", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-15T22:12:28.907768", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-15T22:12:28.908769", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-15T22:12:28.909744", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-15T22:12:28.910914", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-15T22:12:28.911985", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-15T22:12:28.912968", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-15T22:12:28.913953", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-15T22:12:28.914937", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-15T22:12:28.925635", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-15T22:12:28.926288", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T22:12:28.929290", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-15T22:12:28.930303", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-15T22:12:28.931250", "lvl": "target", "msg": ""}
- {"dt": "2023-12-15T22:12:28.932876", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-15T22:12:28.933961", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:32) [common]"}
- {"dt": "2023-12-15T22:12:28.935037", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "31.62", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-15T22:12:28.937004", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-15T22:12:28.938039", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:38) [common]"}
- {"dt": "2023-12-15T22:12:28.939255", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "37.86", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-15T22:12:28.940449", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-15T22:12:28.941154", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-15T22:12:28.941732", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:41) [common]"}
- {"dt": "2023-12-15T22:12:28.942269", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "41.04", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-15T22:12:28.943517", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-15T22:12:28.944214", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/initrd"}
- {"dt": "2023-12-15T22:12:28.945980", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/kernel"}
- {"dt": "2023-12-15T22:12:28.955269", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/dtb"}
- {"dt": "2023-12-15T22:12:28.956760", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34356/nbd-deploy-axp5wzl2/nbdroot"}
- {"dt": "2023-12-15T22:12:29.255215", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-15T22:12:29.256404", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-15T22:12:32.669595", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-15T22:12:32.706787", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-15T22:12:32.810987", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-15T22:12:32.813041", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.56", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-15T22:12:32.816907", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-15T22:12:32.821079", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-15T22:12:32.823617", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.824283", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.824832", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.825370", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.825882", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.826399", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.826910", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.827477", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.828005", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.828519", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.829033", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.829544", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.830054", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.830555", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.831055", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.831588", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.832092", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.832594", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.833093", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.833595", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.834104", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.834603", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.835105", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.835638", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.836143", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.836691", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.837222", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.837739", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.838249", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.838753", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.839304", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.839804", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.840291", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.840779", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.841265", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.841766", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.842265", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.842754", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.843272", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.843768", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.844257", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.844750", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.845236", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.845739", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.846247", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.846748", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.847283", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.847789", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.848283", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-15T22:12:32.849101", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-15T22:12:33.824585", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-15T22:12:33.826892", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-15T22:12:33.828780", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-15T22:12:33.932300", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-15T22:12:33.934419", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-15T22:12:33.936305", "lvl": "debug", "msg": "clean NBD port 61999"}
- {"dt": "2023-12-15T22:12:37.205550", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34356"}
- {"dt": "2023-12-15T22:12:37.296101", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34356"}
- {"dt": "2023-12-15T22:12:37.297265", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-15T22:12:37.297977", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
