- {"dt": "2023-12-11T22:03:13.280223", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-12-11T22:03:13.281530", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-12-11T22:03:13.282550", "lvl": "info", "msg": "Start time: 2023-12-11 22:03:13.282495+00:00 (UTC)"}
- {"dt": "2023-12-11T22:03:13.283745", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29504/3/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-12-11T22:03:13.699714", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29504/3/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-12-11T22:03:13.924278", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29504/3/h3ulcb-nogfx/r8a77951-ulcb.dtb exists"}
- {"dt": "2023-12-11T22:03:14.138958", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/29504/3/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-12-11T22:03:14.376205", "lvl": "info", "msg": "validate duration: 1.09"}
- {"dt": "2023-12-11T22:03:14.376998", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-12-11T22:03:14.378165", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-12-11T22:03:14.378832", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-12-11T22:03:14.379537", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-12-11T22:03:14.380128", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-12-11T22:03:14.381122", "lvl": "debug", "msg": "Set_port 61977"}
- {"dt": "2023-12-11T22:03:14.382016", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-12-11T22:03:14.382826", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-12-11T22:03:14.384111", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29504/3/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-11T22:03:14.384812", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-11T22:03:14.385432", "lvl": "debug", "msg": "total size: 2061467 (1MB)"}
- {"dt": "2023-12-11T22:03:14.385999", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-11T22:03:14.688621", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-12-11T22:03:14.890593", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-12-11T22:03:14.986450", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-12-11T22:03:14.996207", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-12-11T22:03:15.086649", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-12-11T22:03:15.093592", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-12-11T22:03:15.189865", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-12-11T22:03:15.195083", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-12-11T22:03:15.200692", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-12-11T22:03:15.205100", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-12-11T22:03:15.209570", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-12-11T22:03:15.279651", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-12-11T22:03:15.292332", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-12-11T22:03:15.297885", "lvl": "debug", "msg": "progress  71% (1MB)"}
- {"dt": "2023-12-11T22:03:15.302270", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-12-11T22:03:15.306654", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-12-11T22:03:15.312230", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-12-11T22:03:15.316626", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-12-11T22:03:15.322152", "lvl": "debug", "msg": "progress  98% (1MB)"}
- {"dt": "2023-12-11T22:03:15.325709", "lvl": "info", "msg": "1MB downloaded in 0.94s (2.09MB/s)"}
- {"dt": "2023-12-11T22:03:15.326966", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:03:15.327721", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.94", "extra": {"label": "initrd", "md5sum": "7a7c16b466e60909892e1479ca377014", "sha256sum": "b2c1bdf8dfb2d2e72204f218d2d49eba83a908656dbc0e04fa6e21117b4c41ad", "sha512sum": "a1cb7623d49fd0e493aa0656c20ccb3b41a0c566bf9e0da37d5e36ac96916c2c041b998c93e88a2ef01b2b3ddccd82906e18dbbf0cdf60b7268a362f4b769443", "size": ! "2061467"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:03:15.329197", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:03:15.330030", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-12-11T22:03:15.330825", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-12-11T22:03:15.331967", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29504/3/h3ulcb-nogfx/Image"}
- {"dt": "2023-12-11T22:03:15.332649", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/kernel/Image"}
- {"dt": "2023-12-11T22:03:15.333221", "lvl": "debug", "msg": "total size: 39207424 (37MB)"}
- {"dt": "2023-12-11T22:03:15.333768", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-11T22:03:15.652656", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-11T22:03:16.393430", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-12-11T22:03:16.552271", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-12-11T22:03:16.635953", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-12-11T22:03:16.788982", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-12-11T22:03:16.913296", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-12-11T22:03:17.031112", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-12-11T22:03:17.149388", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-12-11T22:03:17.263516", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-12-11T22:03:17.420246", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-12-11T22:03:17.538194", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-12-11T22:03:17.653586", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-12-11T22:03:17.767413", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-12-11T22:03:17.877488", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-12-11T22:03:17.989738", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-12-11T22:03:18.119262", "lvl": "debug", "msg": "progress  75% (28MB)"}
- {"dt": "2023-12-11T22:03:18.244690", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-12-11T22:03:18.358644", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-12-11T22:03:18.467525", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-12-11T22:03:18.575363", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-12-11T22:03:18.681103", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-12-11T22:03:18.682628", "lvl": "info", "msg": "37MB downloaded in 3.35s (11.16MB/s)"}
- {"dt": "2023-12-11T22:03:18.683828", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:03) [common]"}
- {"dt": "2023-12-11T22:03:18.684516", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "3.35", "extra": {"label": "kernel", "md5sum": "67bfab12e96116ab670f028caec3573c", "sha256sum": "e30f14c64900849b17441f15e1578a685afa29566827ee43d2e0c94e58a73561", "sha512sum": "37d836f1471d791eb9e265b170ac123ce0548b1cb590a8511f2ce6476e17d2faa0df169055f6bd17cd10b942795f605950bc68d5169b3277cd4a24f11d672038", "size": ! "39207424"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:03:18.685976", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:03) [common]"}
- {"dt": "2023-12-11T22:03:18.686811", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:56) [common]"}
- {"dt": "2023-12-11T22:03:18.687673", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:56) [common]"}
- {"dt": "2023-12-11T22:03:18.688788", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29504/3/h3ulcb-nogfx/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-11T22:03:18.689481", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-11T22:03:18.690066", "lvl": "debug", "msg": "total size: 82960 (0MB)"}
- {"dt": "2023-12-11T22:03:18.690628", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-12-11T22:03:19.009660", "lvl": "debug", "msg": "progress  39% (0MB)"}
- {"dt": "2023-12-11T22:03:19.112904", "lvl": "debug", "msg": "progress  78% (0MB)"}
- {"dt": "2023-12-11T22:03:19.119777", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-12-11T22:03:19.123815", "lvl": "info", "msg": "0MB downloaded in 0.43s (0.18MB/s)"}
- {"dt": "2023-12-11T22:03:19.124958", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:03:19.125676", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.44", "extra": {"label": "dtb", "md5sum": "b7b77a5bcd5ad3e5af64677b0833c507", "sha256sum": "7b589087974b9e9d0de191cb371345189ba4ef640b13944ea28ba49d078c1789", "sha512sum": "ebcb6657499258a45ce691359360493900603f1d08f640603ee9f5b4a642e5a3a35d29e4ba0c01a23c59eb57fb879b62bfa31433bb9d00bd0aefc03fc3adf229", "size": ! "82960"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:03:19.127170", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:03:19.127977", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:55) [common]"}
- {"dt": "2023-12-11T22:03:19.128762", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:55) [common]"}
- {"dt": "2023-12-11T22:03:19.129842", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/29504/3/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-12-11T22:03:19.130534", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-11T22:03:19.131114", "lvl": "debug", "msg": "total size: 454091748 (433MB)"}
- {"dt": "2023-12-11T22:03:19.131744", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-12-11T22:03:19.469524", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-12-11T22:03:25.352363", "lvl": "debug", "msg": "progress   5% (21MB)"}
- {"dt": "2023-12-11T22:03:29.486964", "lvl": "debug", "msg": "progress  10% (43MB)"}
- {"dt": "2023-12-11T22:03:34.499669", "lvl": "debug", "msg": "progress  15% (64MB)"}
- {"dt": "2023-12-11T22:03:38.615079", "lvl": "debug", "msg": "progress  20% (86MB)"}
- {"dt": "2023-12-11T22:03:43.739386", "lvl": "debug", "msg": "progress  25% (108MB)"}
- {"dt": "2023-12-11T22:03:50.527635", "lvl": "debug", "msg": "progress  30% (129MB)"}
- {"dt": "2023-12-11T22:04:00.037159", "lvl": "debug", "msg": "progress  35% (151MB)"}
- {"dt": "2023-12-11T22:04:10.751800", "lvl": "debug", "msg": "progress  40% (173MB)"}
- {"dt": "2023-12-11T22:04:21.960031", "lvl": "debug", "msg": "progress  45% (194MB)"}
- {"dt": "2023-12-11T22:04:29.279791", "lvl": "debug", "msg": "progress  50% (216MB)"}
- {"dt": "2023-12-11T22:04:36.835634", "lvl": "debug", "msg": "progress  55% (238MB)"}
- {"dt": "2023-12-11T22:04:44.915878", "lvl": "debug", "msg": "progress  60% (259MB)"}
- {"dt": "2023-12-11T22:04:51.328994", "lvl": "debug", "msg": "progress  65% (281MB)"}
- {"dt": "2023-12-11T22:04:59.415619", "lvl": "debug", "msg": "progress  70% (303MB)"}
- {"dt": "2023-12-11T22:05:09.975848", "lvl": "debug", "msg": "progress  75% (324MB)"}
- {"dt": "2023-12-11T22:05:17.088015", "lvl": "debug", "msg": "progress  80% (346MB)"}
- {"dt": "2023-12-11T22:05:24.181110", "lvl": "debug", "msg": "progress  85% (368MB)"}
- {"dt": "2023-12-11T22:05:31.623690", "lvl": "debug", "msg": "progress  90% (389MB)"}
- {"dt": "2023-12-11T22:05:37.019767", "lvl": "debug", "msg": "progress  95% (411MB)"}
- {"dt": "2023-12-11T22:05:46.483937", "lvl": "debug", "msg": "progress 100% (433MB)"}
- {"dt": "2023-12-11T22:05:51.390428", "lvl": "info", "msg": "433MB downloaded in 152.26s (2.84MB/s)"}
- {"dt": "2023-12-11T22:05:51.392609", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:02:32) [common]"}
- {"dt": "2023-12-11T22:05:51.393508", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "152.26", "extra": {"label": "nbdroot", "md5sum": "27640258af1b00307bd24ecf857aaede", "sha256sum": "318761524ca3679b23aa8195d8e15e6d7fdc72c0bca485dc7998ec7c78a609a3", "sha512sum": "b435541193465a6bc02c190da72f18be4b396ecfe34e4b2c85b2fd9db058655aff9b41079ad47c6fa1d7c97ee5fa3b76af87698b9266191f1ea19a5407ea6455", "size": ! "454091748"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:51.397313", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:02:32) [common]"}
- {"dt": "2023-12-11T22:05:51.398379", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-12-11T22:05:51.400712", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw"}
- {"dt": "2023-12-11T22:05:51.402147", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin"}
- {"dt": "2023-12-11T22:05:51.403880", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/tests"}
- {"dt": "2023-12-11T22:05:51.405103", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/results"}
- {"dt": "2023-12-11T22:05:51.406272", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-add-keys"}
- {"dt": "2023-12-11T22:05:51.410048", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-add-sources"}
- {"dt": "2023-12-11T22:05:51.411749", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-background-process-start"}
- {"dt": "2023-12-11T22:05:51.413365", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-background-process-stop"}
- {"dt": "2023-12-11T22:05:51.414908", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-common-functions"}
- {"dt": "2023-12-11T22:05:51.417201", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-echo-ipv4"}
- {"dt": "2023-12-11T22:05:51.418821", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-install-packages"}
- {"dt": "2023-12-11T22:05:51.420986", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-installed-packages"}
- {"dt": "2023-12-11T22:05:51.422558", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-os-build"}
- {"dt": "2023-12-11T22:05:51.426130", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-probe-channel"}
- {"dt": "2023-12-11T22:05:51.427832", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-probe-ip"}
- {"dt": "2023-12-11T22:05:51.429484", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-target-ip"}
- {"dt": "2023-12-11T22:05:51.431020", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-target-mac"}
- {"dt": "2023-12-11T22:05:51.433375", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-target-storage"}
- {"dt": "2023-12-11T22:05:51.434934", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-test-case"}
- {"dt": "2023-12-11T22:05:51.438469", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-test-event"}
- {"dt": "2023-12-11T22:05:51.440137", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-test-feedback"}
- {"dt": "2023-12-11T22:05:51.441709", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-test-raise"}
- {"dt": "2023-12-11T22:05:51.445406", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-test-reference"}
- {"dt": "2023-12-11T22:05:51.447051", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-test-runner"}
- {"dt": "2023-12-11T22:05:51.448679", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-test-set"}
- {"dt": "2023-12-11T22:05:51.450234", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-test-shell"}
- {"dt": "2023-12-11T22:05:51.452645", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-install-packages (oe)"}
- {"dt": "2023-12-11T22:05:51.454449", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/bin/lava-installed-packages (oe)"}
- {"dt": "2023-12-11T22:05:51.456341", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/environment"}
- {"dt": "2023-12-11T22:05:51.457608", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-12-11T22:05:51.458464", "lvl": "debug", "msg": "- LAVA_JOB_ID=34128"}
- {"dt": "2023-12-11T22:05:51.461262", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-12-11T22:05:51.462569", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:27:23) [common]"}
- {"dt": "2023-12-11T22:05:51.465441", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:51.466503", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-12-11T22:05:51.468146", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-12-11T22:05:51.469114", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:51.470091", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:27:23) [common]"}
- {"dt": "2023-12-11T22:05:51.470860", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-12-11T22:05:51.473956", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:51.474957", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:27:23) [common]"}
- {"dt": "2023-12-11T22:05:51.475941", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-12-11T22:05:51.477017", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:27:23) [common]"}
- {"dt": "2023-12-11T22:05:51.477900", "lvl": "debug", "msg": "Using /lava-34128 at stage 0"}
- {"dt": "2023-12-11T22:05:51.479017", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-11T22:05:51.480941", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/0/tests/0_network-veth'"}
- {"dt": "2023-12-11T22:05:52.541574", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2023-12-11T22:05:52.546927", "lvl": "debug", "msg": "uuid=34128_1.5.4.1 testdef=None"}
- {"dt": "2023-12-11T22:05:52.553784", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:05:52.554582", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.08", "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-11T22:05:52.560940", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-12-11T22:05:52.566258", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:52.566985", "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": "34128_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:52.570279", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-12-11T22:05:52.578419", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:52.580607", "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": "34128_1.5.4.1", "uuid": "34128_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:52.582424", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:22) [common]"}
- {"dt": "2023-12-11T22:05:52.590342", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/0/tests/0_network-veth test_uuid 34128_1.5.4.1"}
- {"dt": "2023-12-11T22:05:52.592217", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:52.592999", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/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": "34128_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:52.594658", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:27:22) [common]"}
- {"dt": "2023-12-11T22:05:52.597061", "lvl": "debug", "msg": "Using /lava-34128 at stage 1"}
- {"dt": "2023-12-11T22:05:52.598233", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-11T22:05:52.599006", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/1_ci-busybox'"}
- {"dt": "2023-12-11T22:05:53.491645", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/1_ci-busybox"}
- {"dt": "2023-12-11T22:05:53.506209", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2023-12-11T22:05:53.509737", "lvl": "debug", "msg": "uuid=34128_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-11T22:05:53.511714", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:05:53.512610", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.92", "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-11T22:05:53.514112", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:27:21) [common]"}
- {"dt": "2023-12-11T22:05:53.522421", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:53.525049", "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": "34128_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:53.526754", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:27:21) [common]"}
- {"dt": "2023-12-11T22:05:53.537666", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:53.540140", "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": "34128_1.5.4.5", "uuid": "34128_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:53.541954", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:27:21) [common]"}
- {"dt": "2023-12-11T22:05:53.552440", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/1_ci-busybox test_uuid 34128_1.5.4.5"}
- {"dt": "2023-12-11T22:05:53.554128", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:53.554864", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/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": "34128_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:53.558557", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:27:21) [common]"}
- {"dt": "2023-12-11T22:05:53.559390", "lvl": "debug", "msg": "Using /lava-34128 at stage 1"}
- {"dt": "2023-12-11T22:05:53.560473", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-11T22:05:53.561297", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2023-12-11T22:05:54.423787", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2023-12-11T22:05:54.438827", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2023-12-11T22:05:54.441936", "lvl": "debug", "msg": "uuid=34128_1.5.4.9 testdef=None"}
- {"dt": "2023-12-11T22:05:54.443055", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:05:54.445813", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.88", "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-11T22:05:54.447463", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:27:20) [common]"}
- {"dt": "2023-12-11T22:05:54.455238", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:54.457755", "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": "34128_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:54.459530", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:27:20) [common]"}
- {"dt": "2023-12-11T22:05:54.469177", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:54.471742", "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": "34128_1.5.4.9", "uuid": "34128_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:54.473514", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:27:20) [common]"}
- {"dt": "2023-12-11T22:05:54.482584", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/2_ci-smoke-tests-basic test_uuid 34128_1.5.4.9"}
- {"dt": "2023-12-11T22:05:54.486995", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:54.489492", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/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": "34128_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:54.491646", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:27:20) [common]"}
- {"dt": "2023-12-11T22:05:54.492493", "lvl": "debug", "msg": "Using /lava-34128 at stage 1"}
- {"dt": "2023-12-11T22:05:54.493531", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-11T22:05:54.494317", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/3_ci-service-check'"}
- {"dt": "2023-12-11T22:05:55.511985", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/3_ci-service-check"}
- {"dt": "2023-12-11T22:05:55.524311", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2023-12-11T22:05:55.532350", "lvl": "debug", "msg": "uuid=34128_1.5.4.13 testdef=None"}
- {"dt": "2023-12-11T22:05:55.533460", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:05:55.534208", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.04", "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-11T22:05:55.536905", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:27:19) [common]"}
- {"dt": "2023-12-11T22:05:55.544560", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:55.545445", "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": "34128_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:55.548396", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:27:19) [common]"}
- {"dt": "2023-12-11T22:05:55.556905", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:55.557699", "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": "34128_1.5.4.13", "uuid": "34128_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:55.559756", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:27:19) [common]"}
- {"dt": "2023-12-11T22:05:55.569689", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1/tests/3_ci-service-check test_uuid 34128_1.5.4.13"}
- {"dt": "2023-12-11T22:05:55.570519", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2023-12-11T22:05:55.571604", "lvl": "debug", "msg": "DEVICE_TAGS='kingfisher can bluetooth wifi audio'"}
- {"dt": "2023-12-11T22:05:55.573212", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:55.573937", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/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": "34128_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:55.586895", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:27:19) [common]"}
- {"dt": "2023-12-11T22:05:55.588173", "lvl": "debug", "msg": "Using /lava-34128 at stage 2"}
- {"dt": "2023-12-11T22:05:55.589395", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-11T22:05:55.590202", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/2/tests/4_screenshooter'"}
- {"dt": "2023-12-11T22:05:56.388086", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2023-12-11T22:05:56.392020", "lvl": "debug", "msg": "uuid=34128_1.5.4.17 testdef=None"}
- {"dt": "2023-12-11T22:05:56.393196", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:05:56.407760", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.81", "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-11T22:05:56.409395", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:27:18) [common]"}
- {"dt": "2023-12-11T22:05:56.418340", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:56.419464", "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": "34128_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:56.439290", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:27:18) [common]"}
- {"dt": "2023-12-11T22:05:56.462617", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:56.469132", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.02", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "34128_1.5.4.17", "uuid": "34128_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:56.470919", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:27:18) [common]"}
- {"dt": "2023-12-11T22:05:56.515564", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/2/tests/4_screenshooter test_uuid 34128_1.5.4.17"}
- {"dt": "2023-12-11T22:05:56.533910", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:56.534795", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.06", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/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": "34128_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:56.536606", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:27:18) [common]"}
- {"dt": "2023-12-11T22:05:56.537362", "lvl": "debug", "msg": "Using /lava-34128 at stage 3"}
- {"dt": "2023-12-11T22:05:56.549813", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2023-12-11T22:05:56.550860", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/3/tests/5_ci-dumpjournal'"}
- {"dt": "2023-12-11T22:05:57.524136", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/3/tests/5_ci-dumpjournal"}
- {"dt": "2023-12-11T22:05:57.545362", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2023-12-11T22:05:57.552435", "lvl": "debug", "msg": "uuid=34128_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-11T22:05:57.553608", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:05:57.554344", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "1.02", "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-11T22:05:57.569143", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:27:17) [common]"}
- {"dt": "2023-12-11T22:05:57.591716", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:57.592974", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.02", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "34128_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:57.598660", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:27:17) [common]"}
- {"dt": "2023-12-11T22:05:57.618241", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:57.619052", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.02", "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": "34128_1.5.4.21", "uuid": "34128_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:57.621252", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:27:17) [common]"}
- {"dt": "2023-12-11T22:05:57.638715", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/3/tests/5_ci-dumpjournal test_uuid 34128_1.5.4.21"}
- {"dt": "2023-12-11T22:05:57.644084", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:57.645386", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.02", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/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": "34128_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:57.646884", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-12-11T22:05:57.649560", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/0 for stage 0"}
- {"dt": "2023-12-11T22:05:57.650626", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2023-12-11T22:05:57.654384", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/1 for stage 1"}
- {"dt": "2023-12-11T22:05:57.655582", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2023-12-11T22:05:57.656727", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2023-12-11T22:05:57.657395", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2023-12-11T22:05:57.658672", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/2 for stage 2"}
- {"dt": "2023-12-11T22:05:57.662900", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2023-12-11T22:05:57.663891", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34128/lava-overlay-tius4wvw/lava-34128/3 for stage 3"}
- {"dt": "2023-12-11T22:05:57.664902", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2023-12-11T22:05:57.666882", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:06) [common]"}
- {"dt": "2023-12-11T22:05:57.667888", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:17) [common]"}
- {"dt": "2023-12-11T22:05:58.645799", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:05:58.646772", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:16) [common]"}
- {"dt": "2023-12-11T22:05:58.649870", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:58.650811", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:07) [common]"}
- {"dt": "2023-12-11T22:05:58.652375", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:16) [common]"}
- {"dt": "2023-12-11T22:05:58.653294", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:16) [common]"}
- {"dt": "2023-12-11T22:05:58.654190", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:58.655006", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:58.658205", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:16) [common]"}
- {"dt": "2023-12-11T22:05:58.658998", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-12-11T22:05:58.659837", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61977, NBD-ROOT: 34128/nbd-deploy-5qt9q7dp/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-11T22:05:58.664241", "lvl": "debug", "msg": "nbd-server 61977 /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-12-11T22:05:58.707720", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-11T22:05:58.709543", "lvl": "debug", "msg": "output: ** (process:2800): WARNING **: 22:05:58.699: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-12-11T22:05:58.710238", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-12-11T22:05:58.710830", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-12-11T22:05:58.719461", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:58.720501", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:44) [common]"}
- {"dt": "2023-12-11T22:05:58.737613", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-12-11T22:05:58.738629", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-12-11T22:05:58.744394", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:58.745353", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-12-11T22:05:58.746728", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-12-11T22:05:58.750946", "lvl": "debug", "msg": "- {BOOTX}: booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-11T22:05:58.751789", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x68000000"}
- {"dt": "2023-12-11T22:05:58.752454", "lvl": "debug", "msg": "- {DTB}: 34128/nbd-deploy-5qt9q7dp/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-11T22:05:58.752983", "lvl": "debug", "msg": "- {INITRD}: 34128/nbd-deploy-5qt9q7dp/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-11T22:05:58.753481", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x68080000"}
- {"dt": "2023-12-11T22:05:58.753966", "lvl": "debug", "msg": "- {KERNEL}: 34128/nbd-deploy-5qt9q7dp/kernel/Image"}
- {"dt": "2023-12-11T22:05:58.754451", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-12-11T22:05:58.754915", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-12-11T22:05:58.759423", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61977"}
- {"dt": "2023-12-11T22:05:58.760069", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-12-11T22:05:58.760651", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-12-11T22:05:58.761171", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4a000000"}
- {"dt": "2023-12-11T22:05:58.761689", "lvl": "debug", "msg": "- {RAMDISK}: 34128/nbd-deploy-5qt9q7dp/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-11T22:05:58.762219", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-12-11T22:05:58.762737", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-12-11T22:05:58.766959", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-12-11T22:05:58.767730", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-12-11T22:05:58.768311", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-12-11T22:05:58.768873", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-12-11T22:05:58.769441", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-12-11T22:05:58.769963", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-11T22:05:58.770468", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-11T22:05:58.771008", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-12-11T22:05:58.776833", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-12-11T22:05:58.777411", "lvl": "info", "msg": "- tftp 0x68080000 34128/nbd-deploy-5qt9q7dp/kernel/Image"}
- {"dt": "2023-12-11T22:05:58.777878", "lvl": "info", "msg": "- tftp 0x4a000000 34128/nbd-deploy-5qt9q7dp/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-11T22:05:58.778349", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-12-11T22:05:58.778800", "lvl": "info", "msg": "- tftp 0x68000000 34128/nbd-deploy-5qt9q7dp/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-11T22:05:58.780168", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61977 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-11T22:05:58.780913", "lvl": "info", "msg": "- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-11T22:05:58.781676", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-12-11T22:05:58.784568", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.04", "extra": {"dtb_addr": "0x68000000", "kernel_addr": "0x68080000", "ramdisk_addr": "0x4a000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:05:58.786346", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-12-11T22:05:58.787086", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63001'"}
- {"dt": "2023-12-11T22:06:03.312304", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-12-11T22:06:03.314393", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:05) [common]"}
- {"dt": "2023-12-11T22:06:03.326628", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:55) [common]"}
- {"dt": "2023-12-11T22:06:03.327633", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:55) [common]"}
- {"dt": "2023-12-11T22:06:03.328560", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:55) [common]"}
- {"dt": "2023-12-11T22:06:03.329836", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'"}
- {"dt": "2023-12-11T22:06:07.693159", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-11T22:06:08.783400", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-11T22:06:08.875731", "lvl": "debug", "msg": "Returned 0 in 5 seconds"}
- {"dt": "2023-12-11T22:06:08.979724", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:06) [common]"}
- {"dt": "2023-12-11T22:06:08.980635", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "5.65", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:06:08.982060", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:06) [common]"}
- {"dt": "2023-12-11T22:06:08.983821", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:50) [common]"}
- {"dt": "2023-12-11T22:06:08.984714", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-12-11T22:06:08.986122", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-12-11T22:06:08.988133", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-12-11T22:06:08.988898", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-12-11T22:06:08.989527", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-12-11T22:06:08.990094", "lvl": "target", "msg": "NOTICE:  BL2: R-Car Gen3 Ini\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.029378", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.030669", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.111433", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter K\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Progr\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.117303", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.156350", "lvl": "target", "msg": "NOTI\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.157212", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.201306", "lvl": "target", "msg": "NOTICE:  BL2: Board i\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.202122", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.247488", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Prem\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.248314", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.254616", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-11T22:06:09.298157", "lvl": "target", "msg": "NOTICE:  \0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.298977", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.307862", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-11T22:06:09.350345", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is Hy\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.351193", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.351829", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-11T22:06:09.356151", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-11T22:06:09.412038", "lvl": "target", "msg": "NOTICE:  BL\uFFFD\0\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.412840", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.413466", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-11T22:06:09.480840", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)\uFFFD\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.481642", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.482234", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-11T22:06:09.482798", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-11T22:06:09.492115", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-11T22:06:09.496307", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-11T22:06:09.497069", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-11T22:06:09.548832", "lvl": "target", "msg": "\0NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3"}
- {"dt": "2023-12-11T22:06:09.549622", "lvl": "target", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-12-11T22:06:09.552676", "lvl": "target", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0"}
- {"dt": "2023-12-11T22:06:09.553404", "lvl": "target", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)"}
- {"dt": "2023-12-11T22:06:09.554054", "lvl": "target", "msg": "NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-12-11T22:06:09.554634", "lvl": "target", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-12-11T22:06:09.557438", "lvl": "target", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)"}
- {"dt": "2023-12-11T22:06:09.560395", "lvl": "target", "msg": "NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-12-11T22:06:09.593492", "lvl": "target", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-12-11T22:06:09.594299", "lvl": "target", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-12-11T22:06:09.594954", "lvl": "target", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-12-11T22:06:09.595635", "lvl": "target", "msg": "NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-12-11T22:06:09.603393", "lvl": "target", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB"}
- {"dt": "2023-12-11T22:06:09.604196", "lvl": "target", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB"}
- {"dt": "2023-12-11T22:06:09.604730", "lvl": "target", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB"}
- {"dt": "2023-12-11T22:06:09.605371", "lvl": "target", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB"}
- {"dt": "2023-12-11T22:06:09.605882", "lvl": "target", "msg": "NOTICE:  BL2: FDT at 0xe6326f70"}
- {"dt": "2023-12-11T22:06:09.606454", "lvl": "target", "msg": "NOTICE:  BL2: v2.5(release):268df1d86"}
- {"dt": "2023-12-11T22:06:09.611227", "lvl": "target", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023"}
- {"dt": "2023-12-11T22:06:09.637872", "lvl": "target", "msg": "NOTICE:  BL2: Normal boot"}
- {"dt": "2023-12-11T22:06:09.638683", "lvl": "target", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)"}
- {"dt": "2023-12-11T22:06:09.639387", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)"}
- {"dt": "2023-12-11T22:06:09.640046", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000"}
- {"dt": "2023-12-11T22:06:09.640632", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)"}
- {"dt": "2023-12-11T22:06:09.641205", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00200000"}
- {"dt": "2023-12-11T22:06:09.651705", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)"}
- {"dt": "2023-12-11T22:06:09.676035", "lvl": "target", "msg": "NOTICE:  rcar_file_len: len: 0x00100000"}
- {"dt": "2023-12-11T22:06:09.676827", "lvl": "target", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)"}
- {"dt": "2023-12-11T22:06:09.680863", "lvl": "target", "msg": "NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-12-11T22:06:10.007450", "lvl": "target", "msg": ""}
- {"dt": "2023-12-11T22:06:10.008221", "lvl": "target", "msg": ""}
- {"dt": "2023-12-11T22:06:10.008765", "lvl": "target", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)"}
- {"dt": "2023-12-11T22:06:10.009398", "lvl": "target", "msg": ""}
- {"dt": "2023-12-11T22:06:10.009983", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-12-11T22:06:10.013106", "lvl": "target", "msg": "Model: Renesas H3ULCB board based on r8a77950"}
- {"dt": "2023-12-11T22:06:10.207394", "lvl": "target", "msg": "DRAM:  3.9 GiB"}
- {"dt": "2023-12-11T22:06:10.235512", "lvl": "target", "msg": "RAM Configuration:"}
- {"dt": "2023-12-11T22:06:10.236271", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB"}
- {"dt": "2023-12-11T22:06:10.236808", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB"}
- {"dt": "2023-12-11T22:06:10.237298", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB"}
- {"dt": "2023-12-11T22:06:10.237796", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB"}
- {"dt": "2023-12-11T22:06:10.238281", "lvl": "target", "msg": ""}
- {"dt": "2023-12-11T22:06:10.267449", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1"}
- {"dt": "2023-12-11T22:06:10.451395", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-12-11T22:06:10.452167", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-12-11T22:06:10.452702", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-12-11T22:06:10.453220", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-12-11T22:06:10.503369", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-12-11T22:06:10.507478", "lvl": "input", "msg": "\n"}
- {"dt": "2023-12-11T22:06:10.559280", "lvl": "target", "msg": "Hit any key to stop autoboot"}
- {"dt": "2023-12-11T22:06:10.560382", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]"}
- {"dt": "2023-12-11T22:06:10.561319", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:48) [common]"}
- {"dt": "2023-12-11T22:06:10.562024", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-12-11T22:06:10.562763", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:48)"}
- {"dt": "2023-12-11T22:06:10.582002", "lvl": "target", "msg": ":  3 \b\b\b 0 "}
- {"dt": "2023-12-11T22:06:10.587608", "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-11T22:06:10.588415", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-12-11T22:06:10.691319", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-12-11T22:06:10.692269", "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:48)"}
- {"dt": "2023-12-11T22:06:10.707509", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-12-11T22:06:10.711415", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-12-11T22:06:10.814404", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-11T22:06:10.815368", "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:48)"}
- {"dt": "2023-12-11T22:06:10.835685", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-12-11T22:06:10.841382", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-12-11T22:06:10.946009", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-11T22:06:10.947226", "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:48)"}
- {"dt": "2023-12-11T22:06:10.963508", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-12-11T22:06:10.967413", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-12-11T22:06:11.071296", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-12-11T22:06:11.072222", "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:48)"}
- {"dt": "2023-12-11T22:06:11.096218", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-12-11T22:06:14.183487", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-12-11T22:06:14.184265", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-12-11T22:06:14.436591", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-12-11T22:06:14.932740", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-12-11T22:06:15.203354", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.19 (1030 ms)"}
- {"dt": "2023-12-11T22:06:15.204592", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-12-11T22:06:15.307293", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-12-11T22:06:15.312754", "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:43)"}
- {"dt": "2023-12-11T22:06:15.331021", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-12-11T22:06:15.335117", "lvl": "input", "msg": "tftp 0x68080000 34128/nbd-deploy-5qt9q7dp/kernel/Image\n"}
- {"dt": "2023-12-11T22:06:15.437489", "lvl": "target", "msg": "=> tftp 0x68080000 34128/nbd-deploy-5qt9q7dp/kernel/Image"}
- {"dt": "2023-12-11T22:06:15.438386", "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:43)"}
- {"dt": "2023-12-11T22:06:15.475973", "lvl": "target", "msg": "tftp 0x68080000 34128/nbd-deploy-5qt9q7dp/kernel/Image"}
- {"dt": "2023-12-11T22:06:15.476755", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-11T22:06:15.477350", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-11T22:06:15.482677", "lvl": "target", "msg": "Filename '34128/nbd-deploy-5qt9q7dp/kernel/Image'."}
- {"dt": "2023-12-11T22:06:15.483493", "lvl": "target", "msg": "Load address: 0x68080000"}
- {"dt": "2023-12-11T22:06:16.005475", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-11T22:06:16.580658", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:17.139969", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:17.655462", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:18.192809", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:18.719974", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:19.216651", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:19.728995", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:20.237844", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:20.764920", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:21.291942", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:21.818143", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:22.346336", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:22.857203", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:23.383917", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:23.895052", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:24.437164", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:24.967349", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:25.507019", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:26.051105", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:26.562008", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:27.105191", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:27.726911", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:28.259370", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:28.735380", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:29.214601", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:29.711363", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:30.188861", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:30.687386", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:31.167368", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:31.679362", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:32.151162", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:32.646475", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:33.173410", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:33.716291", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:34.243986", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:34.771491", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:35.282996", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:35.778639", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:36.322464", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:36.834908", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:36.892693", "lvl": "target", "msg": "\t ######"}
- {"dt": "2023-12-11T22:06:36.893457", "lvl": "target", "msg": "\t 1.7 MiB/s"}
- {"dt": "2023-12-11T22:06:36.893988", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-11T22:06:36.895914", "lvl": "target", "msg": "Bytes transferred = 39207424 (2564200 hex)"}
- {"dt": "2023-12-11T22:06:36.897291", "lvl": "input", "msg": "tftp 0x4a000000 34128/nbd-deploy-5qt9q7dp/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-12-11T22:06:36.999335", "lvl": "target", "msg": "=> tftp 0x4a000000 34128/nbd-deploy-5qt9q7dp/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-11T22:06:37.000957", "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-11T22:06:37.005991", "lvl": "target", "msg": "tftp 0x4a000000 34128/nbd-deploy-5qt9q7dp/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-12-11T22:06:37.036816", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-11T22:06:37.037601", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-11T22:06:37.038135", "lvl": "target", "msg": "Filename '34128/nbd-deploy-5qt9q7dp/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-12-11T22:06:37.040041", "lvl": "target", "msg": "Load address: 0x4a000000"}
- {"dt": "2023-12-11T22:06:37.519333", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-12-11T22:06:37.998873", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-12-11T22:06:38.079024", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-12-11T22:06:38.079871", "lvl": "target", "msg": "\t 1.9 MiB/s"}
- {"dt": "2023-12-11T22:06:38.080400", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-11T22:06:38.095416", "lvl": "target", "msg": "Bytes transferred = 2061467 (1f749b hex)"}
- {"dt": "2023-12-11T22:06:38.096687", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-12-11T22:06:38.198361", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-12-11T22:06:38.199908", "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:21)"}
- {"dt": "2023-12-11T22:06:38.206791", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-12-11T22:06:38.208113", "lvl": "input", "msg": "tftp 0x68000000 34128/nbd-deploy-5qt9q7dp/dtb/r8a77951-ulcb.dtb\n"}
- {"dt": "2023-12-11T22:06:38.309761", "lvl": "target", "msg": "=> tftp 0x68000000 34128/nbd-deploy-5qt9q7dp/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-11T22:06:38.310745", "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-11T22:06:38.316617", "lvl": "target", "msg": "tftp 0x68000000 34128/nbd-deploy-5qt9q7dp/dtb/r8a77951-ulcb.dtb"}
- {"dt": "2023-12-11T22:06:38.347586", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-12-11T22:06:38.348395", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.19"}
- {"dt": "2023-12-11T22:06:38.349041", "lvl": "target", "msg": "Filename '34128/nbd-deploy-5qt9q7dp/dtb/r8a77951-ulcb.dtb'."}
- {"dt": "2023-12-11T22:06:38.349655", "lvl": "target", "msg": "Load address: 0x68000000"}
- {"dt": "2023-12-11T22:06:38.406580", "lvl": "target", "msg": "Loading: *\b######"}
- {"dt": "2023-12-11T22:06:38.407419", "lvl": "target", "msg": "\t 1.6 MiB/s"}
- {"dt": "2023-12-11T22:06:38.407984", "lvl": "target", "msg": "done"}
- {"dt": "2023-12-11T22:06:38.408461", "lvl": "target", "msg": "Bytes transferred = 82960 (14410 hex)"}
- {"dt": "2023-12-11T22:06:38.409294", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61977 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-11T22:06:38.511288", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61977 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-11T22:06:38.513051", "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-11T22:06:38.528495", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61977 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-11T22:06:38.529739", "lvl": "input", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000\n"}
- {"dt": "2023-12-11T22:06:38.631702", "lvl": "target", "msg": "=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-11T22:06:38.632668", "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-11T22:06:38.633444", "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:20)"}
- {"dt": "2023-12-11T22:06:38.641485", "lvl": "target", "msg": "booti 0x68080000 0x4a000000:${initrd_size} 0x68000000"}
- {"dt": "2023-12-11T22:06:38.657424", "lvl": "target", "msg": "Moving Image from 0x68080000 to 0x68200000, end=6a800000"}
- {"dt": "2023-12-11T22:06:38.753397", "lvl": "target", "msg": "## Flattened Device Tree blob at 68000000"}
- {"dt": "2023-12-11T22:06:38.803459", "lvl": "target", "msg": "   Booting using the fdt blob at 0x68000000"}
- {"dt": "2023-12-11T22:06:38.804238", "lvl": "target", "msg": "   Loading Ramdisk to 7fe08000, end 7ffff49b ... OK"}
- {"dt": "2023-12-11T22:06:38.804811", "lvl": "target", "msg": "   Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \\\"Synchronous Abort\\\" handler, esr 0x96000046"}
- {"dt": "2023-12-11T22:06:38.805320", "lvl": "target", "msg": "elr: 000000002016db9c lr : 000000002016dbfc (reloc)"}
- {"dt": "2023-12-11T22:06:38.805899", "lvl": "target", "msg": "elr: 0000000050069b9c lr : 0000000050069bfc"}
- {"dt": "2023-12-11T22:06:38.806509", "lvl": "target", "msg": "x0 : 0000000053fe8000 x1 : 0000000068000000"}
- {"dt": "2023-12-11T22:06:38.807203", "lvl": "target", "msg": "x2 : 0000000000014410 x3 : 0000000000000000"}
- {"dt": "2023-12-11T22:06:38.807834", "lvl": "target", "msg": "x4 : 10440100edfe0dd0 x5 : 0000000053fe8000"}
- {"dt": "2023-12-11T22:06:38.809079", "lvl": "target", "msg": "x6 : 0000000053fe8000 x7 : 000000007beeed80"}
- {"dt": "2023-12-11T22:06:38.848819", "lvl": "target", "msg": "x8 : 000000007beeed48 x9 : 0000000000000008"}
- {"dt": "2023-12-11T22:06:38.849605", "lvl": "target", "msg": "x10: 0000000053fff40f x11: 0000000053fff40f"}
- {"dt": "2023-12-11T22:06:38.850146", "lvl": "target", "msg": "x12: 0000000048000000 x13: fffffffffffff000"}
- {"dt": "2023-12-11T22:06:38.850654", "lvl": "target", "msg": "x14: 0000000053fe8000 x15: 0000000000000021"}
- {"dt": "2023-12-11T22:06:38.851642", "lvl": "target", "msg": "x16: 0000000050038720 x17: 0000000000000000"}
- {"dt": "2023-12-11T22:06:38.852329", "lvl": "target", "msg": "x18: 000000007befbdb0 x19: 0000000053fe8000"}
- {"dt": "2023-12-11T22:06:38.852839", "lvl": "target", "msg": "x20: 0000000068000000 x21: 0000000000017410"}
- {"dt": "2023-12-11T22:06:38.853315", "lvl": "target", "msg": "x22: 0000000010440100 x23: 0000000000000010"}
- {"dt": "2023-12-11T22:06:38.853779", "lvl": "target", "msg": "x24: 00000000500e3980 x25: 000000007beeedf8"}
- {"dt": "2023-12-11T22:06:38.854246", "lvl": "target", "msg": "x26: 0000000000000000 x27: 0000000050002e3c"}
- {"dt": "2023-12-11T22:06:38.855326", "lvl": "target", "msg": "x28: 000000007bf04d70 x29: 000000007beeed10"}
- {"dt": "2023-12-11T22:06:38.856037", "lvl": "target", "msg": ""}
- {"dt": "2023-12-11T22:06:38.869030", "lvl": "target", "msg": "Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804) "}
- {"dt": "2023-12-11T22:06:38.869856", "lvl": "target", "msg": "Resetting CPU ..."}
- {"dt": "2023-12-11T22:06:38.870389", "lvl": "target", "msg": ""}
- {"dt": "2023-12-11T22:06:38.871754", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-11T22:06:38.872557", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:28) [common]"}
- {"dt": "2023-12-11T22:06:38.873624", "lvl": "results", "msg": {"case": "bootloader-commands", "definition": "lava", "duration": "28.31", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4.3", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-11T22:06:38.874863", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-11T22:06:38.875590", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:00:36) [common]"}
- {"dt": "2023-12-11T22:06:38.876309", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "35.55", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-11T22:06:38.877426", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'"}
- {"dt": "2023-12-11T22:06:38.878188", "lvl": "exception", "msg": "matched a bootloader error message: 'Resetting CPU' (1)"}
- {"dt": "2023-12-11T22:06:38.878816", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:00:40) [common]"}
- {"dt": "2023-12-11T22:06:38.879489", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "40.14", "extra": {"fail": "matched a bootloader error message: 'Resetting CPU' (1)"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2023-12-11T22:06:38.880833", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-12-11T22:06:38.881623", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/initrd"}
- {"dt": "2023-12-11T22:06:38.884187", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/kernel"}
- {"dt": "2023-12-11T22:06:38.893572", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/dtb"}
- {"dt": "2023-12-11T22:06:38.895127", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34128/nbd-deploy-5qt9q7dp/nbdroot"}
- {"dt": "2023-12-11T22:06:38.897901", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-12-11T22:06:38.899083", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'"}
- {"dt": "2023-12-11T22:06:42.378684", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-12-11T22:06:42.418294", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-12-11T22:06:42.520739", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-12-11T22:06:42.521880", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.62", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-12-11T22:06:42.523862", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-12-11T22:06:42.525977", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-11T22:06:42.527804", "lvl": "feedback", "msg": "resetting ...", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.528730", "lvl": "feedback", "msg": "NOTICE:  BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.529564", "lvl": "feedback", "msg": "NOTICE:  BL2: PRR is R-Car H3 Ver.3.0", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.530347", "lvl": "feedback", "msg": "NOTICE:  BL2: Board is Starter Kit Premier Rev.2.0", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.531182", "lvl": "feedback", "msg": "NOTICE:  BL2: Boot device is HyperFlash(80MHz)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.532046", "lvl": "feedback", "msg": "NOTICE:  BL2: LCM state is CM", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.532931", "lvl": "feedback", "msg": "NOTICE:  AVS setting succeeded. DVFS_SetVID=0x53", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.533755", "lvl": "feedback", "msg": "NOTICE:  BL2: DDR3200(rev.0.42)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.534432", "lvl": "feedback", "msg": "NOTICE:  BL2: [COLD_BOOT]", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.535085", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM Split is 4ch(DDR f)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.535811", "lvl": "feedback", "msg": "NOTICE:  BL2: QoS is default setting(rev.0.11)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.536467", "lvl": "feedback", "msg": "NOTICE:  BL2: DRAM refresh interval 1.95 usec", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.537134", "lvl": "feedback", "msg": "NOTICE:  BL2: Periodic Write DQ Training", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.537776", "lvl": "feedback", "msg": "NOTICE:  BL2: CH0: 400000000 - 43fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.538415", "lvl": "feedback", "msg": "NOTICE:  BL2: CH1: 500000000 - 53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.539057", "lvl": "feedback", "msg": "NOTICE:  BL2: CH2: 600000000 - 63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.539642", "lvl": "feedback", "msg": "NOTICE:  BL2: CH3: 700000000 - 73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.540154", "lvl": "feedback", "msg": "NOTICE:  BL2: FDT at 0xe6326f70", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.540657", "lvl": "feedback", "msg": "NOTICE:  BL2: v2.5(release):268df1d86", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.541169", "lvl": "feedback", "msg": "NOTICE:  BL2: Built : 17:33:20, Jan 30 2023", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.541724", "lvl": "feedback", "msg": "NOTICE:  BL2: Normal boot", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.542394", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.543093", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.543881", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x0003e000", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.544579", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.545270", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00200000", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.546030", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.546754", "lvl": "feedback", "msg": "NOTICE:  rcar_file_len: len: 0x00100000", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.547498", "lvl": "feedback", "msg": "NOTICE:  BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.548167", "lvl": "feedback", "msg": "NOTICE:  BL2: Booting BL31", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.548829", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.549481", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.550124", "lvl": "feedback", "msg": "U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.550778", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.551518", "lvl": "feedback", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.552193", "lvl": "feedback", "msg": "Model: Renesas H3ULCB board based on r8a77950", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.552843", "lvl": "feedback", "msg": "DRAM:  3.9 GiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.553472", "lvl": "feedback", "msg": "RAM Configuration:", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.554102", "lvl": "feedback", "msg": "Bank #0: 0x048000000 - 0x07fffffff, 896 MiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.554756", "lvl": "feedback", "msg": "Bank #1: 0x500000000 - 0x53fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.555469", "lvl": "feedback", "msg": "Bank #2: 0x600000000 - 0x63fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.556130", "lvl": "feedback", "msg": "Bank #3: 0x700000000 - 0x73fffffff, 1 GiB", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.556778", "lvl": "feedback", "msg": "", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.557422", "lvl": "feedback", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.558070", "lvl": "feedback", "msg": "Loading Environment from MMC... OK", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.558722", "lvl": "feedback", "msg": "In:    serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.559441", "lvl": "feedback", "msg": "Out:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.560109", "lvl": "feedback", "msg": "Err:   serial@e6e88000", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.560748", "lvl": "feedback", "msg": "Net:   eth0: ethernet@e6800000", "ns": "common"}
- {"dt": "2023-12-11T22:06:42.561757", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-12-11T22:06:43.527553", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-12-11T22:06:43.528538", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-12-11T22:06:43.529472", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \0"}
- {"dt": "2023-12-11T22:06:43.631533", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-12-11T22:06:43.632441", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-12-11T22:06:43.633182", "lvl": "debug", "msg": "clean NBD port 61977"}
- {"dt": "2023-12-11T22:06:47.110471", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34128"}
- {"dt": "2023-12-11T22:06:47.204627", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34128"}
- {"dt": "2023-12-11T22:06:47.205703", "lvl": "error", "msg": "InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins."}
- {"dt": "2023-12-11T22:06:47.206325", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "matched a bootloader error message: 'Resetting CPU' (1)", "error_type": "Infrastructure", "result": "fail"}}
