- {"dt": "2024-12-01T13:32:46.186677", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.10"}
- {"dt": "2024-12-01T13:32:46.187040", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2024-12-01T13:32:46.187253", "lvl": "info", "msg": "Start time: 2024-12-01 13:32:46.187240+00:00 (UTC)"}
- {"dt": "2024-12-01T13:32:46.187524", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/30604/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2024-12-01T13:32:46.388428", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/30604/1/h3ulcb-nogfx/Image exists"}
- {"dt": "2024-12-01T13:32:46.587402", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/30604/1/h3ulcb-nogfx/r8a77951-agl-refhw.dtb exists"}
- {"dt": "2024-12-01T13:32:46.788751", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/30604/1/h3ulcb-nogfx/agl-ivi-demo-qt-h3ulcb.ext4.xz exists"}
- {"dt": "2024-12-01T13:32:46.989483", "lvl": "info", "msg": "validate duration: 0.80"}
- {"dt": "2024-12-01T13:32:46.989714", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2024-12-01T13:32:46.989953", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2024-12-01T13:32:46.990072", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2024-12-01T13:32:46.990169", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2024-12-01T13:32:46.990275", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2024-12-01T13:32:46.990534", "lvl": "debug", "msg": "Set_port 61990"}
- {"dt": "2024-12-01T13:32:46.990690", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2024-12-01T13:32:46.990829", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2024-12-01T13:32:46.991127", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/30604/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2024-12-01T13:32:46.991244", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2024-12-01T13:32:46.991341", "lvl": "debug", "msg": "total size: 2151500 (2\u202FMB)"}
- {"dt": "2024-12-01T13:32:46.991434", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-01T13:32:47.287445", "lvl": "debug", "msg": "progress   1\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.483668", "lvl": "debug", "msg": "progress   6\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.581186", "lvl": "debug", "msg": "progress  12\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.583655", "lvl": "debug", "msg": "progress  18\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.683482", "lvl": "debug", "msg": "progress  24\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.685137", "lvl": "debug", "msg": "progress  30\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.685915", "lvl": "debug", "msg": "progress  35\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.686908", "lvl": "debug", "msg": "progress  41\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.783229", "lvl": "debug", "msg": "progress  47\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.786222", "lvl": "debug", "msg": "progress  53\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.787173", "lvl": "debug", "msg": "progress  59\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.788102", "lvl": "debug", "msg": "progress  65\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.789662", "lvl": "debug", "msg": "progress  70\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.790646", "lvl": "debug", "msg": "progress  76\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.791607", "lvl": "debug", "msg": "progress  82\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.877020", "lvl": "debug", "msg": "progress  88\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.881076", "lvl": "debug", "msg": "progress  94\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.882103", "lvl": "debug", "msg": "progress 100\u202F% (2\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.882476", "lvl": "info", "msg": "2\u202FMB downloaded in 0.89\u202Fs (2.30\u202FMB/s)"}
- {"dt": "2024-12-01T13:32:47.882739", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T13:32:47.882857", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.89", "extra": {"label": "initrd", "md5sum": "85a89a775c0ea05486e6ee6c0e142e0b", "sha256sum": "3c096eb4b586ad3461d1c4f6a0bea4320ac78813fdad960d965d441d8f1d7b18", "sha512sum": "aa8341e513153f4671cf68c9b4801a94d7bca0604ece0d2d5270545b9198cc203138cd49af7ade30da01d5ed81e48c25cc194d60d05c1008d1d8f8c6850c14b6", "size": ! "2151500"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:32:47.883115", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T13:32:47.883247", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2024-12-01T13:32:47.883376", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2024-12-01T13:32:47.883627", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/30604/1/h3ulcb-nogfx/Image"}
- {"dt": "2024-12-01T13:32:47.883733", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/kernel/Image"}
- {"dt": "2024-12-01T13:32:47.883822", "lvl": "debug", "msg": "total size: 39135744 (37\u202FMB)"}
- {"dt": "2024-12-01T13:32:47.883911", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-01T13:32:48.181409", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:48.779111", "lvl": "debug", "msg": "progress   5\u202F% (1\u202FMB)"}
- {"dt": "2024-12-01T13:32:49.069202", "lvl": "debug", "msg": "progress  10\u202F% (3\u202FMB)"}
- {"dt": "2024-12-01T13:32:49.081495", "lvl": "debug", "msg": "progress  15\u202F% (5\u202FMB)"}
- {"dt": "2024-12-01T13:32:49.295641", "lvl": "debug", "msg": "progress  20\u202F% (7\u202FMB)"}
- {"dt": "2024-12-01T13:32:49.366576", "lvl": "debug", "msg": "progress  25\u202F% (9\u202FMB)"}
- {"dt": "2024-12-01T13:32:49.656822", "lvl": "debug", "msg": "progress  30\u202F% (11\u202FMB)"}
- {"dt": "2024-12-01T13:32:49.757859", "lvl": "debug", "msg": "progress  35\u202F% (13\u202FMB)"}
- {"dt": "2024-12-01T13:32:49.995751", "lvl": "debug", "msg": "progress  40\u202F% (14\u202FMB)"}
- {"dt": "2024-12-01T13:32:50.281009", "lvl": "debug", "msg": "progress  45\u202F% (16\u202FMB)"}
- {"dt": "2024-12-01T13:32:50.489550", "lvl": "debug", "msg": "progress  50\u202F% (18\u202FMB)"}
- {"dt": "2024-12-01T13:32:50.767019", "lvl": "debug", "msg": "progress  55\u202F% (20\u202FMB)"}
- {"dt": "2024-12-01T13:32:51.072274", "lvl": "debug", "msg": "progress  60\u202F% (22\u202FMB)"}
- {"dt": "2024-12-01T13:32:51.377161", "lvl": "debug", "msg": "progress  65\u202F% (24\u202FMB)"}
- {"dt": "2024-12-01T13:32:51.719271", "lvl": "debug", "msg": "progress  70\u202F% (26\u202FMB)"}
- {"dt": "2024-12-01T13:32:52.028731", "lvl": "debug", "msg": "progress  75\u202F% (28\u202FMB)"}
- {"dt": "2024-12-01T13:32:52.342165", "lvl": "debug", "msg": "progress  80\u202F% (29\u202FMB)"}
- {"dt": "2024-12-01T13:32:52.645285", "lvl": "debug", "msg": "progress  85\u202F% (31\u202FMB)"}
- {"dt": "2024-12-01T13:32:52.940816", "lvl": "debug", "msg": "progress  90\u202F% (33\u202FMB)"}
- {"dt": "2024-12-01T13:32:53.236069", "lvl": "debug", "msg": "progress  95\u202F% (35\u202FMB)"}
- {"dt": "2024-12-01T13:32:53.526976", "lvl": "debug", "msg": "progress 100\u202F% (37\u202FMB)"}
- {"dt": "2024-12-01T13:32:53.527601", "lvl": "info", "msg": "37\u202FMB downloaded in 5.64\u202Fs (6.61\u202FMB/s)"}
- {"dt": "2024-12-01T13:32:53.527835", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:06) [common]"}
- {"dt": "2024-12-01T13:32:53.527944", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "5.64", "extra": {"label": "kernel", "md5sum": "f458db4da86f39d6bbc256f8e16f10dd", "sha256sum": "788afbbb13f2515780d91c406e511743b9ede716a61f5896683481a8748d4235", "sha512sum": "9e4b3b444a5d5520358810a5777b9246d099f111ad0bd700d3c3d1291f01f5ed865547c2b25a860909bac54713f24436ab0c062eae385d4aaa9e510782615177", "size": ! "39135744"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:32:53.528181", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:06) [common]"}
- {"dt": "2024-12-01T13:32:53.528308", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:53) [common]"}
- {"dt": "2024-12-01T13:32:53.528429", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:53) [common]"}
- {"dt": "2024-12-01T13:32:53.528677", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/30604/1/h3ulcb-nogfx/r8a77951-agl-refhw.dtb"}
- {"dt": "2024-12-01T13:32:53.529044", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2024-12-01T13:32:53.529146", "lvl": "debug", "msg": "total size: 88958 (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:53.529234", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2024-12-01T13:32:53.833945", "lvl": "debug", "msg": "progress  36\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:53.931102", "lvl": "debug", "msg": "progress  73\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:53.932456", "lvl": "debug", "msg": "progress 100\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:53.933169", "lvl": "info", "msg": "0\u202FMB downloaded in 0.40\u202Fs (0.21\u202FMB/s)"}
- {"dt": "2024-12-01T13:32:53.933457", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:32:53.933614", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "0.41", "extra": {"label": "dtb", "md5sum": "40d1b427f4f7f206d2f3b2d5730151f2", "sha256sum": "85ae8f72927f8de5e0844d4fb47fef48998e0ffbffb5c02391ce4b52c95290e0", "sha512sum": "eb7e817faa08ae2a831fe53ff0a4a4957b5b5e1ce3a761481c568b7573ef7b9573d2925b7160d0126f6d90dfb1b4007238eb03088c5875453f3ba13b6789bbeb", "size": ! "88958"}, "level": "1.3.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:32:53.933895", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:32:53.934061", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:53) [common]"}
- {"dt": "2024-12-01T13:32:53.934228", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:53) [common]"}
- {"dt": "2024-12-01T13:32:53.934852", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/30604/1/h3ulcb-nogfx/agl-ivi-demo-qt-h3ulcb.ext4.xz"}
- {"dt": "2024-12-01T13:32:53.935021", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/nbdroot/agl-ivi-demo-qt-h3ulcb.ext4"}
- {"dt": "2024-12-01T13:32:53.935153", "lvl": "debug", "msg": "total size: 620537828 (591\u202FMB)"}
- {"dt": "2024-12-01T13:32:53.935298", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2024-12-01T13:32:54.233463", "lvl": "debug", "msg": "progress   0\u202F% (0\u202FMB)"}
- {"dt": "2024-12-01T13:32:58.947824", "lvl": "debug", "msg": "progress   5\u202F% (29\u202FMB)"}
- {"dt": "2024-12-01T13:33:02.285312", "lvl": "debug", "msg": "progress  10\u202F% (59\u202FMB)"}
- {"dt": "2024-12-01T13:33:05.174299", "lvl": "debug", "msg": "progress  15\u202F% (88\u202FMB)"}
- {"dt": "2024-12-01T13:33:07.684249", "lvl": "debug", "msg": "progress  20\u202F% (118\u202FMB)"}
- {"dt": "2024-12-01T13:33:10.829546", "lvl": "debug", "msg": "progress  25\u202F% (147\u202FMB)"}
- {"dt": "2024-12-01T13:33:13.680314", "lvl": "debug", "msg": "progress  30\u202F% (177\u202FMB)"}
- {"dt": "2024-12-01T13:33:18.058093", "lvl": "debug", "msg": "progress  35\u202F% (207\u202FMB)"}
- {"dt": "2024-12-01T13:33:21.843725", "lvl": "debug", "msg": "progress  40\u202F% (236\u202FMB)"}
- {"dt": "2024-12-01T13:33:27.246156", "lvl": "debug", "msg": "progress  45\u202F% (266\u202FMB)"}
- {"dt": "2024-12-01T13:33:31.261742", "lvl": "debug", "msg": "progress  50\u202F% (295\u202FMB)"}
- {"dt": "2024-12-01T13:33:35.043205", "lvl": "debug", "msg": "progress  55\u202F% (325\u202FMB)"}
- {"dt": "2024-12-01T13:33:37.887012", "lvl": "debug", "msg": "progress  60\u202F% (355\u202FMB)"}
- {"dt": "2024-12-01T13:33:41.214999", "lvl": "debug", "msg": "progress  65\u202F% (384\u202FMB)"}
- {"dt": "2024-12-01T13:33:44.140722", "lvl": "debug", "msg": "progress  70\u202F% (414\u202FMB)"}
- {"dt": "2024-12-01T13:33:47.064930", "lvl": "debug", "msg": "progress  75\u202F% (443\u202FMB)"}
- {"dt": "2024-12-01T13:33:49.989696", "lvl": "debug", "msg": "progress  80\u202F% (473\u202FMB)"}
- {"dt": "2024-12-01T13:33:52.563980", "lvl": "debug", "msg": "progress  85\u202F% (503\u202FMB)"}
- {"dt": "2024-12-01T13:33:54.810547", "lvl": "debug", "msg": "progress  90\u202F% (532\u202FMB)"}
- {"dt": "2024-12-01T13:33:57.381942", "lvl": "debug", "msg": "progress  95\u202F% (562\u202FMB)"}
- {"dt": "2024-12-01T13:34:02.522244", "lvl": "debug", "msg": "progress 100\u202F% (591\u202FMB)"}
- {"dt": "2024-12-01T13:34:03.494156", "lvl": "info", "msg": "591\u202FMB downloaded in 69.56\u202Fs (8.51\u202FMB/s)"}
- {"dt": "2024-12-01T13:34:03.494483", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:10) [common]"}
- {"dt": "2024-12-01T13:34:03.494597", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "69.56", "extra": {"label": "nbdroot", "md5sum": "d6a94bebf7cccbf6f3bc9a96761e19ef", "sha256sum": "7af0eb0508010a262952a191aa267678471ff8274f42309f785283f90b23c584", "sha512sum": "e73053b398a2a2f6e8823a2193c57f5bd100cae4e7a91c9af2c7f542126ef076b1bde84a23ff9041fc8d3dc8caff8c061080fc9473b41a8b944d868984a647d6", "size": ! "620537828"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:03.494821", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:10) [common]"}
- {"dt": "2024-12-01T13:34:03.494937", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.495236", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a"}
- {"dt": "2024-12-01T13:34:03.495457", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin"}
- {"dt": "2024-12-01T13:34:03.495607", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/tests"}
- {"dt": "2024-12-01T13:34:03.495747", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/results"}
- {"dt": "2024-12-01T13:34:03.495888", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-add-keys"}
- {"dt": "2024-12-01T13:34:03.496105", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-add-sources"}
- {"dt": "2024-12-01T13:34:03.496315", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-background-process-start"}
- {"dt": "2024-12-01T13:34:03.496529", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-background-process-stop"}
- {"dt": "2024-12-01T13:34:03.496765", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-common-functions"}
- {"dt": "2024-12-01T13:34:03.496967", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-echo-ipv4"}
- {"dt": "2024-12-01T13:34:03.497166", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-install-packages"}
- {"dt": "2024-12-01T13:34:03.497353", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-installed-packages"}
- {"dt": "2024-12-01T13:34:03.497534", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-os-build"}
- {"dt": "2024-12-01T13:34:03.497716", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-probe-channel"}
- {"dt": "2024-12-01T13:34:03.497897", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-probe-ip"}
- {"dt": "2024-12-01T13:34:03.498078", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-target-ip"}
- {"dt": "2024-12-01T13:34:03.498259", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-target-mac"}
- {"dt": "2024-12-01T13:34:03.498444", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-target-storage"}
- {"dt": "2024-12-01T13:34:03.498632", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-test-case"}
- {"dt": "2024-12-01T13:34:03.498821", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-test-event"}
- {"dt": "2024-12-01T13:34:03.499040", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-test-feedback"}
- {"dt": "2024-12-01T13:34:03.499226", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-test-raise"}
- {"dt": "2024-12-01T13:34:03.499419", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-test-reference"}
- {"dt": "2024-12-01T13:34:03.499602", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-test-runner"}
- {"dt": "2024-12-01T13:34:03.499782", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-test-set"}
- {"dt": "2024-12-01T13:34:03.499962", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-test-shell"}
- {"dt": "2024-12-01T13:34:03.500162", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-install-packages (oe)"}
- {"dt": "2024-12-01T13:34:03.500409", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/bin/lava-installed-packages (oe)"}
- {"dt": "2024-12-01T13:34:03.500602", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/environment"}
- {"dt": "2024-12-01T13:34:03.500771", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2024-12-01T13:34:03.500869", "lvl": "debug", "msg": "- LAVA_JOB_ID=38035"}
- {"dt": "2024-12-01T13:34:03.500951", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2024-12-01T13:34:03.501110", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.501535", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:03.501664", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.501753", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2024-12-01T13:34:03.501848", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:03.501947", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.502022", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2024-12-01T13:34:03.502112", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:03.502208", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.502303", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2024-12-01T13:34:03.502414", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.502504", "lvl": "debug", "msg": "Using /lava-38035 at stage 0"}
- {"dt": "2024-12-01T13:34:03.502639", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2024-12-01T13:34:03.502731", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/0/tests/0_network-veth'"}
- {"dt": "2024-12-01T13:34:03.804838", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml"}
- {"dt": "2024-12-01T13:34:03.805237", "lvl": "debug", "msg": "uuid=38035_1.5.4.1 testdef=None"}
- {"dt": "2024-12-01T13:34:03.805378", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:03.805478", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.30", "extra": {"commit": "678c63ae73f5c70b9b06d292df74e66a9a1f7255", "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": "2024-12-01T13:34:03.805670", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.806410", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:03.806521", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "network-veth", "path": "test-suites/short-smoke/network-veth.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "38035_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:03.806739", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.807709", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:03.807818", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "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": "38035_1.5.4.1", "uuid": "38035_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:03.808049", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.808955", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/0/tests/0_network-veth test_uuid 38035_1.5.4.1"}
- {"dt": "2024-12-01T13:34:03.809214", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:03.809316", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/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": "38035_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:03.809534", "lvl": "debug", "msg": "start: 1.5.4.5 git-repo-action (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:03.809626", "lvl": "debug", "msg": "Using /lava-38035 at stage 1"}
- {"dt": "2024-12-01T13:34:03.809770", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2024-12-01T13:34:03.809865", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/1_ci-busybox'"}
- {"dt": "2024-12-01T13:34:04.096705", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/1_ci-busybox"}
- {"dt": "2024-12-01T13:34:04.098174", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml"}
- {"dt": "2024-12-01T13:34:04.098600", "lvl": "debug", "msg": "uuid=38035_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": "2024-12-01T13:34:04.098771", "lvl": "debug", "msg": "end: 1.5.4.5 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.098872", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.29", "extra": {"commit": "678c63ae73f5c70b9b06d292df74e66a9a1f7255", "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": "2024-12-01T13:34:04.099075", "lvl": "debug", "msg": "start: 1.5.4.6 test-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:04.099974", "lvl": "debug", "msg": "end: 1.5.4.6 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.100079", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "38035_1.5.4.5"}, "level": "1.5.4.6", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.100295", "lvl": "debug", "msg": "start: 1.5.4.7 test-install-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:04.101502", "lvl": "debug", "msg": "end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.101612", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-busybox", "path": "test-suites/short-smoke/busybox.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "38035_1.5.4.5", "uuid": "38035_1.5.4.5"}, "level": "1.5.4.7", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.101839", "lvl": "debug", "msg": "start: 1.5.4.8 test-runscript-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:04.102935", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/1_ci-busybox test_uuid 38035_1.5.4.5"}
- {"dt": "2024-12-01T13:34:04.103192", "lvl": "debug", "msg": "end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.103295", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/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": "38035_1.5.4.5"}, "level": "1.5.4.8", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.103517", "lvl": "debug", "msg": "start: 1.5.4.9 git-repo-action (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:04.103611", "lvl": "debug", "msg": "Using /lava-38035 at stage 1"}
- {"dt": "2024-12-01T13:34:04.103755", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2024-12-01T13:34:04.103851", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/2_ci-smoke-tests-basic'"}
- {"dt": "2024-12-01T13:34:04.392035", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/2_ci-smoke-tests-basic"}
- {"dt": "2024-12-01T13:34:04.393469", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml"}
- {"dt": "2024-12-01T13:34:04.393820", "lvl": "debug", "msg": "uuid=38035_1.5.4.9 testdef=None"}
- {"dt": "2024-12-01T13:34:04.393957", "lvl": "debug", "msg": "end: 1.5.4.9 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.394055", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.29", "extra": {"commit": "678c63ae73f5c70b9b06d292df74e66a9a1f7255", "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": "2024-12-01T13:34:04.394257", "lvl": "debug", "msg": "start: 1.5.4.10 test-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:04.395109", "lvl": "debug", "msg": "end: 1.5.4.10 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.395215", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "38035_1.5.4.9"}, "level": "1.5.4.10", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.395431", "lvl": "debug", "msg": "start: 1.5.4.11 test-install-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:04.396491", "lvl": "debug", "msg": "end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.396597", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-smoke-tests-basic", "path": "test-suites/short-smoke/smoke-tests-basic.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "38035_1.5.4.9", "uuid": "38035_1.5.4.9"}, "level": "1.5.4.11", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.396886", "lvl": "debug", "msg": "start: 1.5.4.12 test-runscript-overlay (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:04.397876", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/2_ci-smoke-tests-basic test_uuid 38035_1.5.4.9"}
- {"dt": "2024-12-01T13:34:04.398301", "lvl": "debug", "msg": "end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.398409", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/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": "38035_1.5.4.9"}, "level": "1.5.4.12", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.398638", "lvl": "debug", "msg": "start: 1.5.4.13 git-repo-action (timeout 01:28:43) [common]"}
- {"dt": "2024-12-01T13:34:04.398732", "lvl": "debug", "msg": "Using /lava-38035 at stage 1"}
- {"dt": "2024-12-01T13:34:04.398880", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2024-12-01T13:34:04.398974", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/3_ci-service-check'"}
- {"dt": "2024-12-01T13:34:04.691906", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/3_ci-service-check"}
- {"dt": "2024-12-01T13:34:04.693391", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml"}
- {"dt": "2024-12-01T13:34:04.693750", "lvl": "debug", "msg": "uuid=38035_1.5.4.13 testdef=None"}
- {"dt": "2024-12-01T13:34:04.693889", "lvl": "debug", "msg": "end: 1.5.4.13 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.693990", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.30", "extra": {"commit": "678c63ae73f5c70b9b06d292df74e66a9a1f7255", "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": "2024-12-01T13:34:04.694196", "lvl": "debug", "msg": "start: 1.5.4.14 test-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:04.695019", "lvl": "debug", "msg": "end: 1.5.4.14 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.695123", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "38035_1.5.4.13"}, "level": "1.5.4.14", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.695338", "lvl": "debug", "msg": "start: 1.5.4.15 test-install-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:04.696404", "lvl": "debug", "msg": "end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.696511", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-service-check", "path": "test-suites/short-smoke/service-check.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "38035_1.5.4.13", "uuid": "38035_1.5.4.13"}, "level": "1.5.4.15", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.696757", "lvl": "debug", "msg": "start: 1.5.4.16 test-runscript-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:04.697766", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1/tests/3_ci-service-check test_uuid 38035_1.5.4.13"}
- {"dt": "2024-12-01T13:34:04.697878", "lvl": "debug", "msg": "BUILD_TAGS=''"}
- {"dt": "2024-12-01T13:34:04.697961", "lvl": "debug", "msg": "DEVICE_TAGS=''"}
- {"dt": "2024-12-01T13:34:04.698191", "lvl": "debug", "msg": "end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.698287", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/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": "38035_1.5.4.13"}, "level": "1.5.4.16", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.698535", "lvl": "debug", "msg": "start: 1.5.4.17 git-repo-action (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:04.698630", "lvl": "debug", "msg": "Using /lava-38035 at stage 2"}
- {"dt": "2024-12-01T13:34:04.698776", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2024-12-01T13:34:04.698871", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/2/tests/4_screenshooter'"}
- {"dt": "2024-12-01T13:34:04.987688", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml"}
- {"dt": "2024-12-01T13:34:04.988109", "lvl": "debug", "msg": "uuid=38035_1.5.4.17 testdef=None"}
- {"dt": "2024-12-01T13:34:04.988267", "lvl": "debug", "msg": "end: 1.5.4.17 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.988366", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.29", "extra": {"commit": "678c63ae73f5c70b9b06d292df74e66a9a1f7255", "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": "2024-12-01T13:34:04.988568", "lvl": "debug", "msg": "start: 1.5.4.18 test-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:04.989234", "lvl": "debug", "msg": "end: 1.5.4.18 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.989315", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "38035_1.5.4.17"}, "level": "1.5.4.18", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.989528", "lvl": "debug", "msg": "start: 1.5.4.19 test-install-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:04.990417", "lvl": "debug", "msg": "end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.990519", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "screenshooter", "path": "test-suites/short-smoke/screenshooter.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "38035_1.5.4.17", "uuid": "38035_1.5.4.17"}, "level": "1.5.4.19", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.990742", "lvl": "debug", "msg": "start: 1.5.4.20 test-runscript-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:04.991607", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/2/tests/4_screenshooter test_uuid 38035_1.5.4.17"}
- {"dt": "2024-12-01T13:34:04.991851", "lvl": "debug", "msg": "end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:04.991953", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/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": "38035_1.5.4.17"}, "level": "1.5.4.20", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:04.992175", "lvl": "debug", "msg": "start: 1.5.4.21 git-repo-action (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:04.992267", "lvl": "debug", "msg": "Using /lava-38035 at stage 3"}
- {"dt": "2024-12-01T13:34:04.992411", "lvl": "info", "msg": "Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions"}
- {"dt": "2024-12-01T13:34:04.992504", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/3/tests/5_ci-dumpjournal'"}
- {"dt": "2024-12-01T13:34:05.279401", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/3/tests/5_ci-dumpjournal"}
- {"dt": "2024-12-01T13:34:05.280987", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml"}
- {"dt": "2024-12-01T13:34:05.281361", "lvl": "debug", "msg": "uuid=38035_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": "2024-12-01T13:34:05.281510", "lvl": "debug", "msg": "end: 1.5.4.21 git-repo-action (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.281610", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "0.29", "extra": {"commit": "678c63ae73f5c70b9b06d292df74e66a9a1f7255", "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": "2024-12-01T13:34:05.281816", "lvl": "debug", "msg": "start: 1.5.4.22 test-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:05.282548", "lvl": "debug", "msg": "end: 1.5.4.22 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.282631", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "uuid": "38035_1.5.4.21"}, "level": "1.5.4.22", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:05.282847", "lvl": "debug", "msg": "start: 1.5.4.23 test-install-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:05.283998", "lvl": "debug", "msg": "end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.284106", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.00", "extra": {"from": "git", "name": "ci-dumpjournal", "path": "test-suites/short-smoke/dumpjournal.yaml", "repository": "https://git.automotivelinux.org/src/qa-testdefinitions", "skipped test-install-overlay": "38035_1.5.4.21", "uuid": "38035_1.5.4.21"}, "level": "1.5.4.23", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:05.284330", "lvl": "debug", "msg": "start: 1.5.4.24 test-runscript-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:05.285372", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/3/tests/5_ci-dumpjournal test_uuid 38035_1.5.4.21"}
- {"dt": "2024-12-01T13:34:05.285601", "lvl": "debug", "msg": "end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.285683", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.00", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/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": "38035_1.5.4.21"}, "level": "1.5.4.24", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:05.285864", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2024-12-01T13:34:05.285946", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/0 for stage 0"}
- {"dt": "2024-12-01T13:34:05.286088", "lvl": "debug", "msg": "- 0_network-veth"}
- {"dt": "2024-12-01T13:34:05.286211", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/1 for stage 1"}
- {"dt": "2024-12-01T13:34:05.286349", "lvl": "debug", "msg": "- 1_ci-busybox"}
- {"dt": "2024-12-01T13:34:05.286436", "lvl": "debug", "msg": "- 2_ci-smoke-tests-basic"}
- {"dt": "2024-12-01T13:34:05.286515", "lvl": "debug", "msg": "- 3_ci-service-check"}
- {"dt": "2024-12-01T13:34:05.286615", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/2 for stage 2"}
- {"dt": "2024-12-01T13:34:05.286747", "lvl": "debug", "msg": "- 4_screenshooter"}
- {"dt": "2024-12-01T13:34:05.286852", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/38035/lava-overlay-dctu_66a/lava-38035/3 for stage 3"}
- {"dt": "2024-12-01T13:34:05.286982", "lvl": "debug", "msg": "- 5_ci-dumpjournal"}
- {"dt": "2024-12-01T13:34:05.287120", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:02) [common]"}
- {"dt": "2024-12-01T13:34:05.287232", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:05.399957", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.400150", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:05.400294", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.400434", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:02) [common]"}
- {"dt": "2024-12-01T13:34:05.400563", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:05.400684", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:05.400841", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.400961", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.401081", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:28:42) [common]"}
- {"dt": "2024-12-01T13:34:05.401177", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2024-12-01T13:34:05.401274", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61990, NBD-ROOT: 38035/nbd-deploy-tu1wd5zm/nbdroot/agl-ivi-demo-qt-h3ulcb.ext4"}
- {"dt": "2024-12-01T13:34:05.401850", "lvl": "debug", "msg": "nbd-server 61990 /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/nbdroot/agl-ivi-demo-qt-h3ulcb.ext4"}
- {"dt": "2024-12-01T13:34:05.405043", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-01T13:34:05.405232", "lvl": "debug", "msg": "output: ** (process:3161): WARNING **: 13:34:05.404: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2024-12-01T13:34:05.405336", "lvl": "debug", "msg": "output: "}
- {"dt": "2024-12-01T13:34:05.405418", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2024-12-01T13:34:05.405540", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.405661", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:01:18) [common]"}
- {"dt": "2024-12-01T13:34:05.405775", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2024-12-01T13:34:05.405880", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2024-12-01T13:34:05.405978", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.406075", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2024-12-01T13:34:05.406251", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2024-12-01T13:34:05.406335", "lvl": "debug", "msg": "- {BOOTX}: booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2024-12-01T13:34:05.406413", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x48000000"}
- {"dt": "2024-12-01T13:34:05.406487", "lvl": "debug", "msg": "- {DTB}: 38035/nbd-deploy-tu1wd5zm/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2024-12-01T13:34:05.406561", "lvl": "debug", "msg": "- {INITRD}: 38035/nbd-deploy-tu1wd5zm/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2024-12-01T13:34:05.406636", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x48080000"}
- {"dt": "2024-12-01T13:34:05.406709", "lvl": "debug", "msg": "- {KERNEL}: 38035/nbd-deploy-tu1wd5zm/kernel/Image"}
- {"dt": "2024-12-01T13:34:05.406781", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2024-12-01T13:34:05.406865", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2024-12-01T13:34:05.406938", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61990"}
- {"dt": "2024-12-01T13:34:05.407010", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2024-12-01T13:34:05.407081", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2024-12-01T13:34:05.407153", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4c000000"}
- {"dt": "2024-12-01T13:34:05.407225", "lvl": "debug", "msg": "- {RAMDISK}: 38035/nbd-deploy-tu1wd5zm/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2024-12-01T13:34:05.407297", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2024-12-01T13:34:05.407368", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2024-12-01T13:34:05.407439", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2024-12-01T13:34:05.407510", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2024-12-01T13:34:05.407580", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2024-12-01T13:34:05.407651", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2024-12-01T13:34:05.407720", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2024-12-01T13:34:05.407792", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-01T13:34:05.407865", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-01T13:34:05.407935", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2024-12-01T13:34:05.408005", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2024-12-01T13:34:05.408077", "lvl": "info", "msg": "- tftp 0x48080000 38035/nbd-deploy-tu1wd5zm/kernel/Image"}
- {"dt": "2024-12-01T13:34:05.408147", "lvl": "info", "msg": "- tftp 0x4c000000 38035/nbd-deploy-tu1wd5zm/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2024-12-01T13:34:05.408219", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2024-12-01T13:34:05.408289", "lvl": "info", "msg": "- tftp 0x48000000 38035/nbd-deploy-tu1wd5zm/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2024-12-01T13:34:05.408360", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 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": "2024-12-01T13:34:05.408439", "lvl": "info", "msg": "- booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2024-12-01T13:34:05.408541", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.408623", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.00", "extra": {"dtb_addr": "0x48000000", "kernel_addr": "0x48080000", "ramdisk_addr": "0x4c000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:05.408829", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2024-12-01T13:34:05.408911", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63004'"}
- {"dt": "2024-12-01T13:34:05.412189", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2024-12-01T13:34:05.412600", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:34:05.412862", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:20:00) [common]"}
- {"dt": "2024-12-01T13:34:05.413004", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:20:00) [common]"}
- {"dt": "2024-12-01T13:34:05.413123", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:20:00) [common]"}
- {"dt": "2024-12-01T13:34:05.413353", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '8'"}
- {"dt": "2024-12-01T13:34:05.529207", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-01T13:34:06.592169", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-01T13:34:06.600486", "lvl": "debug", "msg": "Returned 0 in 1 seconds"}
- {"dt": "2024-12-01T13:34:06.701313", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T13:34:06.701942", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "1.29", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:34:06.702755", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T13:34:06.703295", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:59) [common]"}
- {"dt": "2024-12-01T13:34:06.703747", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2024-12-01T13:34:06.704097", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2024-12-01T13:34:06.705287", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2024-12-01T13:34:06.705720", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2024-12-01T13:34:06.706013", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2024-12-01T13:34:06.855237", "lvl": "target", "msg": "\0[    0.000149] NOTICE:  BL2: R-Car H3 Initial Program Loader(CA57)"}
- {"dt": "2024-12-01T13:34:06.855801", "lvl": "target", "msg": "[    0.004585] NOTICE:  BL2: Initial Program Loader(Rev.2.0.6)"}
- {"dt": "2024-12-01T13:34:06.856162", "lvl": "target", "msg": "[    0.010118] NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-12-01T13:34:06.856482", "lvl": "target", "msg": "[    0.014780] NOTICE:  BL2: Board is unknown Rev.---"}
- {"dt": "2024-12-01T13:34:06.856815", "lvl": "target", "msg": "[    0.019552] NOTICE:  BL2: Boot device is eMMC(50MHz x8)"}
- {"dt": "2024-12-01T13:34:06.857435", "lvl": "target", "msg": "[    0.024721] NOTICE:  BL2: LCM state is CM"}
- {"dt": "2024-12-01T13:34:06.857704", "lvl": "target", "msg": "[    0.028762] NOTICE:  BL2: AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2024-12-01T13:34:06.857981", "lvl": "target", "msg": "[    0.034738] NOTICE:  BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:34:06.886280", "lvl": "target", "msg": "[    0.040600] NOTICE:  BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:34:06.886695", "lvl": "target", "msg": "[    0.046474] NOTICE:  BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:34:06.886921", "lvl": "target", "msg": "[    0.052350] NOTICE:  BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:34:06.887124", "lvl": "target", "msg": "[    0.058267] NOTICE:  BL2: DDR3200(rev.0.40)"}
- {"dt": "2024-12-01T13:34:06.889431", "lvl": "target", "msg": "[    0.073569] NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2024-12-01T13:34:06.921936", "lvl": "target", "msg": "[    0.083098] NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2024-12-01T13:34:06.922120", "lvl": "target", "msg": "[    0.086397] NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2024-12-01T13:34:06.922218", "lvl": "target", "msg": "[    0.091840] NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2024-12-01T13:34:06.924182", "lvl": "target", "msg": "[    0.097197] NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2024-12-01T13:34:06.965301", "lvl": "target", "msg": "[    0.114668] NOTICE:  BL2: v1.5(release):af9f429a-dirty"}
- {"dt": "2024-12-01T13:34:06.965629", "lvl": "target", "msg": "[    0.118312] NOTICE:  BL2: Built : 02:23:33, Feb  7 2020"}
- {"dt": "2024-12-01T13:34:06.965807", "lvl": "target", "msg": "[    0.123500] NOTICE:  BL2: Normal boot"}
- {"dt": "2024-12-01T13:34:06.965994", "lvl": "target", "msg": "[    0.127135] NOTICE:  BL2: eMMC boot from partition 1"}
- {"dt": "2024-12-01T13:34:06.966135", "lvl": "target", "msg": "[    0.133683] NOTICE:  BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1)"}
- {"dt": "2024-12-01T13:34:06.966268", "lvl": "target", "msg": "[    0.139799] NOTICE:  BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12)"}
- {"dt": "2024-12-01T13:34:06.968385", "lvl": "target", "msg": "[    0.147399] NOTICE:  BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128)"}
- {"dt": "2024-12-01T13:34:06.989240", "lvl": "target", "msg": "[    0.156376] NOTICE:  BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048)"}
- {"dt": "2024-12-01T13:34:07.019386", "lvl": "target", "msg": "[    0.187304] NOTICE:  BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048)"}
- {"dt": "2024-12-01T13:34:07.035391", "lvl": "target", "msg": "[    0.216045] NOTICE:  BL2: Booting BL31"}
- {"dt": "2024-12-01T13:34:07.272183", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:34:07.272889", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:34:07.273313", "lvl": "target", "msg": "U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000)"}
- {"dt": "2024-12-01T13:34:07.273706", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:34:07.274021", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2024-12-01T13:34:07.275272", "lvl": "target", "msg": "Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-12-01T13:34:07.643823", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2024-12-01T13:34:07.644298", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2024-12-01T13:34:07.644600", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:34:07.646852", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:34:07.647273", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:34:07.647562", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:34:07.678559", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2"}
- {"dt": "2024-12-01T13:34:08.008207", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2024-12-01T13:34:08.008417", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2024-12-01T13:34:08.008517", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2024-12-01T13:34:08.011323", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2024-12-01T13:34:08.059568", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2024-12-01T13:34:08.060335", "lvl": "input", "msg": "\n"}
- {"dt": "2024-12-01T13:34:08.110774", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2024-12-01T13:34:08.111573", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T13:34:08.112089", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:57) [common]"}
- {"dt": "2024-12-01T13:34:08.112447", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2024-12-01T13:34:08.112839", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:57)"}
- {"dt": "2024-12-01T13:34:08.139582", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2024-12-01T13:34:08.140444", "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": "2024-12-01T13:34:08.140845", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2024-12-01T13:34:08.241426", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2024-12-01T13:34:08.242111", "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:57)"}
- {"dt": "2024-12-01T13:34:08.251410", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2024-12-01T13:34:08.252213", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2024-12-01T13:34:08.353049", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-01T13:34:08.353904", "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:57)"}
- {"dt": "2024-12-01T13:34:08.363379", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2024-12-01T13:34:08.364116", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2024-12-01T13:34:08.464773", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-01T13:34:08.465485", "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:57)"}
- {"dt": "2024-12-01T13:34:08.475318", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2024-12-01T13:34:08.476025", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2024-12-01T13:34:08.576791", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2024-12-01T13:34:08.577583", "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:57)"}
- {"dt": "2024-12-01T13:34:08.587252", "lvl": "target", "msg": "dhcp"}
- {"dt": "2024-12-01T13:34:14.739954", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2024-12-01T13:34:14.740527", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2024-12-01T13:34:14.995591", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2024-12-01T13:34:15.490744", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2024-12-01T13:34:16.002624", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.11 (1258 ms)"}
- {"dt": "2024-12-01T13:34:16.003441", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2024-12-01T13:34:16.104124", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2024-12-01T13:34:16.104797", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2024-12-01T13:34:16.114307", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2024-12-01T13:34:16.115070", "lvl": "input", "msg": "tftp 0x48080000 38035/nbd-deploy-tu1wd5zm/kernel/Image\n"}
- {"dt": "2024-12-01T13:34:16.215740", "lvl": "target", "msg": "=> tftp 0x48080000 38035/nbd-deploy-tu1wd5zm/kernel/Image"}
- {"dt": "2024-12-01T13:34:16.216425", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:49)"}
- {"dt": "2024-12-01T13:34:16.226107", "lvl": "target", "msg": "tftp 0x48080000 38035/nbd-deploy-tu1wd5zm/kernel/Image"}
- {"dt": "2024-12-01T13:34:16.269084", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-01T13:34:16.269560", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-01T13:34:16.269973", "lvl": "target", "msg": "Filename '38035/nbd-deploy-tu1wd5zm/kernel/Image'."}
- {"dt": "2024-12-01T13:34:16.270370", "lvl": "target", "msg": "Load address: 0x48080000"}
- {"dt": "2024-12-01T13:34:16.598741", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2024-12-01T13:34:16.951166", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:17.318686", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:17.654414", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:18.005910", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:18.948950", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:19.939565", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:21.249949", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:21.857397", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:22.224897", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:22.560553", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:23.119910", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:23.599247", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:23.966973", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:24.382617", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:24.718170", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:26.092716", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:27.323150", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:28.376977", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:29.784318", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:31.094710", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:32.421216", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:32.820171", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:34.162251", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:37.535034", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:40.970817", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:42.153360", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:44.119209", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:47.970691", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:51.805961", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:55.210097", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:57.559411", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:58.374506", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:58.757858", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:59.077474", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:59.413130", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:34:59.764597", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:35:00.100284", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:35:00.420010", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:35:00.755606", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:35:01.075228", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:35:01.075847", "lvl": "target", "msg": "\t #"}
- {"dt": "2024-12-01T13:35:01.076190", "lvl": "target", "msg": "\t 852.5 KiB/s"}
- {"dt": "2024-12-01T13:35:01.076547", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-01T13:35:01.091142", "lvl": "target", "msg": "Bytes transferred = 39135744 (2552a00 hex)"}
- {"dt": "2024-12-01T13:35:01.092084", "lvl": "input", "msg": "tftp 0x4c000000 38035/nbd-deploy-tu1wd5zm/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2024-12-01T13:35:01.192730", "lvl": "target", "msg": "=> tftp 0x4c000000 38035/nbd-deploy-tu1wd5zm/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2024-12-01T13:35:01.193390", "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:04)"}
- {"dt": "2024-12-01T13:35:01.200032", "lvl": "target", "msg": "tftp 0x4c000000 38035/nbd-deploy-tu1wd5zm/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2024-12-01T13:35:01.241067", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-01T13:35:01.241572", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-01T13:35:01.241989", "lvl": "target", "msg": "Filename '38035/nbd-deploy-tu1wd5zm/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2024-12-01T13:35:01.242364", "lvl": "target", "msg": "Load address: 0x4c000000"}
- {"dt": "2024-12-01T13:35:01.589538", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2024-12-01T13:35:01.925424", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2024-12-01T13:35:02.024836", "lvl": "target", "msg": "\t #################"}
- {"dt": "2024-12-01T13:35:02.025047", "lvl": "target", "msg": "\t 2.6 MiB/s"}
- {"dt": "2024-12-01T13:35:02.025151", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-01T13:35:02.025240", "lvl": "target", "msg": "Bytes transferred = 2151500 (20d44c hex)"}
- {"dt": "2024-12-01T13:35:02.028137", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2024-12-01T13:35:02.128554", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2024-12-01T13:35:02.128987", "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:03)"}
- {"dt": "2024-12-01T13:35:02.139848", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2024-12-01T13:35:02.140253", "lvl": "input", "msg": "tftp 0x48000000 38035/nbd-deploy-tu1wd5zm/dtb/r8a77951-agl-refhw.dtb\n"}
- {"dt": "2024-12-01T13:35:02.240627", "lvl": "target", "msg": "=> tftp 0x48000000 38035/nbd-deploy-tu1wd5zm/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2024-12-01T13:35:02.240982", "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:03)"}
- {"dt": "2024-12-01T13:35:02.247676", "lvl": "target", "msg": "tftp 0x48000000 38035/nbd-deploy-tu1wd5zm/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2024-12-01T13:35:02.288905", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2024-12-01T13:35:02.289148", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2024-12-01T13:35:02.289269", "lvl": "target", "msg": "Filename '38035/nbd-deploy-tu1wd5zm/dtb/r8a77951-agl-refhw.dtb'."}
- {"dt": "2024-12-01T13:35:02.289385", "lvl": "target", "msg": "Load address: 0x48000000"}
- {"dt": "2024-12-01T13:35:02.301700", "lvl": "target", "msg": "Loading: *\b#######"}
- {"dt": "2024-12-01T13:35:02.317856", "lvl": "target", "msg": "\t 2.8 MiB/s"}
- {"dt": "2024-12-01T13:35:02.318081", "lvl": "target", "msg": "done"}
- {"dt": "2024-12-01T13:35:02.318190", "lvl": "target", "msg": "Bytes transferred = 88958 (15b7e hex)"}
- {"dt": "2024-12-01T13:35:02.318504", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 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": "2024-12-01T13:35:02.418912", "lvl": "target", "msg": "=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 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": "2024-12-01T13:35:02.419398", "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:03)"}
- {"dt": "2024-12-01T13:35:02.436509", "lvl": "target", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 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": "2024-12-01T13:35:02.436959", "lvl": "input", "msg": "booti 0x48080000 0x4c000000:${initrd_size} 0x48000000\n"}
- {"dt": "2024-12-01T13:35:02.537348", "lvl": "target", "msg": "=> booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2024-12-01T13:35:02.537665", "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": "2024-12-01T13:35:02.537973", "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:03)"}
- {"dt": "2024-12-01T13:35:02.549289", "lvl": "target", "msg": "booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2024-12-01T13:35:02.682129", "lvl": "target", "msg": "## Flattened Device Tree blob at 48000000"}
- {"dt": "2024-12-01T13:35:02.682334", "lvl": "target", "msg": "   Booting using the fdt blob at 0x48000000"}
- {"dt": "2024-12-01T13:35:02.682436", "lvl": "target", "msg": "   Loading Ramdisk to bfbf7000, end bfe0444c ... OK"}
- {"dt": "2024-12-01T13:35:02.685186", "lvl": "target", "msg": "   Loading Device Tree to 00000000bfbde000, end 00000000bfbf6b7d ... OK"}
- {"dt": "2024-12-01T13:35:02.685357", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:35:02.685461", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2024-12-01T13:35:02.685553", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:35:02.685895", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:55) [common]"}
- {"dt": "2024-12-01T13:35:02.686055", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:19:03) [common]"}
- {"dt": "2024-12-01T13:35:02.686173", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2024-12-01T13:35:02.686289", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]', '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": "2024-12-01T13:35:02.686401", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]', '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:20:00)"}
- {"dt": "2024-12-01T13:35:04.003777", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073]"}
- {"dt": "2024-12-01T13:35:04.004086", "lvl": "target", "msg": "[    0.000000] Linux version 5.10.194-yocto-standard (oe-user@oe-host) (aarch64-agl-linux-gcc (GCC) 13.3.0, GNU ld (GNU Binutils) 2.42.0.20240716) #1 SMP PREEMPT Mon Nov 25 10:43:33 UTC 2024"}
- {"dt": "2024-12-01T13:35:04.004208", "lvl": "target", "msg": "[    0.000000] Machine model: AGL Reference Hardware based on r8a77951 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-12-01T13:35:04.004402", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2024-12-01T13:35:04.004504", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000057000000, size 16 MiB"}
- {"dt": "2024-12-01T13:35:04.004929", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:19:01) [common]"}
- {"dt": "2024-12-01T13:35:04.005086", "lvl": "warning", "msg": "The string '/ #' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely."}
- {"dt": "2024-12-01T13:35:04.005203", "lvl": "debug", "msg": "Setting prompt string to []"}
- {"dt": "2024-12-01T13:35:04.005407", "lvl": "debug", "msg": "Setting prompt string to ['-\\\\[ cut here \\\\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']"}
- {"dt": "2024-12-01T13:35:04.005714", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2024-12-01T13:35:04.005970", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2024-12-01T13:35:04.006262", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2024-12-01T13:35:04.006342", "lvl": "debug", "msg": ["-\\[ cut here \\]", "Unhandled fault", "BUG: KCSAN:", "BUG: KASAN:", "BUG: KFENCE:", "Oops(?: -|:)", "WARNING:", "(kernel BUG at|BUG:)", "invalid opcode:", "Kernel panic - not syncing", "root@h3ulcb:~", "h3ulcb:~#", "/ #", "login:", "Login incorrect"]}
- {"dt": "2024-12-01T13:35:04.006612", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:19:01)"}
- {"dt": "2024-12-01T13:35:04.046870", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,adsp@57000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-01T13:35:04.047163", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000058000000, size 384 MiB"}
- {"dt": "2024-12-01T13:35:04.047312", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma@58000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-01T13:35:04.047487", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000070000000, size 256 MiB"}
- {"dt": "2024-12-01T13:35:04.047640", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,multimedia@70000000, compatible id shared-dma-pool"}
- {"dt": "2024-12-01T13:35:04.049986", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2024-12-01T13:35:04.089739", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-12-01T13:35:04.089943", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x77efdfb00-0x77efe1fff]"}
- {"dt": "2024-12-01T13:35:04.090057", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2024-12-01T13:35:04.090147", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000048000000-0x00000000ffffffff]"}
- {"dt": "2024-12-01T13:35:04.090290", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2024-12-01T13:35:04.090480", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000077fffffff]"}
- {"dt": "2024-12-01T13:35:04.090628", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2024-12-01T13:35:04.090701", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2024-12-01T13:35:04.132602", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000048000000-0x0000000053ffffff]"}
- {"dt": "2024-12-01T13:35:04.133085", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000054000000-0x0000000056ffffff]"}
- {"dt": "2024-12-01T13:35:04.133193", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000057000000-0x00000000bfffffff]"}
- {"dt": "2024-12-01T13:35:04.133361", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000500000000-0x000000057fffffff]"}
- {"dt": "2024-12-01T13:35:04.133567", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000600000000-0x000000067fffffff]"}
- {"dt": "2024-12-01T13:35:04.133795", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000700000000-0x000000077fffffff]"}
- {"dt": "2024-12-01T13:35:04.134023", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2024-12-01T13:35:04.175671", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2024-12-01T13:35:04.175884", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2024-12-01T13:35:04.176014", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2024-12-01T13:35:04.176307", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS resident on physical CPU 0x0"}
- {"dt": "2024-12-01T13:35:04.176405", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.1"}
- {"dt": "2024-12-01T13:35:04.176513", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 33 pages/cpu s94808 r8192 d32168 u135168"}
- {"dt": "2024-12-01T13:35:04.176628", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2024-12-01T13:35:04.176750", "lvl": "target", "msg": "[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2024-12-01T13:35:04.178824", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v2"}
- {"dt": "2024-12-01T13:35:04.218584", "lvl": "target", "msg": "[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923"}
- {"dt": "2024-12-01T13:35:04.218788", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-BHB"}
- {"dt": "2024-12-01T13:35:04.218894", "lvl": "target", "msg": "[    0.000000] CPU features: detected: ARM erratum 1742098"}
- {"dt": "2024-12-01T13:35:04.218980", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2032128"}
- {"dt": "2024-12-01T13:35:04.219079", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2024-12-01T13:35:04.261504", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61990 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": "2024-12-01T13:35:04.261722", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.261835", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.261977", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off"}
- {"dt": "2024-12-01T13:35:04.262157", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000bbbde000-0x00000000bfbde000] (64MB)"}
- {"dt": "2024-12-01T13:35:04.304364", "lvl": "target", "msg": "[    0.000000] Memory: 7268736K/8257536K available (17664K kernel code, 3786K rwdata, 9332K rodata, 7296K init, 563K bss, 317056K reserved, 671744K cma-reserved)"}
- {"dt": "2024-12-01T13:35:04.304596", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2024-12-01T13:35:04.304696", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2024-12-01T13:35:04.304841", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2024-12-01T13:35:04.305032", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2024-12-01T13:35:04.305110", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2024-12-01T13:35:04.305185", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2024-12-01T13:35:04.347327", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2024-12-01T13:35:04.347532", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2024-12-01T13:35:04.347641", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2024-12-01T13:35:04.347730", "lvl": "target", "msg": "[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000"}
- {"dt": "2024-12-01T13:35:04.347811", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2024-12-01T13:35:04.347895", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys)."}
- {"dt": "2024-12-01T13:35:04.390448", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns"}
- {"dt": "2024-12-01T13:35:04.390729", "lvl": "target", "msg": "[    0.000003] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns"}
- {"dt": "2024-12-01T13:35:04.390866", "lvl": "target", "msg": "[    0.000189] Console: colour dummy device 80x25"}
- {"dt": "2024-12-01T13:35:04.391026", "lvl": "target", "msg": "[    0.000271] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333)"}
- {"dt": "2024-12-01T13:35:04.391295", "lvl": "target", "msg": "[    0.000284] pid_max: default: 32768 minimum: 301"}
- {"dt": "2024-12-01T13:35:04.391552", "lvl": "target", "msg": "[    0.000338] LSM: Security Framework initializing"}
- {"dt": "2024-12-01T13:35:04.391724", "lvl": "target", "msg": "[    0.000365] SELinux:  Initializing."}
- {"dt": "2024-12-01T13:35:04.393540", "lvl": "target", "msg": "[    0.000409] LSM support for eBPF active"}
- {"dt": "2024-12-01T13:35:04.433558", "lvl": "target", "msg": "[    0.000480] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.433821", "lvl": "target", "msg": "[    0.000527] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.433953", "lvl": "target", "msg": "[    0.002117] rcu: Hierarchical SRCU implementation."}
- {"dt": "2024-12-01T13:35:04.434193", "lvl": "target", "msg": "[    0.003760] Detected Renesas R-Car Gen3 r8a7795 ES3.0"}
- {"dt": "2024-12-01T13:35:04.434458", "lvl": "target", "msg": "[    0.005744] EFI services will not be available."}
- {"dt": "2024-12-01T13:35:04.434683", "lvl": "target", "msg": "[    0.006097] smp: Bringing up secondary CPUs ..."}
- {"dt": "2024-12-01T13:35:04.434876", "lvl": "target", "msg": "[    0.006585] Detected PIPT I-cache on CPU1"}
- {"dt": "2024-12-01T13:35:04.436469", "lvl": "target", "msg": "[    0.006633] CPU1: Booted secondary processor 0x0000000001 [0x411fd073]"}
- {"dt": "2024-12-01T13:35:04.476252", "lvl": "target", "msg": "[    0.007135] Detected PIPT I-cache on CPU2"}
- {"dt": "2024-12-01T13:35:04.476551", "lvl": "target", "msg": "[    0.007159] CPU2: Booted secondary processor 0x0000000002 [0x411fd073]"}
- {"dt": "2024-12-01T13:35:04.476657", "lvl": "target", "msg": "[    0.007612] Detected PIPT I-cache on CPU3"}
- {"dt": "2024-12-01T13:35:04.476756", "lvl": "target", "msg": "[    0.007637] CPU3: Booted secondary processor 0x0000000003 [0x411fd073]"}
- {"dt": "2024-12-01T13:35:04.476842", "lvl": "target", "msg": "[    0.008097] CPU features: detected: ARM erratum 845719"}
- {"dt": "2024-12-01T13:35:04.477052", "lvl": "target", "msg": "[    0.008109] Detected VIPT I-cache on CPU4"}
- {"dt": "2024-12-01T13:35:04.477295", "lvl": "target", "msg": "[    0.008154] CPU4: Booted secondary processor 0x0000000100 [0x410fd034]"}
- {"dt": "2024-12-01T13:35:04.477488", "lvl": "target", "msg": "[    0.008657] Detected VIPT I-cache on CPU5"}
- {"dt": "2024-12-01T13:35:04.519254", "lvl": "target", "msg": "[    0.008684] CPU5: Booted secondary processor 0x0000000101 [0x410fd034]"}
- {"dt": "2024-12-01T13:35:04.519442", "lvl": "target", "msg": "[    0.009172] Detected VIPT I-cache on CPU6"}
- {"dt": "2024-12-01T13:35:04.519542", "lvl": "target", "msg": "[    0.009199] CPU6: Booted secondary processor 0x0000000102 [0x410fd034]"}
- {"dt": "2024-12-01T13:35:04.519625", "lvl": "target", "msg": "[    0.009657] Detected VIPT I-cache on CPU7"}
- {"dt": "2024-12-01T13:35:04.519702", "lvl": "target", "msg": "[    0.009684] CPU7: Booted secondary processor 0x0000000103 [0x410fd034]"}
- {"dt": "2024-12-01T13:35:04.519779", "lvl": "target", "msg": "[    0.009764] smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2024-12-01T13:35:04.519852", "lvl": "target", "msg": "[    0.009791] SMP: Total of 8 processors activated."}
- {"dt": "2024-12-01T13:35:04.519960", "lvl": "target", "msg": "[    0.009797] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2024-12-01T13:35:04.522351", "lvl": "target", "msg": "[    0.009802] CPU features: detected: CRC32 instructions"}
- {"dt": "2024-12-01T13:35:04.562156", "lvl": "target", "msg": "[    0.009806] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2024-12-01T13:35:04.562652", "lvl": "target", "msg": "[    0.023636] CPU: All CPU(s) started at EL2"}
- {"dt": "2024-12-01T13:35:04.562771", "lvl": "target", "msg": "[    0.023687] alternatives: patching kernel code"}
- {"dt": "2024-12-01T13:35:04.562855", "lvl": "target", "msg": "[    0.024816] devtmpfs: initialized"}
- {"dt": "2024-12-01T13:35:04.562932", "lvl": "target", "msg": "[    0.031871] KASLR disabled due to lack of seed"}
- {"dt": "2024-12-01T13:35:04.563068", "lvl": "target", "msg": "[    0.032015] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2024-12-01T13:35:04.563164", "lvl": "target", "msg": "[    0.032030] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.565252", "lvl": "target", "msg": "[    0.040701] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2024-12-01T13:35:04.605201", "lvl": "target", "msg": "[    0.042118] DMI not present or invalid."}
- {"dt": "2024-12-01T13:35:04.605419", "lvl": "target", "msg": "[    0.042592] NET: Registered protocol family 16"}
- {"dt": "2024-12-01T13:35:04.605516", "lvl": "target", "msg": "[    0.043544] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2024-12-01T13:35:04.605601", "lvl": "target", "msg": "[    0.043715] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2024-12-01T13:35:04.605814", "lvl": "target", "msg": "[    0.044001] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2024-12-01T13:35:04.606064", "lvl": "target", "msg": "[    0.044040] audit: initializing netlink subsys (disabled)"}
- {"dt": "2024-12-01T13:35:04.648106", "lvl": "target", "msg": "[    0.044235] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2024-12-01T13:35:04.648309", "lvl": "target", "msg": "[    0.045340] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2024-12-01T13:35:04.648409", "lvl": "target", "msg": "[    0.045903] cpuidle: using governor menu"}
- {"dt": "2024-12-01T13:35:04.648494", "lvl": "target", "msg": "[    0.046083] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2024-12-01T13:35:04.648651", "lvl": "target", "msg": "[    0.046209] ASID allocator initialised with 65536 entries"}
- {"dt": "2024-12-01T13:35:04.648805", "lvl": "target", "msg": "[    0.047468] Serial: AMBA PL011 UART driver"}
- {"dt": "2024-12-01T13:35:04.648887", "lvl": "target", "msg": "[    0.091321] sh-pfc e6060000.pinctrl: r8a77951_pfc support registered"}
- {"dt": "2024-12-01T13:35:04.651233", "lvl": "target", "msg": "[    0.105703] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-01T13:35:04.691017", "lvl": "target", "msg": "[    0.105711] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-01T13:35:04.691226", "lvl": "target", "msg": "[    0.105716] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-01T13:35:04.691333", "lvl": "target", "msg": "[    0.105721] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2024-12-01T13:35:04.691450", "lvl": "target", "msg": "[    0.106719] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2024-12-01T13:35:04.691613", "lvl": "target", "msg": "[    0.109003] ACPI: Interpreter disabled."}
- {"dt": "2024-12-01T13:35:04.691769", "lvl": "target", "msg": "[    0.112808] rcar-avs: use avs value: 0"}
- {"dt": "2024-12-01T13:35:04.691914", "lvl": "target", "msg": "[    0.112820] rcar-avs: cpu@0 is running with: opp_table0"}
- {"dt": "2024-12-01T13:35:04.694159", "lvl": "target", "msg": "[    0.112827] rcar-avs: cpu@1 is running with: opp_table0"}
- {"dt": "2024-12-01T13:35:04.733992", "lvl": "target", "msg": "[    0.112834] rcar-avs: cpu@2 is running with: opp_table0"}
- {"dt": "2024-12-01T13:35:04.734188", "lvl": "target", "msg": "[    0.112841] rcar-avs: cpu@3 is running with: opp_table0"}
- {"dt": "2024-12-01T13:35:04.734285", "lvl": "target", "msg": "[    0.112919] rcar-avs: gsx@fd000000 is running with: gsx_opp_table0"}
- {"dt": "2024-12-01T13:35:04.734379", "lvl": "target", "msg": "[    0.114276] iommu: Default domain type: Translated "}
- {"dt": "2024-12-01T13:35:04.734590", "lvl": "target", "msg": "[    0.114822] ipmmu-vmsa e67b0000.iommu: IPMMU context 0 is reserved"}
- {"dt": "2024-12-01T13:35:04.734818", "lvl": "target", "msg": "[    0.115776] vgaarb: loaded"}
- {"dt": "2024-12-01T13:35:04.735023", "lvl": "target", "msg": "[    0.115990] SCSI subsystem initialized"}
- {"dt": "2024-12-01T13:35:04.735137", "lvl": "target", "msg": "[    0.116247] usbcore: registered new interface driver usbfs"}
- {"dt": "2024-12-01T13:35:04.736243", "lvl": "target", "msg": "[    0.116269] usbcore: registered new interface driver hub"}
- {"dt": "2024-12-01T13:35:04.776960", "lvl": "target", "msg": "[    0.116291] usbcore: registered new device driver usb"}
- {"dt": "2024-12-01T13:35:04.777162", "lvl": "target", "msg": "[    0.119939] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz"}
- {"dt": "2024-12-01T13:35:04.777274", "lvl": "target", "msg": "[    0.120459] mc: Linux media interface: v0.10"}
- {"dt": "2024-12-01T13:35:04.777480", "lvl": "target", "msg": "[    0.120478] videodev: Linux video capture interface: v2.00"}
- {"dt": "2024-12-01T13:35:04.777754", "lvl": "target", "msg": "[    0.120525] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2024-12-01T13:35:04.778011", "lvl": "target", "msg": "[    0.120530] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2024-12-01T13:35:04.778257", "lvl": "target", "msg": "[    0.120539] PTP clock support registered"}
- {"dt": "2024-12-01T13:35:04.780013", "lvl": "target", "msg": "[    0.120698] EDAC MC: Ver: 3.0.0"}
- {"dt": "2024-12-01T13:35:04.819843", "lvl": "target", "msg": "[    0.122565] FPGA manager framework"}
- {"dt": "2024-12-01T13:35:04.820141", "lvl": "target", "msg": "[    0.122625] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2024-12-01T13:35:04.820461", "lvl": "target", "msg": "[    0.123216] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2024-12-01T13:35:04.820630", "lvl": "target", "msg": "[    0.568617] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2024-12-01T13:35:04.820775", "lvl": "target", "msg": "[    0.568662] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2024-12-01T13:35:04.820909", "lvl": "target", "msg": "[    0.568820] pnp: PnP ACPI: disabled"}
- {"dt": "2024-12-01T13:35:04.821053", "lvl": "target", "msg": "[    0.572847] NET: Registered protocol family 2"}
- {"dt": "2024-12-01T13:35:04.821895", "lvl": "target", "msg": "[    0.573253] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.862817", "lvl": "target", "msg": "[    0.575463] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.863022", "lvl": "target", "msg": "[    0.575563] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.863129", "lvl": "target", "msg": "[    0.575842] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.863214", "lvl": "target", "msg": "[    0.576422] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2024-12-01T13:35:04.863405", "lvl": "target", "msg": "[    0.576552] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.865180", "lvl": "target", "msg": "[    0.576663] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2024-12-01T13:35:04.905839", "lvl": "target", "msg": "[    0.576883] NET: Registered protocol family 1"}
- {"dt": "2024-12-01T13:35:04.906060", "lvl": "target", "msg": "[    0.577271] RPC: Registered named UNIX socket transport module."}
- {"dt": "2024-12-01T13:35:04.906174", "lvl": "target", "msg": "[    0.577278] RPC: Registered udp transport module."}
- {"dt": "2024-12-01T13:35:04.906315", "lvl": "target", "msg": "[    0.577282] RPC: Registered tcp transport module."}
- {"dt": "2024-12-01T13:35:04.906456", "lvl": "target", "msg": "[    0.577285] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2024-12-01T13:35:04.906639", "lvl": "target", "msg": "[    0.577297] PCI: CLS 0 bytes, default 64"}
- {"dt": "2024-12-01T13:35:04.906737", "lvl": "target", "msg": "[    0.577443] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2024-12-01T13:35:04.909009", "lvl": "target", "msg": "[    0.577619] rootfs image is not initramfs (no cpio magic); looks like an initrd"}
- {"dt": "2024-12-01T13:35:04.948760", "lvl": "target", "msg": "[    0.580740] Freeing initrd memory: 2100K"}
- {"dt": "2024-12-01T13:35:04.949380", "lvl": "target", "msg": "[    0.581625] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available"}
- {"dt": "2024-12-01T13:35:04.949775", "lvl": "target", "msg": "[    0.581904] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available"}
- {"dt": "2024-12-01T13:35:04.950101", "lvl": "target", "msg": "[    0.582448] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2024-12-01T13:35:04.950402", "lvl": "target", "msg": "[    0.583321] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2024-12-01T13:35:04.950686", "lvl": "target", "msg": "[    0.583494] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2024-12-01T13:35:04.950945", "lvl": "target", "msg": "[    0.586735] Initialise system trusted keyrings"}
- {"dt": "2024-12-01T13:35:04.951771", "lvl": "target", "msg": "[    0.586889] workingset: timestamp_bits=42 max_order=21 bucket_order=0"}
- {"dt": "2024-12-01T13:35:04.991711", "lvl": "target", "msg": "[    0.590280] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2024-12-01T13:35:04.991960", "lvl": "target", "msg": "[    0.590715] NFS: Registering the id_resolver key type"}
- {"dt": "2024-12-01T13:35:04.992070", "lvl": "target", "msg": "[    0.590742] Key type id_resolver registered"}
- {"dt": "2024-12-01T13:35:04.992179", "lvl": "target", "msg": "[    0.590747] Key type id_legacy registered"}
- {"dt": "2024-12-01T13:35:04.992317", "lvl": "target", "msg": "[    0.590805] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2024-12-01T13:35:04.992425", "lvl": "target", "msg": "[    0.590811] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2024-12-01T13:35:04.992501", "lvl": "target", "msg": "[    0.590966] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2024-12-01T13:35:04.994820", "lvl": "target", "msg": "[    0.617314] NET: Registered protocol family 38"}
- {"dt": "2024-12-01T13:35:05.034715", "lvl": "target", "msg": "[    0.617323] Key type asymmetric registered"}
- {"dt": "2024-12-01T13:35:05.034903", "lvl": "target", "msg": "[    0.617328] Asymmetric key parser 'x509' registered"}
- {"dt": "2024-12-01T13:35:05.035022", "lvl": "target", "msg": "[    0.617360] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)"}
- {"dt": "2024-12-01T13:35:05.035130", "lvl": "target", "msg": "[    0.617366] io scheduler mq-deadline registered"}
- {"dt": "2024-12-01T13:35:05.035242", "lvl": "target", "msg": "[    0.617371] io scheduler kyber registered"}
- {"dt": "2024-12-01T13:35:05.035350", "lvl": "target", "msg": "[    0.632336] gpio_rcar e6050000.gpio: driving 16 GPIOs"}
- {"dt": "2024-12-01T13:35:05.035423", "lvl": "target", "msg": "[    0.632636] gpio_rcar e6051000.gpio: driving 29 GPIOs"}
- {"dt": "2024-12-01T13:35:05.035500", "lvl": "target", "msg": "[    0.632924] gpio_rcar e6052000.gpio: driving 15 GPIOs"}
- {"dt": "2024-12-01T13:35:05.077762", "lvl": "target", "msg": "[    0.633222] gpio_rcar e6053000.gpio: driving 16 GPIOs"}
- {"dt": "2024-12-01T13:35:05.077954", "lvl": "target", "msg": "[    0.633514] gpio_rcar e6054000.gpio: driving 18 GPIOs"}
- {"dt": "2024-12-01T13:35:05.078050", "lvl": "target", "msg": "[    0.633835] gpio_rcar e6055000.gpio: driving 26 GPIOs"}
- {"dt": "2024-12-01T13:35:05.078130", "lvl": "target", "msg": "[    0.634043] gpio-369 (wlan-pwr-en): hogged as output/high"}
- {"dt": "2024-12-01T13:35:05.078295", "lvl": "target", "msg": "[    0.634061] gpio-381 (can-1-transceiver-stb): hogged as output/low"}
- {"dt": "2024-12-01T13:35:05.078506", "lvl": "target", "msg": "[    0.634079] gpio-372 (can-2-transceiver-stb): hogged as output/low"}
- {"dt": "2024-12-01T13:35:05.078632", "lvl": "target", "msg": "[    0.634285] gpio_rcar e6055400.gpio: driving 32 GPIOs"}
- {"dt": "2024-12-01T13:35:05.078739", "lvl": "target", "msg": "[    0.634558] gpio_rcar e6055800.gpio: driving 4 GPIOs"}
- {"dt": "2024-12-01T13:35:05.120574", "lvl": "target", "msg": "[    0.636832] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges:"}
- {"dt": "2024-12-01T13:35:05.120810", "lvl": "target", "msg": "[    0.636854] rcar-pcie fe000000.pcie:       IO 0x00fe100000..0x00fe1fffff -> 0x0000000000"}
- {"dt": "2024-12-01T13:35:05.120929", "lvl": "target", "msg": "[    0.636872] rcar-pcie fe000000.pcie:      MEM 0x00fe200000..0x00fe3fffff -> 0x00fe200000"}
- {"dt": "2024-12-01T13:35:05.121051", "lvl": "target", "msg": "[    0.636887] rcar-pcie fe000000.pcie:      MEM 0x0030000000..0x0037ffffff -> 0x0030000000"}
- {"dt": "2024-12-01T13:35:05.121288", "lvl": "target", "msg": "[    0.636897] rcar-pcie fe000000.pcie:      MEM 0x0038000000..0x003fffffff -> 0x0038000000"}
- {"dt": "2024-12-01T13:35:05.123713", "lvl": "target", "msg": "[    0.636910] rcar-pcie fe000000.pcie:   IB MEM 0x0040000000..0x00bfffffff -> 0x0040000000"}
- {"dt": "2024-12-01T13:35:05.163562", "lvl": "target", "msg": "[    0.636950] rcar-pcie fe000000.pcie: no pcie3v3 regulator found"}
- {"dt": "2024-12-01T13:35:05.164003", "lvl": "target", "msg": "[    0.636958] rcar-pcie fe000000.pcie: no pcie1v8 regulator found"}
- {"dt": "2024-12-01T13:35:05.164152", "lvl": "target", "msg": "[    0.702849] rcar-pcie fe000000.pcie: PCIe link down"}
- {"dt": "2024-12-01T13:35:05.164414", "lvl": "target", "msg": "[    0.703014] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges:"}
- {"dt": "2024-12-01T13:35:05.164564", "lvl": "target", "msg": "[    0.703034] rcar-pcie ee800000.pcie:       IO 0x00ee900000..0x00ee9fffff -> 0x0000000000"}
- {"dt": "2024-12-01T13:35:05.164684", "lvl": "target", "msg": "[    0.703050] rcar-pcie ee800000.pcie:      MEM 0x00eea00000..0x00eebfffff -> 0x00eea00000"}
- {"dt": "2024-12-01T13:35:05.206639", "lvl": "target", "msg": "[    0.703065] rcar-pcie ee800000.pcie:      MEM 0x00c0000000..0x00c7ffffff -> 0x00c0000000"}
- {"dt": "2024-12-01T13:35:05.206927", "lvl": "target", "msg": "[    0.703074] rcar-pcie ee800000.pcie:      MEM 0x00c8000000..0x00cfffffff -> 0x00c8000000"}
- {"dt": "2024-12-01T13:35:05.207106", "lvl": "target", "msg": "[    0.703087] rcar-pcie ee800000.pcie:   IB MEM 0x0040000000..0x00bfffffff -> 0x0040000000"}
- {"dt": "2024-12-01T13:35:05.207423", "lvl": "target", "msg": "[    0.703114] rcar-pcie ee800000.pcie: no pcie3v3 regulator found"}
- {"dt": "2024-12-01T13:35:05.207686", "lvl": "target", "msg": "[    0.703121] rcar-pcie ee800000.pcie: no pcie1v8 regulator found"}
- {"dt": "2024-12-01T13:35:05.207936", "lvl": "target", "msg": "[    0.766847] rcar-pcie ee800000.pcie: PCIe link down"}
- {"dt": "2024-12-01T13:35:05.209738", "lvl": "target", "msg": "[    0.769133] EINJ: ACPI disabled."}
- {"dt": "2024-12-01T13:35:05.249528", "lvl": "target", "msg": "[    0.801719] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2024-12-01T13:35:05.249763", "lvl": "target", "msg": "[    0.804143] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2024-12-01T13:35:05.249871", "lvl": "target", "msg": "[    0.804501] e6540000.serial: ttySC4 at MMIO 0xe6540000 (irq = 44, base_baud = 0) is a hscif"}
- {"dt": "2024-12-01T13:35:05.249980", "lvl": "target", "msg": "[    0.804633] serial serial0: tty port ttySC4 registered"}
- {"dt": "2024-12-01T13:35:05.250175", "lvl": "target", "msg": "[    0.805038] e6550000.serial: ttySC3 at MMIO 0xe6550000 (irq = 45, base_baud = 0) is a hscif"}
- {"dt": "2024-12-01T13:35:05.250311", "lvl": "target", "msg": "[    0.805662] e6560000.serial: ttySC5 at MMIO 0xe6560000 (irq = 46, base_baud = 0) is a hscif"}
- {"dt": "2024-12-01T13:35:05.300136", "lvl": "target", "msg": "[    0.806241] e6e68000.serial: ttySC1 at MMIO 0xe6e68000 (irq = 134, base_baud = 0) is a scif"}
- {"dt": "2024-12-01T13:35:05.300455", "lvl": "target", "msg": "[    0.806834] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 135, base_baud = 0) is a scif"}
- {"dt": "2024-12-01T13:35:05.300578", "lvl": "target", "msg": "[    2.111221] printk: console [ttySC0] enabled"}
- {"dt": "2024-12-01T13:35:05.300926", "lvl": "target", "msg": "[    2.115998] e6f30000.serial: ttySC2 at MMIO 0xe6f30000 (irq = 136, base_baud = 0) is a scif"}
- {"dt": "2024-12-01T13:35:05.301201", "lvl": "target", "msg": "[    2.125501] msm_serial: driver initialized"}
- {"dt": "2024-12-01T13:35:05.301452", "lvl": "target", "msg": "[    2.135409] rcar-dw-hdmi fead0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2024-12-01T13:35:05.345266", "lvl": "target", "msg": "[    2.145283] rcar-dw-hdmi fead0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2024-12-01T13:35:05.345476", "lvl": "target", "msg": "[    2.153505] rcar-dw-hdmi feae0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2024-12-01T13:35:05.345580", "lvl": "target", "msg": "[    2.163359] rcar-dw-hdmi feae0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2024-12-01T13:35:05.345722", "lvl": "target", "msg": "[    2.178040] brd: module loaded"}
- {"dt": "2024-12-01T13:35:05.348297", "lvl": "target", "msg": "[    2.185527] loop: module loaded"}
- {"dt": "2024-12-01T13:35:05.396304", "lvl": "target", "msg": "[    2.237356] megasas: 07.714.04.00-rc1"}
- {"dt": "2024-12-01T13:35:05.442017", "lvl": "target", "msg": "[    2.247037] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2024-12-01T13:35:05.442289", "lvl": "target", "msg": "[    2.252924] thunder_xcv, ver 1.0"}
- {"dt": "2024-12-01T13:35:05.442492", "lvl": "target", "msg": "[    2.256173] thunder_bgx, ver 1.0"}
- {"dt": "2024-12-01T13:35:05.442778", "lvl": "target", "msg": "[    2.259402] nicpf, ver 1.0"}
- {"dt": "2024-12-01T13:35:05.443039", "lvl": "target", "msg": "[    2.263415] hclge is initializing"}
- {"dt": "2024-12-01T13:35:05.443290", "lvl": "target", "msg": "[    2.266727] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2024-12-01T13:35:05.443426", "lvl": "target", "msg": "[    2.273914] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2024-12-01T13:35:05.443671", "lvl": "target", "msg": "[    2.279237] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-12-01T13:35:05.445156", "lvl": "target", "msg": "[    2.284093] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2024-12-01T13:35:05.489459", "lvl": "target", "msg": "[    2.289840] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2024-12-01T13:35:05.489772", "lvl": "target", "msg": "[    2.294781] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2024-12-01T13:35:05.490125", "lvl": "target", "msg": "[    2.300698] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2024-12-01T13:35:05.490372", "lvl": "target", "msg": "[    2.306071] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2024-12-01T13:35:05.490549", "lvl": "target", "msg": "[    2.311629] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2024-12-01T13:35:05.490682", "lvl": "target", "msg": "[    2.317865] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2024-12-01T13:35:05.490811", "lvl": "target", "msg": "[    2.324142] sky2: driver version 1.30"}
- {"dt": "2024-12-01T13:35:05.530125", "lvl": "target", "msg": "[    2.331784] ravb e6800000.ethernet eth0: Base address at 0xe6800000, 00:80:45:1b:40:7f, IRQ 129."}
- {"dt": "2024-12-01T13:35:05.530322", "lvl": "target", "msg": "[    2.341936] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2024-12-01T13:35:05.530426", "lvl": "target", "msg": "[    2.348993] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2024-12-01T13:35:05.533306", "lvl": "target", "msg": "[    2.355496] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2024-12-01T13:35:05.533675", "lvl": "target", "msg": "[    2.359939] ehci-platform: EHCI generic platform driver"}
- {"dt": "2024-12-01T13:35:05.571909", "lvl": "target", "msg": "[    2.391282] ehci-platform ee080100.usb: EHCI Host Controller"}
- {"dt": "2024-12-01T13:35:05.572090", "lvl": "target", "msg": "[    2.396938] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1"}
- {"dt": "2024-12-01T13:35:05.575039", "lvl": "target", "msg": "[    2.404795] ehci-platform ee080100.usb: irq 182, io mem 0xee080100"}
- {"dt": "2024-12-01T13:35:05.621962", "lvl": "target", "msg": "[    2.423224] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-01T13:35:05.622148", "lvl": "target", "msg": "[    2.429765] hub 1-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:05.622251", "lvl": "target", "msg": "[    2.433516] hub 1-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:05.622333", "lvl": "target", "msg": "[    2.437911] ehci-platform ee0a0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-01T13:35:05.622508", "lvl": "target", "msg": "[    2.443556] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2"}
- {"dt": "2024-12-01T13:35:05.625011", "lvl": "target", "msg": "[    2.451401] ehci-platform ee0a0100.usb: irq 183, io mem 0xee0a0100"}
- {"dt": "2024-12-01T13:35:05.670865", "lvl": "target", "msg": "[    2.471222] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-01T13:35:05.671073", "lvl": "target", "msg": "[    2.477675] hub 2-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:05.671172", "lvl": "target", "msg": "[    2.481425] hub 2-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:05.671254", "lvl": "target", "msg": "[    2.485793] ehci-platform ee0c0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-01T13:35:05.671331", "lvl": "target", "msg": "[    2.491438] ehci-platform ee0c0100.usb: new USB bus registered, assigned bus number 3"}
- {"dt": "2024-12-01T13:35:05.671408", "lvl": "target", "msg": "[    2.499299] ehci-platform ee0c0100.usb: irq 184, io mem 0xee0c0100"}
- {"dt": "2024-12-01T13:35:05.697872", "lvl": "target", "msg": "[    2.519222] ehci-platform ee0c0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-01T13:35:05.698064", "lvl": "target", "msg": "[    2.525658] hub 3-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:05.701027", "lvl": "target", "msg": "[    2.529406] hub 3-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:05.739971", "lvl": "target", "msg": "[    2.559257] ehci-platform ee0e0100.usb: EHCI Host Controller"}
- {"dt": "2024-12-01T13:35:05.740289", "lvl": "target", "msg": "[    2.564903] ehci-platform ee0e0100.usb: new USB bus registered, assigned bus number 4"}
- {"dt": "2024-12-01T13:35:05.743112", "lvl": "target", "msg": "[    2.572732] ehci-platform ee0e0100.usb: irq 185, io mem 0xee0e0100"}
- {"dt": "2024-12-01T13:35:05.785351", "lvl": "target", "msg": "[    2.591221] ehci-platform ee0e0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2024-12-01T13:35:05.785523", "lvl": "target", "msg": "[    2.597650] hub 4-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:05.785618", "lvl": "target", "msg": "[    2.601401] hub 4-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:05.785697", "lvl": "target", "msg": "[    2.605740] ehci-orion: EHCI orion driver"}
- {"dt": "2024-12-01T13:35:05.785796", "lvl": "target", "msg": "[    2.609970] ehci-exynos: EHCI Exynos driver"}
- {"dt": "2024-12-01T13:35:05.785905", "lvl": "target", "msg": "[    2.614246] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2024-12-01T13:35:05.786016", "lvl": "target", "msg": "[    2.620414] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2024-12-01T13:35:05.786124", "lvl": "target", "msg": "[    2.624874] ohci-platform: OHCI generic platform driver"}
- {"dt": "2024-12-01T13:35:05.811702", "lvl": "target", "msg": "[    2.630283] ohci-platform ee080000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-01T13:35:05.812232", "lvl": "target", "msg": "[    2.636962] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 5"}
- {"dt": "2024-12-01T13:35:05.814803", "lvl": "target", "msg": "[    2.644798] ohci-platform ee080000.usb: irq 182, io mem 0xee080000"}
- {"dt": "2024-12-01T13:35:05.894740", "lvl": "target", "msg": "[    2.737612] hub 5-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:05.928824", "lvl": "target", "msg": "[    2.741363] hub 5-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:05.929262", "lvl": "target", "msg": "[    2.745679] ohci-platform ee0a0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-01T13:35:05.929493", "lvl": "target", "msg": "[    2.752358] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 6"}
- {"dt": "2024-12-01T13:35:05.931867", "lvl": "target", "msg": "[    2.760190] ohci-platform ee0a0000.usb: irq 183, io mem 0xee0a0000"}
- {"dt": "2024-12-01T13:35:06.011894", "lvl": "target", "msg": "[    2.853627] hub 6-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:06.045660", "lvl": "target", "msg": "[    2.857376] hub 6-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:06.045969", "lvl": "target", "msg": "[    2.861678] ohci-platform ee0c0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-01T13:35:06.046220", "lvl": "target", "msg": "[    2.868362] ohci-platform ee0c0000.usb: new USB bus registered, assigned bus number 7"}
- {"dt": "2024-12-01T13:35:06.048685", "lvl": "target", "msg": "[    2.876195] ohci-platform ee0c0000.usb: irq 184, io mem 0xee0c0000"}
- {"dt": "2024-12-01T13:35:06.162491", "lvl": "target", "msg": "[    2.969597] hub 7-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:06.162772", "lvl": "target", "msg": "[    2.973346] hub 7-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:06.162913", "lvl": "target", "msg": "[    2.977636] ohci-platform ee0e0000.usb: Generic Platform OHCI controller"}
- {"dt": "2024-12-01T13:35:06.163059", "lvl": "target", "msg": "[    2.984317] ohci-platform ee0e0000.usb: new USB bus registered, assigned bus number 8"}
- {"dt": "2024-12-01T13:35:06.165552", "lvl": "target", "msg": "[    2.992148] ohci-platform ee0e0000.usb: irq 185, io mem 0xee0e0000"}
- {"dt": "2024-12-01T13:35:06.273255", "lvl": "target", "msg": "[    3.085606] hub 8-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:06.273445", "lvl": "target", "msg": "[    3.089355] hub 8-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:06.273571", "lvl": "target", "msg": "[    3.093659] ohci-exynos: OHCI Exynos driver"}
- {"dt": "2024-12-01T13:35:06.273667", "lvl": "target", "msg": "[    3.098458] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-12-01T13:35:06.276300", "lvl": "target", "msg": "[    3.103671] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 9"}
- {"dt": "2024-12-01T13:35:06.320148", "lvl": "target", "msg": "[    3.126892] xhci-hcd ee000000.usb: hcc params 0x014051ce hci version 0x100 quirks 0x0000000000830410"}
- {"dt": "2024-12-01T13:35:06.320426", "lvl": "target", "msg": "[    3.136017] xhci-hcd ee000000.usb: irq 180, io mem 0xee000000"}
- {"dt": "2024-12-01T13:35:06.320594", "lvl": "target", "msg": "[    3.141859] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2024-12-01T13:35:06.320991", "lvl": "target", "msg": "[    3.147065] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 10"}
- {"dt": "2024-12-01T13:35:06.321302", "lvl": "target", "msg": "[    3.154517] xhci-hcd ee000000.usb: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2024-12-01T13:35:06.321537", "lvl": "target", "msg": "[    3.159231] usb usb7-port1: over-current condition"}
- {"dt": "2024-12-01T13:35:06.323262", "lvl": "target", "msg": "[    3.161074] hub 9-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:06.364388", "lvl": "target", "msg": "[    3.169272] hub 9-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:06.364770", "lvl": "target", "msg": "[    3.173353] usb usb10: We don't know the algorithms for LPM for this host, disabling LPM."}
- {"dt": "2024-12-01T13:35:06.364912", "lvl": "target", "msg": "[    3.181767] hub 10-0:1.0: USB hub found"}
- {"dt": "2024-12-01T13:35:06.365031", "lvl": "target", "msg": "[    3.185604] hub 10-0:1.0: 1 port detected"}
- {"dt": "2024-12-01T13:35:06.365325", "lvl": "target", "msg": "[    3.190425] usbcore: registered new interface driver usb-storage"}
- {"dt": "2024-12-01T13:35:06.365613", "lvl": "target", "msg": "[    3.196477] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2024-12-01T13:35:06.365886", "lvl": "target", "msg": "[    3.202985] usbserial: USB Serial support registered for generic"}
- {"dt": "2024-12-01T13:35:06.409984", "lvl": "target", "msg": "[    3.208987] usbcore: registered new interface driver cp210x"}
- {"dt": "2024-12-01T13:35:06.410453", "lvl": "target", "msg": "[    3.214541] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2024-12-01T13:35:06.410805", "lvl": "target", "msg": "[    3.222067] renesas_usbhs e6590000.usb: host probed"}
- {"dt": "2024-12-01T13:35:06.411105", "lvl": "target", "msg": "[    3.226934] renesas_usbhs e6590000.usb: no transceiver found"}
- {"dt": "2024-12-01T13:35:06.411392", "lvl": "target", "msg": "[    3.232636] renesas_usbhs e6590000.usb: gadget probed"}
- {"dt": "2024-12-01T13:35:06.411663", "lvl": "target", "msg": "[    3.237818] renesas_usbhs e6590000.usb: probed"}
- {"dt": "2024-12-01T13:35:06.412083", "lvl": "target", "msg": "[    3.242694] renesas_usbhs e659c000.usb: host probed"}
- {"dt": "2024-12-01T13:35:06.412426", "lvl": "target", "msg": "[    3.247561] renesas_usbhs e659c000.usb: no transceiver found"}
- {"dt": "2024-12-01T13:35:06.413645", "lvl": "target", "msg": "[    3.253248] renesas_usbhs e659c000.usb: gadget probed"}
- {"dt": "2024-12-01T13:35:06.438899", "lvl": "target", "msg": "[    3.258422] renesas_usbhs e659c000.usb: probed"}
- {"dt": "2024-12-01T13:35:06.439080", "lvl": "target", "msg": "[    3.263964] renesas_usb3 ee020000.usb: probed with phy"}
- {"dt": "2024-12-01T13:35:06.442075", "lvl": "target", "msg": "[    3.271164] i2c /dev entries driver"}
- {"dt": "2024-12-01T13:35:06.487666", "lvl": "target", "msg": "[    3.288096] cs2000-cp 2-004f: revision - C1"}
- {"dt": "2024-12-01T13:35:06.488106", "lvl": "target", "msg": "[    3.292326] i2c-rcar e6510000.i2c: probed"}
- {"dt": "2024-12-01T13:35:06.488244", "lvl": "target", "msg": "[    3.297332] i2c-rcar e66d0000.i2c: probed"}
- {"dt": "2024-12-01T13:35:06.488501", "lvl": "target", "msg": "[    3.303972] i2c-rcar e66d8000.i2c: probed"}
- {"dt": "2024-12-01T13:35:06.488623", "lvl": "target", "msg": "[    3.308658] i2c-rcar e66e0000.i2c: probed"}
- {"dt": "2024-12-01T13:35:06.489373", "lvl": "target", "msg": "[    3.313573] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2024-12-01T13:35:06.489503", "lvl": "target", "msg": "[    3.320078] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@8/endpoint on port 8"}
- {"dt": "2024-12-01T13:35:06.509972", "lvl": "target", "msg": "[    3.329007] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@a/endpoint on port 10"}
- {"dt": "2024-12-01T13:35:06.513085", "lvl": "target", "msg": "[    3.339162] adv748x 2-0070: chip found @ 0xe0 revision 2143"}
- {"dt": "2024-12-01T13:35:06.621833", "lvl": "target", "msg": "[    3.427239] usb 9-1: new high-speed USB device number 2 using xhci-hcd"}
- {"dt": "2024-12-01T13:35:06.622027", "lvl": "target", "msg": "[    3.434420] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@8/endpoint on port 8"}
- {"dt": "2024-12-01T13:35:06.622134", "lvl": "target", "msg": "[    3.443355] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@a/endpoint on port 10"}
- {"dt": "2024-12-01T13:35:06.624883", "lvl": "target", "msg": "[    3.453498] adv748x 2-0071: chip found @ 0xe2 revision 2143"}
- {"dt": "2024-12-01T13:35:06.753234", "lvl": "target", "msg": "[    3.553631] rcar-csi2 feaa0000.csi2: 4 lanes found"}
- {"dt": "2024-12-01T13:35:06.753458", "lvl": "target", "msg": "[    3.558757] rcar-csi2 feab0000.csi2: 4 lanes found"}
- {"dt": "2024-12-01T13:35:06.753560", "lvl": "target", "msg": "[    3.569180] rcar-vin e6ef0000.video: Device registered as video0"}
- {"dt": "2024-12-01T13:35:06.753657", "lvl": "target", "msg": "[    3.575288] rcar-vin e6ef1000.video: Device registered as video1"}
- {"dt": "2024-12-01T13:35:06.753754", "lvl": "target", "msg": "[    3.581407] rcar-vin e6ef2000.video: Device registered as video2"}
- {"dt": "2024-12-01T13:35:06.753833", "lvl": "target", "msg": "[    3.587489] rcar-vin e6ef3000.video: Device registered as video3"}
- {"dt": "2024-12-01T13:35:06.756329", "lvl": "target", "msg": "[    3.593554] rcar-vin e6ef4000.video: Device registered as video4"}
- {"dt": "2024-12-01T13:35:06.817317", "lvl": "target", "msg": "[    3.599620] rcar-vin e6ef5000.video: Device registered as video5"}
- {"dt": "2024-12-01T13:35:06.817499", "lvl": "target", "msg": "[    3.605685] rcar-vin e6ef6000.video: Device registered as video6"}
- {"dt": "2024-12-01T13:35:06.817601", "lvl": "target", "msg": "[    3.611750] rcar-vin e6ef7000.video: Device registered as video7"}
- {"dt": "2024-12-01T13:35:06.817684", "lvl": "target", "msg": "[    3.620367] rcar_gen3_thermal e6198000.thermal: is using pseudo fixed FUSE values"}
- {"dt": "2024-12-01T13:35:06.817818", "lvl": "target", "msg": "[    3.632450] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points"}
- {"dt": "2024-12-01T13:35:06.817956", "lvl": "target", "msg": "[    3.643642] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points"}
- {"dt": "2024-12-01T13:35:06.820339", "lvl": "target", "msg": "[    3.654742] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 3 trip points"}
- {"dt": "2024-12-01T13:35:06.863299", "lvl": "target", "msg": "[    3.666470] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2024-12-01T13:35:06.864156", "lvl": "target", "msg": "[    3.672641] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2024-12-01T13:35:06.864291", "lvl": "target", "msg": "[    3.677765] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2024-12-01T13:35:06.864386", "lvl": "target", "msg": "[    3.678195] renesas_sdhi_internal_dmac ee140000.mmc: Adding to iommu group 0"}
- {"dt": "2024-12-01T13:35:06.864471", "lvl": "target", "msg": "[    3.685007] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2024-12-01T13:35:06.864618", "lvl": "target", "msg": "[    3.690795] renesas_sdhi_internal_dmac ee140000.mmc: Using IPMMU context 1"}
- {"dt": "2024-12-01T13:35:06.866801", "lvl": "target", "msg": "[    3.703865] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2024-12-01T13:35:06.884414", "lvl": "target", "msg": "[    3.711410] usbcore: registered new interface driver usbhid"}
- {"dt": "2024-12-01T13:35:06.887493", "lvl": "target", "msg": "[    3.716966] usbhid: USB HID core driver"}
- {"dt": "2024-12-01T13:35:06.928617", "lvl": "target", "msg": "[    3.754505] renesas_sdhi_internal_dmac ee140000.mmc: mmc0 base at 0x00000000ee140000, max clock rate 200 MHz"}
- {"dt": "2024-12-01T13:35:07.034370", "lvl": "target", "msg": "[    3.838227] mmc0: new HS400 MMC card at address 0001"}
- {"dt": "2024-12-01T13:35:07.034698", "lvl": "target", "msg": "[    3.843878] mmcblk0: mmc0:0001 CJUD4R 59.6 GiB "}
- {"dt": "2024-12-01T13:35:07.034863", "lvl": "target", "msg": "[    3.848803] mmcblk0boot0: mmc0:0001 CJUD4R partition 1 31.9 MiB"}
- {"dt": "2024-12-01T13:35:07.035009", "lvl": "target", "msg": "[    3.851453] st_lsm6dsx_i2c 3-006a: mounting matrix not found: using identity..."}
- {"dt": "2024-12-01T13:35:07.035145", "lvl": "target", "msg": "[    3.855071] mmcblk0boot1: mmc0:0001 CJUD4R partition 2 31.9 MiB"}
- {"dt": "2024-12-01T13:35:07.035283", "lvl": "target", "msg": "[    3.864745] optee: probing for conduit method."}
- {"dt": "2024-12-01T13:35:07.037565", "lvl": "target", "msg": "[    3.868108] mmcblk0rpmb: mmc0:0001 CJUD4R partition 3 4.00 MiB, chardev (508:0)"}
- {"dt": "2024-12-01T13:35:07.067448", "lvl": "target", "msg": "[    3.872293] optee: revision 3.1 (19fb6dcb)"}
- {"dt": "2024-12-01T13:35:07.067982", "lvl": "target", "msg": "[    3.880197] optee driver R-Car Rev.1.0.11"}
- {"dt": "2024-12-01T13:35:07.068337", "lvl": "target", "msg": "[    3.881561]  mmcblk0: p1 p2"}
- {"dt": "2024-12-01T13:35:07.068624", "lvl": "target", "msg": "[    3.884689] optee: initialized driver"}
- {"dt": "2024-12-01T13:35:07.070653", "lvl": "target", "msg": "[    3.896409] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2024-12-01T13:35:07.105742", "lvl": "target", "msg": "[    3.916519] rcar_sound ec500000.sound: probed"}
- {"dt": "2024-12-01T13:35:07.106334", "lvl": "target", "msg": "[    3.921554] NET: Registered protocol family 17"}
- {"dt": "2024-12-01T13:35:07.106717", "lvl": "target", "msg": "[    3.926100] 9pnet: Installing 9P2000 support"}
- {"dt": "2024-12-01T13:35:07.107016", "lvl": "target", "msg": "[    3.930410] Key type dns_resolver registered"}
- {"dt": "2024-12-01T13:35:07.107282", "lvl": "target", "msg": "[    3.935055] registered taskstats version 1"}
- {"dt": "2024-12-01T13:35:07.108877", "lvl": "target", "msg": "[    3.939151] Loading compiled-in X.509 certificates"}
- {"dt": "2024-12-01T13:35:07.158760", "lvl": "target", "msg": "[    3.955255] thermal emergency: set temperature to 110 celsius"}
- {"dt": "2024-12-01T13:35:07.159312", "lvl": "target", "msg": "[    3.961172] thermal emergency: shutdown target cpus 1-7"}
- {"dt": "2024-12-01T13:35:07.159658", "lvl": "target", "msg": "[    3.966377] thermal emergency: freq scaled target cpus 0"}
- {"dt": "2024-12-01T13:35:07.159938", "lvl": "target", "msg": "[    3.972017] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs"}
- {"dt": "2024-12-01T13:35:07.160198", "lvl": "target", "msg": "[    3.983352] [drm] Initialized rcar-du 1.0.0 20130110 for feb00000.display on minor 0"}
- {"dt": "2024-12-01T13:35:07.161726", "lvl": "target", "msg": "[    3.991066] [drm] Device feb00000.display probed"}
- {"dt": "2024-12-01T13:35:07.552258", "lvl": "target", "msg": "[    4.356780] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2024-12-01T13:35:07.555467", "lvl": "target", "msg": "[    4.379699] rcar-du feb00000.display: [drm] fb0: rcar-dudrmfb frame buffer device"}
- {"dt": "2024-12-01T13:35:07.573267", "lvl": "target", "msg": "[    4.401317] cpu cpu0: EM: created perf domain"}
- {"dt": "2024-12-01T13:35:07.576380", "lvl": "target", "msg": "[    4.406073] cpu cpu4: EM: created perf domain"}
- {"dt": "2024-12-01T13:35:07.639504", "lvl": "target", "msg": "[    4.464483] renesas_sdhi_internal_dmac ee100000.mmc: mmc1 base at 0x00000000ee100000, max clock rate 200 MHz"}
- {"dt": "2024-12-01T13:35:07.716113", "lvl": "target", "msg": "[    4.541169] renesas_sdhi_internal_dmac ee160000.mmc: mmc2 base at 0x00000000ee160000, max clock rate 50 MHz"}
- {"dt": "2024-12-01T13:35:07.806011", "lvl": "target", "msg": "[    4.622187] Micrel KSZ9031 Gigabit PHY e6800000.ethernet-ffffffff:00: attached PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=e6800000.ethernet-ffffffff:00, irq=190)"}
- {"dt": "2024-12-01T13:35:14.474675", "lvl": "target", "msg": "[   11.296018] ravb e6800000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2024-12-01T13:35:17.317457", "lvl": "target", "msg": "[   11.319229] Sending DHCP requests .., OK"}
- {"dt": "2024-12-01T13:35:17.318022", "lvl": "target", "msg": "[   14.123164] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.11"}
- {"dt": "2024-12-01T13:35:17.318356", "lvl": "target", "msg": "[   14.130968] IP-Config: Complete:"}
- {"dt": "2024-12-01T13:35:17.319388", "lvl": "target", "msg": "[   14.134189]      device=eth0, hwaddr=00:80:45:1b:40:7f, ipaddr=192.168.66.11, mask=255.255.255.0, gw=192.168.66.1"}
- {"dt": "2024-12-01T13:35:17.319691", "lvl": "target", "msg": "[   14.144405]      host=192.168.66.11, domain=example.org, nis-domain=(none)"}
- {"dt": "2024-12-01T13:35:17.319948", "lvl": "target", "msg": "[   14.151254]      bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath="}
- {"dt": "2024-12-01T13:35:17.321000", "lvl": "target", "msg": "[   14.151259]      nameserver0=10.1.1.1"}
- {"dt": "2024-12-01T13:35:17.338359", "lvl": "target", "msg": "[   14.161591] ALSA device list:"}
- {"dt": "2024-12-01T13:35:17.338819", "lvl": "target", "msg": "[   14.164560]   #0: ak4613"}
- {"dt": "2024-12-01T13:35:17.341579", "lvl": "target", "msg": "[   14.167428] Waiting 7 sec before mounting root device..."}
- {"dt": "2024-12-01T13:35:24.389444", "lvl": "target", "msg": "[   21.215328] RAMDISK: gzip image found at block 0"}
- {"dt": "2024-12-01T13:35:24.525995", "lvl": "target", "msg": "[   21.327966] EXT4-fs (ram0): mounted filesystem with ordered data mode. Opts: (null)"}
- {"dt": "2024-12-01T13:35:24.526560", "lvl": "target", "msg": "[   21.335641] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2024-12-01T13:35:24.526867", "lvl": "target", "msg": "[   21.341561] devtmpfs: mounted"}
- {"dt": "2024-12-01T13:35:24.527137", "lvl": "target", "msg": "[   21.347830] Freeing unused kernel memory: 7296K"}
- {"dt": "2024-12-01T13:35:24.529221", "lvl": "target", "msg": "[   21.352413] Run /sbin/init as init process"}
- {"dt": "2024-12-01T13:35:24.568884", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-01T13:35:24.569433", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2024-12-01T13:35:24.569742", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2024-12-01T13:35:24.570017", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2024-12-01T13:35:24.570275", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2024-12-01T13:35:24.570526", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2024-12-01T13:35:24.613169", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2024-12-01T13:35:24.613727", "lvl": "target", "msg": "/sbin/init[1]: starting initrd script"}
- {"dt": "2024-12-01T13:35:24.614063", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2024-12-01T13:35:24.614333", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2024-12-01T13:35:24.615339", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2024-12-01T13:35:24.615611", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2024-12-01T13:35:24.615859", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2024-12-01T13:35:24.616104", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2024-12-01T13:35:24.616353", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2024-12-01T13:35:24.616600", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2024-12-01T13:35:24.616937", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.66.1:61990"}
- {"dt": "2024-12-01T13:35:24.631924", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2024-12-01T13:35:24.700337", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2024-12-01T13:35:24.700911", "lvl": "target", "msg": "[   21.499077] EXT4-fs (nbd0): couldn't mount RDWR because of unsupported optional features (10000)"}
- {"dt": "2024-12-01T13:35:24.701224", "lvl": "target", "msg": "[   21.507861] EXT4-fs (nbd0): couldn't mount as ext3 due to feature incompatibilities"}
- {"dt": "2024-12-01T13:35:24.701490", "lvl": "target", "msg": "[   21.517061] EXT4-fs (nbd0): couldn't mount RDWR because of unsupported optional features (10000)"}
- {"dt": "2024-12-01T13:35:24.701744", "lvl": "target", "msg": "[   21.527354] EXT2-fs (nbd0): error: couldn't mount because of unsupported optional features (22c4)"}
- {"dt": "2024-12-01T13:35:24.726678", "lvl": "target", "msg": "mount: mounting /dev/nbd0 on /sysroot failed: In[   21.541099] kvm: exiting hardware virtualization"}
- {"dt": "2024-12-01T13:35:24.727211", "lvl": "target", "msg": "valid argument"}
- {"dt": "2024-12-01T13:35:24.727513", "lvl": "target", "msg": "/sbin/init[1]: ERROR Unable to mount root NBD device"}
- {"dt": "2024-12-01T13:35:24.729950", "lvl": "target", "msg": "/sbin/init[1]: Rebooting..."}
- {"dt": "2024-12-01T13:35:24.761765", "lvl": "target", "msg": "[   21.596358] reboot: Restarting system"}
- {"dt": "2024-12-01T13:35:25.279524", "lvl": "target", "msg": "\0[    0.000149] NOTICE:  BL2: R-Car H3 Initial Program Loader(CA57)"}
- {"dt": "2024-12-01T13:35:25.280058", "lvl": "target", "msg": "[    0.004585] NOTICE:  BL2: Initial Program Loader(Rev.2.0.6)"}
- {"dt": "2024-12-01T13:35:25.280364", "lvl": "target", "msg": "[    0.010118] NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2024-12-01T13:35:25.280634", "lvl": "target", "msg": "[    0.014780] NOTICE:  BL2: Board is unknown Rev.---"}
- {"dt": "2024-12-01T13:35:25.280917", "lvl": "target", "msg": "[    0.019552] NOTICE:  BL2: Boot device is eMMC(50MHz x8)"}
- {"dt": "2024-12-01T13:35:25.281169", "lvl": "target", "msg": "[    0.024721] NOTICE:  BL2: LCM state is CM"}
- {"dt": "2024-12-01T13:35:25.282149", "lvl": "target", "msg": "[    0.028762] NOTICE:  BL2: AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2024-12-01T13:35:25.283122", "lvl": "target", "msg": "[    0.034738] NOTICE:  BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:35:25.338104", "lvl": "target", "msg": "[    0.040600] NOTICE:  BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:35:25.338654", "lvl": "target", "msg": "[    0.046474] NOTICE:  BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:35:25.338961", "lvl": "target", "msg": "[    0.052350] NOTICE:  BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:35:25.339228", "lvl": "target", "msg": "[    0.058267] NOTICE:  BL2: DDR3200(rev.0.40)"}
- {"dt": "2024-12-01T13:35:25.339479", "lvl": "target", "msg": "[    0.073570] NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2024-12-01T13:35:25.339725", "lvl": "target", "msg": "[    0.083132] NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2024-12-01T13:35:25.339965", "lvl": "target", "msg": "[    0.086431] NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2024-12-01T13:35:25.341433", "lvl": "target", "msg": "[    0.091874] NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2024-12-01T13:35:25.357347", "lvl": "target", "msg": "[    0.097231] NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2024-12-01T13:35:25.397231", "lvl": "target", "msg": "[    0.117371] NOTICE:  BL2: v1.5(release):af9f429a-dirty"}
- {"dt": "2024-12-01T13:35:25.397768", "lvl": "target", "msg": "[    0.121015] NOTICE:  BL2: Built : 02:23:33, Feb  7 2020"}
- {"dt": "2024-12-01T13:35:25.398070", "lvl": "target", "msg": "[    0.126203] NOTICE:  BL2: Normal boot"}
- {"dt": "2024-12-01T13:35:25.398337", "lvl": "target", "msg": "[    0.129838] NOTICE:  BL2: eMMC boot from partition 1"}
- {"dt": "2024-12-01T13:35:25.398590", "lvl": "target", "msg": "[    0.136381] NOTICE:  BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1)"}
- {"dt": "2024-12-01T13:35:25.398839", "lvl": "target", "msg": "[    0.142497] NOTICE:  BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12)"}
- {"dt": "2024-12-01T13:35:25.400397", "lvl": "target", "msg": "[    0.150097] NOTICE:  BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128)"}
- {"dt": "2024-12-01T13:35:25.421382", "lvl": "target", "msg": "[    0.159074] NOTICE:  BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048)"}
- {"dt": "2024-12-01T13:35:25.450144", "lvl": "target", "msg": "[    0.190001] NOTICE:  BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048)"}
- {"dt": "2024-12-01T13:35:25.465995", "lvl": "target", "msg": "[    0.218739] NOTICE:  BL2: Booting BL31"}
- {"dt": "2024-12-01T13:35:25.703584", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:35:25.704112", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:35:25.704419", "lvl": "target", "msg": "U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000)"}
- {"dt": "2024-12-01T13:35:25.704687", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:35:25.704977", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2024-12-01T13:35:25.706817", "lvl": "target", "msg": "Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2024-12-01T13:35:26.074188", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2024-12-01T13:35:26.074713", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2024-12-01T13:35:26.075016", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:35:26.077744", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:35:26.078268", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2024-12-01T13:35:26.078569", "lvl": "target", "msg": ""}
- {"dt": "2024-12-01T13:35:26.109370", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2"}
- {"dt": "2024-12-01T13:35:26.438693", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2024-12-01T13:35:26.439220", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2024-12-01T13:35:26.439516", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2024-12-01T13:35:26.441895", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2024-12-01T13:35:26.489283", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2024-12-01T13:35:29.504980", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 \b\b\b 2 \b\b\b 1 \b\b\b 0 "}
- {"dt": "2024-12-01T13:35:29.505520", "lvl": "target", "msg": "MMC: no card present"}
- {"dt": "2024-12-01T13:35:29.505823", "lvl": "target", "msg": "MMC: no card present"}
- {"dt": "2024-12-01T13:35:29.508246", "lvl": "target", "msg": "Bad Linux ARM64 Image magic!"}
- {"dt": "2024-12-01T13:54:05.005408", "lvl": "exception", "msg": "login-action timed out after 1141 seconds"}
- {"dt": "2024-12-01T13:54:05.006028", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:19:01) [common]"}
- {"dt": "2024-12-01T13:54:05.006383", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "1141.00", "extra": {"fail": "login-action timed out after 1141 seconds"}, "level": "2.4.4.1", "namespace": "common", "result": "fail"}}
- {"dt": "2024-12-01T13:54:05.006941", "lvl": "error", "msg": "auto-login-action failed: 1 of 1 attempts. 'login-action timed out after 1141 seconds'"}
- {"dt": "2024-12-01T13:54:05.007297", "lvl": "exception", "msg": "login-action timed out after 1141 seconds"}
- {"dt": "2024-12-01T13:54:05.007591", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:19:02) [common]"}
- {"dt": "2024-12-01T13:54:05.007887", "lvl": "results", "msg": {"case": "auto-login-action", "definition": "lava", "duration": "1142.32", "extra": {"fail": "login-action timed out after 1141 seconds"}, "level": "2.4.4", "namespace": "common", "result": "fail"}}
- {"dt": "2024-12-01T13:54:05.008461", "lvl": "exception", "msg": "login-action timed out after 1141 seconds"}
- {"dt": "2024-12-01T13:54:05.008815", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:20:00) [common]"}
- {"dt": "2024-12-01T13:54:05.009169", "lvl": "results", "msg": {"case": "uboot-commands", "definition": "lava", "duration": "1199.60", "extra": {"fail": "login-action timed out after 1141 seconds"}, "level": "2.4", "namespace": "common", "result": "fail"}}
- {"dt": "2024-12-01T13:54:05.009745", "lvl": "error", "msg": "uboot-action failed: 1 of 1 attempts. 'login-action timed out after 1141 seconds'"}
- {"dt": "2024-12-01T13:54:05.010298", "lvl": "exception", "msg": "login-action timed out after 1141 seconds"}
- {"dt": "2024-12-01T13:54:05.010729", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:20:00) [common]"}
- {"dt": "2024-12-01T13:54:05.011084", "lvl": "results", "msg": {"case": "uboot-action", "definition": "lava", "duration": "1199.60", "extra": {"fail": "login-action timed out after 1141 seconds"}, "level": "2", "namespace": "common", "result": "fail"}}
- {"dt": "2024-12-01T13:54:05.011804", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2024-12-01T13:54:05.012195", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/initrd"}
- {"dt": "2024-12-01T13:54:05.013511", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/kernel"}
- {"dt": "2024-12-01T13:54:05.017861", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/dtb"}
- {"dt": "2024-12-01T13:54:05.018574", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/38035/nbd-deploy-tu1wd5zm/nbdroot"}
- {"dt": "2024-12-01T13:54:05.207256", "lvl": "debug", "msg": "start: 7.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2024-12-01T13:54:05.207503", "lvl": "debug", "msg": "Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '8'"}
- {"dt": "2024-12-01T13:54:05.328834", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2024-12-01T13:54:05.349587", "lvl": "debug", "msg": "Returned 0 in 0 seconds"}
- {"dt": "2024-12-01T13:54:05.450535", "lvl": "debug", "msg": "end: 7.1 power-off (duration 00:00:00) [common]"}
- {"dt": "2024-12-01T13:54:05.451147", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "0.24", "extra": {"status": "success"}, "level": "7.1", "namespace": "common", "result": "pass"}}
- {"dt": "2024-12-01T13:54:05.451835", "lvl": "debug", "msg": "start: 7.2 read-feedback (timeout 00:30:00) [common]"}
- {"dt": "2024-12-01T13:54:05.452781", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2024-12-01T13:54:06.453697", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2024-12-01T13:54:06.454345", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2024-12-01T13:54:06.454710", "lvl": "target", "msg": "=> \uFFFD"}
- {"dt": "2024-12-01T13:54:06.555747", "lvl": "debug", "msg": "end: 7.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2024-12-01T13:54:06.556349", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2024-12-01T13:54:06.556688", "lvl": "debug", "msg": "clean NBD port 61990"}
- {"dt": "2024-12-01T13:54:06.670335", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/38035"}
- {"dt": "2024-12-01T13:54:06.686966", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/38035"}
- {"dt": "2024-12-01T13:54:06.687200", "lvl": "error", "msg": "JobError: Your job cannot terminate cleanly."}
- {"dt": "2024-12-01T13:54:06.687291", "lvl": "results", "msg": {"case": "job", "definition": "lava", "error_msg": "login-action timed out after 1141 seconds", "error_type": "Job", "result": "fail"}}
