- {"dt": "2023-03-09T06:11:52.774049", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2023.01"}
- {"dt": "2023-03-09T06:11:52.775411", "lvl": "info", "msg": "start: 0 validate"}
- {"dt": "2023-03-09T06:11:52.776417", "lvl": "info", "msg": "Start time: 2023-03-09 06:11:52.776359+00:00 (UTC)"}
- {"dt": "2023-03-09T06:11:52.777558", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists"}
- {"dt": "2023-03-09T06:11:53.032931", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/Image exists"}
- {"dt": "2023-03-09T06:11:53.237595", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/r8a77951-agl-refhw.dtb exists"}
- {"dt": "2023-03-09T06:11:53.453270", "lvl": "debug", "msg": "Validating that http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists"}
- {"dt": "2023-03-09T06:11:53.686048", "lvl": "info", "msg": "validate duration: 0.91"}
- {"dt": "2023-03-09T06:11:53.686840", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}}
- {"dt": "2023-03-09T06:11:53.687933", "lvl": "info", "msg": "start: 1 nbd-deploy (timeout 01:30:00) [common]"}
- {"dt": "2023-03-09T06:11:53.688531", "lvl": "info", "msg": "Making protocol call for nbd-deploy using lava-xnbd"}
- {"dt": "2023-03-09T06:11:53.689038", "lvl": "debug", "msg": "[nbd-deploy] Checking protocol data for lava-xnbd"}
- {"dt": "2023-03-09T06:11:53.689493", "lvl": "debug", "msg": "Get a port from pool"}
- {"dt": "2023-03-09T06:11:53.690360", "lvl": "debug", "msg": "Set_port 61957"}
- {"dt": "2023-03-09T06:11:53.691175", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 01:30:00) [common]"}
- {"dt": "2023-03-09T06:11:53.691929", "lvl": "debug", "msg": "start: 1.1.1 http-download (timeout 01:30:00) [common]"}
- {"dt": "2023-03-09T06:11:53.693046", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T06:11:53.693701", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T06:11:53.694198", "lvl": "debug", "msg": "total size: 2057818 (1MB)"}
- {"dt": "2023-03-09T06:11:53.694650", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-09T06:11:53.991665", "lvl": "debug", "msg": "progress   1% (0MB)"}
- {"dt": "2023-03-09T06:11:54.187491", "lvl": "debug", "msg": "progress   6% (0MB)"}
- {"dt": "2023-03-09T06:11:54.280709", "lvl": "debug", "msg": "progress  11% (0MB)"}
- {"dt": "2023-03-09T06:11:54.288968", "lvl": "debug", "msg": "progress  17% (0MB)"}
- {"dt": "2023-03-09T06:11:54.384225", "lvl": "debug", "msg": "progress  22% (0MB)"}
- {"dt": "2023-03-09T06:11:54.388664", "lvl": "debug", "msg": "progress  27% (0MB)"}
- {"dt": "2023-03-09T06:11:54.394176", "lvl": "debug", "msg": "progress  33% (0MB)"}
- {"dt": "2023-03-09T06:11:54.572074", "lvl": "debug", "msg": "progress  38% (0MB)"}
- {"dt": "2023-03-09T06:11:54.582619", "lvl": "debug", "msg": "progress  44% (0MB)"}
- {"dt": "2023-03-09T06:11:54.587137", "lvl": "debug", "msg": "progress  49% (0MB)"}
- {"dt": "2023-03-09T06:11:54.591980", "lvl": "debug", "msg": "progress  54% (1MB)"}
- {"dt": "2023-03-09T06:11:54.597617", "lvl": "debug", "msg": "progress  60% (1MB)"}
- {"dt": "2023-03-09T06:11:54.602033", "lvl": "debug", "msg": "progress  65% (1MB)"}
- {"dt": "2023-03-09T06:11:54.606450", "lvl": "debug", "msg": "progress  70% (1MB)"}
- {"dt": "2023-03-09T06:11:54.662871", "lvl": "debug", "msg": "progress  76% (1MB)"}
- {"dt": "2023-03-09T06:11:54.668925", "lvl": "debug", "msg": "progress  81% (1MB)"}
- {"dt": "2023-03-09T06:11:54.676749", "lvl": "debug", "msg": "progress  87% (1MB)"}
- {"dt": "2023-03-09T06:11:54.755023", "lvl": "debug", "msg": "progress  92% (1MB)"}
- {"dt": "2023-03-09T06:11:54.762206", "lvl": "debug", "msg": "progress  97% (1MB)"}
- {"dt": "2023-03-09T06:11:54.767199", "lvl": "info", "msg": "1MB downloaded in 1.07s (1.83MB/s)"}
- {"dt": "2023-03-09T06:11:54.768438", "lvl": "debug", "msg": "end: 1.1.1 http-download (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T06:11:54.769129", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "1.08", "extra": {"label": "initrd", "md5sum": "28ac0b000066ef9accb6e27c5e4f1541", "sha256sum": "61dbdb553c35c6bcb3933f2b1a4cc3c2991e688fd4a8d56d72673c6a531c7285", "sha512sum": "45e75163a9620ec32113322968d0631d0ddeba121caf320c3423441be5b1b9fe5311a2d4ca9116da5d7032daa62689f61f1578a72d159aacdb02e34cf342673b", "size": ! "2057818"}, "level": "1.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:11:54.770622", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T06:11:54.771490", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 01:29:59) [common]"}
- {"dt": "2023-03-09T06:11:54.772384", "lvl": "debug", "msg": "start: 1.2.1 http-download (timeout 01:29:59) [common]"}
- {"dt": "2023-03-09T06:11:54.773706", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/Image"}
- {"dt": "2023-03-09T06:11:54.774443", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/kernel/Image"}
- {"dt": "2023-03-09T06:11:54.775085", "lvl": "debug", "msg": "total size: 39076352 (37MB)"}
- {"dt": "2023-03-09T06:11:54.775652", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-09T06:11:55.078359", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-09T06:11:55.863685", "lvl": "debug", "msg": "progress   5% (1MB)"}
- {"dt": "2023-03-09T06:11:56.351828", "lvl": "debug", "msg": "progress  10% (3MB)"}
- {"dt": "2023-03-09T06:11:56.764173", "lvl": "debug", "msg": "progress  15% (5MB)"}
- {"dt": "2023-03-09T06:11:57.161986", "lvl": "debug", "msg": "progress  20% (7MB)"}
- {"dt": "2023-03-09T06:11:57.553658", "lvl": "debug", "msg": "progress  25% (9MB)"}
- {"dt": "2023-03-09T06:11:57.949057", "lvl": "debug", "msg": "progress  30% (11MB)"}
- {"dt": "2023-03-09T06:11:58.307045", "lvl": "debug", "msg": "progress  35% (13MB)"}
- {"dt": "2023-03-09T06:11:58.624146", "lvl": "debug", "msg": "progress  40% (14MB)"}
- {"dt": "2023-03-09T06:11:58.917563", "lvl": "debug", "msg": "progress  45% (16MB)"}
- {"dt": "2023-03-09T06:11:59.205305", "lvl": "debug", "msg": "progress  50% (18MB)"}
- {"dt": "2023-03-09T06:11:59.438985", "lvl": "debug", "msg": "progress  55% (20MB)"}
- {"dt": "2023-03-09T06:11:59.705825", "lvl": "debug", "msg": "progress  60% (22MB)"}
- {"dt": "2023-03-09T06:11:59.944763", "lvl": "debug", "msg": "progress  65% (24MB)"}
- {"dt": "2023-03-09T06:12:00.185556", "lvl": "debug", "msg": "progress  70% (26MB)"}
- {"dt": "2023-03-09T06:12:00.393998", "lvl": "debug", "msg": "progress  75% (27MB)"}
- {"dt": "2023-03-09T06:12:00.594415", "lvl": "debug", "msg": "progress  80% (29MB)"}
- {"dt": "2023-03-09T06:12:00.790905", "lvl": "debug", "msg": "progress  85% (31MB)"}
- {"dt": "2023-03-09T06:12:00.991358", "lvl": "debug", "msg": "progress  90% (33MB)"}
- {"dt": "2023-03-09T06:12:01.182779", "lvl": "debug", "msg": "progress  95% (35MB)"}
- {"dt": "2023-03-09T06:12:01.368524", "lvl": "debug", "msg": "progress 100% (37MB)"}
- {"dt": "2023-03-09T06:12:01.370657", "lvl": "info", "msg": "37MB downloaded in 6.60s (5.65MB/s)"}
- {"dt": "2023-03-09T06:12:01.372279", "lvl": "debug", "msg": "end: 1.2.1 http-download (duration 00:00:07) [common]"}
- {"dt": "2023-03-09T06:12:01.373218", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "6.60", "extra": {"label": "kernel", "md5sum": "b7ad3f8a8c99f081f6b3a30284fcee3d", "sha256sum": "9192b0042c196dbbc05b4e1ed43cba3d335bb23a9fd5d1c31ba403d944a8b9c7", "sha512sum": "8095cb217fd581f94246357bca8803ee86c04c00798a44e6b04a99774a49b85f8c05273bb46c63a6441ddceec93fda3570883a32b48191aa80d9655bdf75d467", "size": ! "39076352"}, "level": "1.2.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:12:01.375442", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:07) [common]"}
- {"dt": "2023-03-09T06:12:01.376254", "lvl": "debug", "msg": "start: 1.3 download-retry (timeout 01:29:52) [common]"}
- {"dt": "2023-03-09T06:12:01.377058", "lvl": "debug", "msg": "start: 1.3.1 http-download (timeout 01:29:52) [common]"}
- {"dt": "2023-03-09T06:12:01.378171", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T06:12:01.378865", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T06:12:01.379500", "lvl": "debug", "msg": "total size: 88958 (0MB)"}
- {"dt": "2023-03-09T06:12:01.380050", "lvl": "debug", "msg": "No compression specified"}
- {"dt": "2023-03-09T06:12:01.683355", "lvl": "debug", "msg": "progress  36% (0MB)"}
- {"dt": "2023-03-09T06:12:01.780541", "lvl": "debug", "msg": "progress  73% (0MB)"}
- {"dt": "2023-03-09T06:12:01.785559", "lvl": "debug", "msg": "progress 100% (0MB)"}
- {"dt": "2023-03-09T06:12:01.789388", "lvl": "info", "msg": "0MB downloaded in 0.41s (0.21MB/s)"}
- {"dt": "2023-03-09T06:12:01.791575", "lvl": "debug", "msg": "end: 1.3.1 http-download (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:12:01.792191", "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": "2023-03-09T06:12:01.793529", "lvl": "debug", "msg": "end: 1.3 download-retry (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:12:01.794171", "lvl": "debug", "msg": "start: 1.4 download-retry (timeout 01:29:52) [common]"}
- {"dt": "2023-03-09T06:12:01.794819", "lvl": "debug", "msg": "start: 1.4.1 http-download (timeout 01:29:52) [common]"}
- {"dt": "2023-03-09T06:12:01.795858", "lvl": "info", "msg": "downloading http://download.automotivelinux.org/AGL/upload/ci/28560/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz"}
- {"dt": "2023-03-09T06:12:01.796479", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-09T06:12:01.796932", "lvl": "debug", "msg": "total size: 486378676 (463MB)"}
- {"dt": "2023-03-09T06:12:01.797379", "lvl": "info", "msg": "Using unxz to decompress xz"}
- {"dt": "2023-03-09T06:12:02.114101", "lvl": "debug", "msg": "progress   0% (0MB)"}
- {"dt": "2023-03-09T06:12:08.465344", "lvl": "debug", "msg": "progress   5% (23MB)"}
- {"dt": "2023-03-09T06:12:13.148525", "lvl": "debug", "msg": "progress  10% (46MB)"}
- {"dt": "2023-03-09T06:12:18.516422", "lvl": "debug", "msg": "progress  15% (69MB)"}
- {"dt": "2023-03-09T06:12:22.885236", "lvl": "debug", "msg": "progress  20% (92MB)"}
- {"dt": "2023-03-09T06:12:27.838107", "lvl": "debug", "msg": "progress  25% (115MB)"}
- {"dt": "2023-03-09T06:12:32.950219", "lvl": "debug", "msg": "progress  30% (139MB)"}
- {"dt": "2023-03-09T06:12:39.674346", "lvl": "debug", "msg": "progress  35% (162MB)"}
- {"dt": "2023-03-09T06:12:46.496792", "lvl": "debug", "msg": "progress  40% (185MB)"}
- {"dt": "2023-03-09T06:12:51.922720", "lvl": "debug", "msg": "progress  45% (208MB)"}
- {"dt": "2023-03-09T06:12:56.951717", "lvl": "debug", "msg": "progress  50% (231MB)"}
- {"dt": "2023-03-09T06:13:02.170833", "lvl": "debug", "msg": "progress  55% (255MB)"}
- {"dt": "2023-03-09T06:13:08.828076", "lvl": "debug", "msg": "progress  60% (278MB)"}
- {"dt": "2023-03-09T06:13:14.713636", "lvl": "debug", "msg": "progress  65% (301MB)"}
- {"dt": "2023-03-09T06:13:20.133163", "lvl": "debug", "msg": "progress  70% (324MB)"}
- {"dt": "2023-03-09T06:13:24.255616", "lvl": "debug", "msg": "progress  75% (347MB)"}
- {"dt": "2023-03-09T06:13:28.541233", "lvl": "debug", "msg": "progress  80% (371MB)"}
- {"dt": "2023-03-09T06:13:32.775349", "lvl": "debug", "msg": "progress  85% (394MB)"}
- {"dt": "2023-03-09T06:13:37.140140", "lvl": "debug", "msg": "progress  90% (417MB)"}
- {"dt": "2023-03-09T06:13:41.717924", "lvl": "debug", "msg": "progress  95% (440MB)"}
- {"dt": "2023-03-09T06:13:50.387650", "lvl": "debug", "msg": "progress 100% (463MB)"}
- {"dt": "2023-03-09T06:13:53.504858", "lvl": "info", "msg": "463MB downloaded in 111.71s (4.15MB/s)"}
- {"dt": "2023-03-09T06:13:53.506698", "lvl": "debug", "msg": "end: 1.4.1 http-download (duration 00:01:52) [common]"}
- {"dt": "2023-03-09T06:13:53.507583", "lvl": "results", "msg": {"case": "http-download", "definition": "lava", "duration": "111.71", "extra": {"label": "nbdroot", "md5sum": "835b516ffbbad322c4bf1e73cb0f2aac", "sha256sum": "87a02b3a62db059a6ad8926d47ee0379756c469d30358ab3f41728a116037658", "sha512sum": "c5e84b43e6e0bfa4db68dbdff03cf757bdfa606175b5fb463634e61995dc13fff8bf2bb9c302341b9a133ae79450d6b7af29feadfeff58a46d4768ebf63e5c0a", "size": ! "486378676"}, "level": "1.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:13:53.509356", "lvl": "debug", "msg": "end: 1.4 download-retry (duration 00:01:52) [common]"}
- {"dt": "2023-03-09T06:13:53.510336", "lvl": "debug", "msg": "start: 1.5 lava-overlay (timeout 01:28:00) [common]"}
- {"dt": "2023-03-09T06:13:53.512400", "lvl": "debug", "msg": "[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62"}
- {"dt": "2023-03-09T06:13:53.514176", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin"}
- {"dt": "2023-03-09T06:13:53.515623", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/tests"}
- {"dt": "2023-03-09T06:13:53.516963", "lvl": "debug", "msg": "makedir: /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/results"}
- {"dt": "2023-03-09T06:13:53.518344", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-add-keys"}
- {"dt": "2023-03-09T06:13:53.519951", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-add-sources"}
- {"dt": "2023-03-09T06:13:53.521176", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-background-process-start"}
- {"dt": "2023-03-09T06:13:53.522379", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-background-process-stop"}
- {"dt": "2023-03-09T06:13:53.523624", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-common-functions"}
- {"dt": "2023-03-09T06:13:53.524797", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-echo-ipv4"}
- {"dt": "2023-03-09T06:13:53.525952", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-install-packages"}
- {"dt": "2023-03-09T06:13:53.527153", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-installed-packages"}
- {"dt": "2023-03-09T06:13:53.528334", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-os-build"}
- {"dt": "2023-03-09T06:13:53.529502", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-probe-channel"}
- {"dt": "2023-03-09T06:13:53.530665", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-probe-ip"}
- {"dt": "2023-03-09T06:13:53.531963", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-target-ip"}
- {"dt": "2023-03-09T06:13:53.533133", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-target-mac"}
- {"dt": "2023-03-09T06:13:53.534284", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-target-storage"}
- {"dt": "2023-03-09T06:13:53.535514", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-test-case"}
- {"dt": "2023-03-09T06:13:53.536696", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-test-event"}
- {"dt": "2023-03-09T06:13:53.537878", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-test-feedback"}
- {"dt": "2023-03-09T06:13:53.539092", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-test-raise"}
- {"dt": "2023-03-09T06:13:53.540338", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-test-reference"}
- {"dt": "2023-03-09T06:13:53.541515", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-test-runner"}
- {"dt": "2023-03-09T06:13:53.542671", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-test-set"}
- {"dt": "2023-03-09T06:13:53.543941", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-test-shell"}
- {"dt": "2023-03-09T06:13:53.545131", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-install-packages (oe)"}
- {"dt": "2023-03-09T06:13:53.546505", "lvl": "debug", "msg": "Updating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/bin/lava-installed-packages (oe)"}
- {"dt": "2023-03-09T06:13:53.547777", "lvl": "debug", "msg": "Creating /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/environment"}
- {"dt": "2023-03-09T06:13:53.548826", "lvl": "debug", "msg": "LAVA metadata"}
- {"dt": "2023-03-09T06:13:53.549456", "lvl": "debug", "msg": "- LAVA_JOB_ID=32192"}
- {"dt": "2023-03-09T06:13:53.549961", "lvl": "debug", "msg": "- LAVA_DISPATCHER_IP=192.168.66.1"}
- {"dt": "2023-03-09T06:13:53.550832", "lvl": "debug", "msg": "start: 1.5.1 ssh-authorize (timeout 01:28:00) [common]"}
- {"dt": "2023-03-09T06:13:53.553154", "lvl": "debug", "msg": "end: 1.5.1 ssh-authorize (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:53.553971", "lvl": "debug", "msg": "start: 1.5.2 lava-vland-overlay (timeout 01:28:00) [common]"}
- {"dt": "2023-03-09T06:13:53.554530", "lvl": "debug", "msg": "skipped lava-vland-overlay"}
- {"dt": "2023-03-09T06:13:53.555168", "lvl": "debug", "msg": "end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:53.555860", "lvl": "debug", "msg": "start: 1.5.3 lava-multinode-overlay (timeout 01:28:00) [common]"}
- {"dt": "2023-03-09T06:13:53.556376", "lvl": "debug", "msg": "skipped lava-multinode-overlay"}
- {"dt": "2023-03-09T06:13:53.556928", "lvl": "debug", "msg": "end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:53.557610", "lvl": "debug", "msg": "start: 1.5.4 test-definition (timeout 01:28:00) [common]"}
- {"dt": "2023-03-09T06:13:53.558226", "lvl": "info", "msg": "Loading test definitions"}
- {"dt": "2023-03-09T06:13:53.559059", "lvl": "debug", "msg": "start: 1.5.4.1 git-repo-action (timeout 01:28:00) [common]"}
- {"dt": "2023-03-09T06:13:53.559720", "lvl": "debug", "msg": "Using /lava-32192 at stage 0"}
- {"dt": "2023-03-09T06:13:53.560550", "lvl": "info", "msg": "Fetching tests from https://github.com/duerpei/yan_test.git"}
- {"dt": "2023-03-09T06:13:53.561196", "lvl": "debug", "msg": "Running '/usr/bin/git clone --depth=1 https://github.com/duerpei/yan_test.git /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/0/tests/0_check_disk_space'"}
- {"dt": "2023-03-09T06:13:56.837832", "lvl": "debug", "msg": "Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/0/tests/0_check_disk_space"}
- {"dt": "2023-03-09T06:13:56.854683", "lvl": "debug", "msg": "Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/0/tests/0_check_disk_space/./check_space.yaml"}
- {"dt": "2023-03-09T06:13:56.857887", "lvl": "debug", "msg": "uuid=32192_1.5.4.1 testdef=None"}
- {"dt": "2023-03-09T06:13:56.858965", "lvl": "debug", "msg": "end: 1.5.4.1 git-repo-action (duration 00:00:03) [common]"}
- {"dt": "2023-03-09T06:13:56.859722", "lvl": "results", "msg": {"case": "git-repo-action", "definition": "lava", "duration": "3.30", "extra": {"commit": "e4513698c438fef88cabd871178580a0c36ed5ce", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git"}, "level": "1.5.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:13:56.861254", "lvl": "debug", "msg": "start: 1.5.4.2 test-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-03-09T06:13:56.866487", "lvl": "debug", "msg": "end: 1.5.4.2 test-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.867209", "lvl": "results", "msg": {"case": "test-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "check_disk_space", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "uuid": "32192_1.5.4.1"}, "level": "1.5.4.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:13:56.868768", "lvl": "debug", "msg": "start: 1.5.4.3 test-install-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-03-09T06:13:56.875750", "lvl": "debug", "msg": "end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.876456", "lvl": "results", "msg": {"case": "test-install-overlay", "definition": "lava", "duration": "0.01", "extra": {"from": "git", "name": "check_disk_space", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "skipped test-install-overlay": "32192_1.5.4.1", "uuid": "32192_1.5.4.1"}, "level": "1.5.4.3", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:13:56.878052", "lvl": "debug", "msg": "start: 1.5.4.4 test-runscript-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-03-09T06:13:56.884666", "lvl": "debug", "msg": "runner path: /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/0/tests/0_check_disk_space test_uuid 32192_1.5.4.1"}
- {"dt": "2023-03-09T06:13:56.886187", "lvl": "debug", "msg": "end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.886920", "lvl": "results", "msg": {"case": "test-runscript-overlay", "definition": "lava", "duration": "0.01", "extra": {"filename": "/var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/0/tests/0_check_disk_space/run.sh", "from": "git", "name": "check_disk_space", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "uuid": "32192_1.5.4.1"}, "level": "1.5.4.4", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:13:56.888366", "lvl": "info", "msg": "Creating lava-test-runner.conf files"}
- {"dt": "2023-03-09T06:13:56.888980", "lvl": "debug", "msg": "Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32192/lava-overlay-9op85v62/lava-32192/0 for stage 0"}
- {"dt": "2023-03-09T06:13:56.889937", "lvl": "debug", "msg": "- 0_check_disk_space"}
- {"dt": "2023-03-09T06:13:56.891084", "lvl": "debug", "msg": "end: 1.5.4 test-definition (duration 00:00:03) [common]"}
- {"dt": "2023-03-09T06:13:56.891974", "lvl": "debug", "msg": "start: 1.5.5 compress-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-03-09T06:13:56.941937", "lvl": "debug", "msg": "end: 1.5.5 compress-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.942937", "lvl": "debug", "msg": "start: 1.5.6 persistent-nfs-overlay (timeout 01:27:57) [common]"}
- {"dt": "2023-03-09T06:13:56.944432", "lvl": "debug", "msg": "end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.945345", "lvl": "debug", "msg": "end: 1.5 lava-overlay (duration 00:00:03) [common]"}
- {"dt": "2023-03-09T06:13:56.946181", "lvl": "debug", "msg": "start: 1.6 prepare-kernel (timeout 01:27:57) [common]"}
- {"dt": "2023-03-09T06:13:56.947041", "lvl": "debug", "msg": "start: 1.6.1 uboot-prepare-kernel (timeout 01:27:57) [common]"}
- {"dt": "2023-03-09T06:13:56.947949", "lvl": "debug", "msg": "end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.948710", "lvl": "debug", "msg": "end: 1.6 prepare-kernel (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.949549", "lvl": "debug", "msg": "start: 1.7 xnbd-server-deploy (timeout 01:27:57) [common]"}
- {"dt": "2023-03-09T06:13:56.950233", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server"}
- {"dt": "2023-03-09T06:13:56.950921", "lvl": "debug", "msg": "NBD-IP: 192.168.66.1, NBD-PORT: 61957, NBD-ROOT: 32192/nbd-deploy-vnz2pcu2/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-09T06:13:56.954448", "lvl": "debug", "msg": "nbd-server 61957 /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/nbdroot/agl-demo-platform-h3ulcb.ext4"}
- {"dt": "2023-03-09T06:13:56.977938", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-09T06:13:56.980757", "lvl": "debug", "msg": "output: ** (process:4590): WARNING **: 06:13:56.975: Specifying an export on the command line no longer uses the oldstyle protocol."}
- {"dt": "2023-03-09T06:13:56.981695", "lvl": "debug", "msg": "output: "}
- {"dt": "2023-03-09T06:13:56.982562", "lvl": "debug", "msg": "xnbd-server-deploy: starting nbd-server done"}
- {"dt": "2023-03-09T06:13:56.983860", "lvl": "debug", "msg": "end: 1.7 xnbd-server-deploy (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.985068", "lvl": "info", "msg": "end: 1 nbd-deploy (duration 00:02:03) [common]"}
- {"dt": "2023-03-09T06:13:56.986235", "lvl": "info", "msg": "start: 2 uboot-action (timeout 00:20:00) [common]"}
- {"dt": "2023-03-09T06:13:56.987709", "lvl": "debug", "msg": "start: 2.1 uboot-from-media (timeout 00:20:00) [common]"}
- {"dt": "2023-03-09T06:13:56.989050", "lvl": "debug", "msg": "end: 2.1 uboot-from-media (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:56.990090", "lvl": "debug", "msg": "start: 2.2 bootloader-overlay (timeout 00:20:00) [common]"}
- {"dt": "2023-03-09T06:13:56.991875", "lvl": "debug", "msg": "substitutions:"}
- {"dt": "2023-03-09T06:13:56.992733", "lvl": "debug", "msg": "- {BOOTX}: booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-09T06:13:56.993512", "lvl": "debug", "msg": "- {DTB_ADDR}: 0x48000000"}
- {"dt": "2023-03-09T06:13:56.994216", "lvl": "debug", "msg": "- {DTB}: 32192/nbd-deploy-vnz2pcu2/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T06:13:56.994972", "lvl": "debug", "msg": "- {INITRD}: 32192/nbd-deploy-vnz2pcu2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T06:13:56.995591", "lvl": "debug", "msg": "- {KERNEL_ADDR}: 0x48080000"}
- {"dt": "2023-03-09T06:13:56.996285", "lvl": "debug", "msg": "- {KERNEL}: 32192/nbd-deploy-vnz2pcu2/kernel/Image"}
- {"dt": "2023-03-09T06:13:56.996820", "lvl": "debug", "msg": "- {LAVA_MAC}: None"}
- {"dt": "2023-03-09T06:13:56.997366", "lvl": "debug", "msg": "- {NBDSERVERIP}: 192.168.66.1"}
- {"dt": "2023-03-09T06:13:56.997880", "lvl": "debug", "msg": "- {NBDSERVERPORT}: 61957"}
- {"dt": "2023-03-09T06:13:56.998388", "lvl": "debug", "msg": "- {PRESEED_CONFIG}: None"}
- {"dt": "2023-03-09T06:13:56.998928", "lvl": "debug", "msg": "- {PRESEED_LOCAL}: None"}
- {"dt": "2023-03-09T06:13:56.999485", "lvl": "debug", "msg": "- {RAMDISK_ADDR}: 0x4c000000"}
- {"dt": "2023-03-09T06:13:57.000016", "lvl": "debug", "msg": "- {RAMDISK}: 32192/nbd-deploy-vnz2pcu2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T06:13:57.000535", "lvl": "debug", "msg": "- {ROOT_PART}: None"}
- {"dt": "2023-03-09T06:13:57.001037", "lvl": "debug", "msg": "- {ROOT}: None"}
- {"dt": "2023-03-09T06:13:57.001529", "lvl": "debug", "msg": "- {SERVER_IP}: 192.168.66.1"}
- {"dt": "2023-03-09T06:13:57.002055", "lvl": "debug", "msg": "- {TEE_ADDR}: 0x83000000"}
- {"dt": "2023-03-09T06:13:57.002593", "lvl": "debug", "msg": "- {TEE}: None"}
- {"dt": "2023-03-09T06:13:57.003174", "lvl": "info", "msg": "Parsed boot commands:"}
- {"dt": "2023-03-09T06:13:57.003719", "lvl": "info", "msg": "- setenv autoload no"}
- {"dt": "2023-03-09T06:13:57.004230", "lvl": "info", "msg": "- setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-09T06:13:57.004754", "lvl": "info", "msg": "- setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-09T06:13:57.005273", "lvl": "info", "msg": "- dhcp"}
- {"dt": "2023-03-09T06:13:57.005788", "lvl": "info", "msg": "- setenv serverip 192.168.66.1"}
- {"dt": "2023-03-09T06:13:57.006289", "lvl": "info", "msg": "- tftp 0x48080000 32192/nbd-deploy-vnz2pcu2/kernel/Image"}
- {"dt": "2023-03-09T06:13:57.006787", "lvl": "info", "msg": "- tftp 0x4c000000 32192/nbd-deploy-vnz2pcu2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T06:13:57.007373", "lvl": "info", "msg": "- setenv initrd_size ${filesize}"}
- {"dt": "2023-03-09T06:13:57.007894", "lvl": "info", "msg": "- tftp 0x48000000 32192/nbd-deploy-vnz2pcu2/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T06:13:57.008396", "lvl": "info", "msg": "- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61957 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-03-09T06:13:57.009010", "lvl": "info", "msg": "- booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-09T06:13:57.009730", "lvl": "debug", "msg": "end: 2.2 bootloader-overlay (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:13:57.010401", "lvl": "results", "msg": {"case": "bootloader-overlay", "definition": "lava", "duration": "0.02", "extra": {"dtb_addr": "0x48000000", "kernel_addr": "0x48080000", "ramdisk_addr": "0x4c000000", "tee_addr": "0x83000000"}, "level": "2.2", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:13:57.012007", "lvl": "debug", "msg": "start: 2.3 connect-device (timeout 00:20:00) [common]"}
- {"dt": "2023-03-09T06:13:57.012725", "lvl": "info", "msg": "[common] connect-device Connecting to device using 'telnet 127.0.0.1 63004'"}
- {"dt": "2023-03-09T06:14:00.159410", "lvl": "debug", "msg": "Setting prompt string to ['lava-test: # ']"}
- {"dt": "2023-03-09T06:14:00.161589", "lvl": "debug", "msg": "end: 2.3 connect-device (duration 00:00:03) [common]"}
- {"dt": "2023-03-09T06:14:00.164600", "lvl": "debug", "msg": "start: 2.4 uboot-commands (timeout 00:19:57) [common]"}
- {"dt": "2023-03-09T06:14:00.165521", "lvl": "debug", "msg": "start: 2.4.1 reset-device (timeout 00:19:57) [common]"}
- {"dt": "2023-03-09T06:14:00.166314", "lvl": "debug", "msg": "start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common]"}
- {"dt": "2023-03-09T06:14:00.167789", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '8'"}
- {"dt": "2023-03-09T06:14:03.589787", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-09T06:14:04.658443", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-09T06:14:04.694650", "lvl": "debug", "msg": "Returned 0 in 4 seconds"}
- {"dt": "2023-03-09T06:14:04.798044", "lvl": "debug", "msg": "end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]"}
- {"dt": "2023-03-09T06:14:04.800460", "lvl": "results", "msg": {"case": "pdu-reboot", "definition": "lava", "duration": "4.63", "extra": {"status": "success"}, "level": "2.4.1.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:14:04.804770", "lvl": "debug", "msg": "end: 2.4.1 reset-device (duration 00:00:05) [common]"}
- {"dt": "2023-03-09T06:14:04.807339", "lvl": "debug", "msg": "start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common]"}
- {"dt": "2023-03-09T06:14:04.808088", "lvl": "debug", "msg": "Setting prompt string to ['Hit any key to stop autoboot']"}
- {"dt": "2023-03-09T06:14:04.808749", "lvl": "debug", "msg": "bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00)"}
- {"dt": "2023-03-09T06:14:04.810443", "lvl": "target", "msg": "Trying 127.0.0.1..."}
- {"dt": "2023-03-09T06:14:04.811170", "lvl": "target", "msg": "Connected to 127.0.0.1."}
- {"dt": "2023-03-09T06:14:04.811820", "lvl": "target", "msg": "Escape character is '^]'."}
- {"dt": "2023-03-09T06:14:04.919384", "lvl": "target", "msg": "\0[    0.000149] NOTICE:  BL2: R-Car H3 Initial Program Loader(CA57)"}
- {"dt": "2023-03-09T06:14:04.922657", "lvl": "target", "msg": "[    0.004585] NOTICE:  BL2: Initial Program Loader(Rev.2.0.6)"}
- {"dt": "2023-03-09T06:14:04.924803", "lvl": "target", "msg": "[    0.010118] NOTICE:  BL2: PRR is R-Car H3 Ver.3.0"}
- {"dt": "2023-03-09T06:14:04.926570", "lvl": "target", "msg": "[    0.014780] NOTICE:  BL2: Board is unknown Rev.---"}
- {"dt": "2023-03-09T06:14:04.928487", "lvl": "target", "msg": "[    0.019552] NOTICE:  BL2: Boot device is eMMC(50MHz x8)"}
- {"dt": "2023-03-09T06:14:04.930250", "lvl": "target", "msg": "[    0.024721] NOTICE:  BL2: LCM state is CM"}
- {"dt": "2023-03-09T06:14:04.931451", "lvl": "target", "msg": "[    0.028762] NOTICE:  BL2: AVS setting succeeded. DVFS_SetVID=0x53"}
- {"dt": "2023-03-09T06:14:04.932106", "lvl": "target", "msg": "[    0.034738] NOTICE:  BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB"}
- {"dt": "2023-03-09T06:14:04.977283", "lvl": "target", "msg": "[    0.040600] NOTICE:  BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2023-03-09T06:14:04.978754", "lvl": "target", "msg": "[    0.046474] NOTICE:  BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2023-03-09T06:14:04.979787", "lvl": "target", "msg": "[    0.052350] NOTICE:  BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2023-03-09T06:14:04.980632", "lvl": "target", "msg": "[    0.058267] NOTICE:  BL2: DDR3200(rev.0.40)"}
- {"dt": "2023-03-09T06:14:04.981500", "lvl": "target", "msg": "[    0.073569] NOTICE:  BL2: [COLD_BOOT]"}
- {"dt": "2023-03-09T06:14:04.982389", "lvl": "target", "msg": "[    0.083100] NOTICE:  BL2: DRAM Split is 4ch(DDR f)"}
- {"dt": "2023-03-09T06:14:04.983322", "lvl": "target", "msg": "[    0.086399] NOTICE:  BL2: QoS is default setting(rev.0.11)"}
- {"dt": "2023-03-09T06:14:04.984171", "lvl": "target", "msg": "[    0.091842] NOTICE:  BL2: DRAM refresh interval 1.95 usec"}
- {"dt": "2023-03-09T06:14:04.995087", "lvl": "target", "msg": "[    0.097199] NOTICE:  BL2: Periodic Write DQ Training"}
- {"dt": "2023-03-09T06:14:05.036563", "lvl": "target", "msg": "[    0.114665] NOTICE:  BL2: v1.5(release):af9f429a-dirty"}
- {"dt": "2023-03-09T06:14:05.037990", "lvl": "target", "msg": "[    0.118310] NOTICE:  BL2: Built : 02:23:33, Feb  7 2020"}
- {"dt": "2023-03-09T06:14:05.039301", "lvl": "target", "msg": "[    0.123498] NOTICE:  BL2: Normal boot"}
- {"dt": "2023-03-09T06:14:05.040493", "lvl": "target", "msg": "[    0.127133] NOTICE:  BL2: eMMC boot from partition 1"}
- {"dt": "2023-03-09T06:14:05.042943", "lvl": "target", "msg": "[    0.133680] NOTICE:  BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1)"}
- {"dt": "2023-03-09T06:14:05.044283", "lvl": "target", "msg": "[    0.139797] NOTICE:  BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12)"}
- {"dt": "2023-03-09T06:14:05.045492", "lvl": "target", "msg": "[    0.147397] NOTICE:  BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128)"}
- {"dt": "2023-03-09T06:14:05.053994", "lvl": "target", "msg": "[    0.156373] NOTICE:  BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048)"}
- {"dt": "2023-03-09T06:14:05.086355", "lvl": "target", "msg": "[    0.187304] NOTICE:  BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048)"}
- {"dt": "2023-03-09T06:14:05.101187", "lvl": "target", "msg": "[    0.216044] NOTICE:  BL2: Booting BL31"}
- {"dt": "2023-03-09T06:14:05.339649", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:14:05.341747", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:14:05.343562", "lvl": "target", "msg": "U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000)"}
- {"dt": "2023-03-09T06:14:05.345327", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:14:05.347734", "lvl": "target", "msg": "CPU: Renesas Electronics R8A7795 rev 3.0"}
- {"dt": "2023-03-09T06:14:05.349525", "lvl": "target", "msg": "Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2023-03-09T06:14:05.709601", "lvl": "target", "msg": "DRAM:  7.9 GiB"}
- {"dt": "2023-03-09T06:14:05.711702", "lvl": "target", "msg": "Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB"}
- {"dt": "2023-03-09T06:14:05.714770", "lvl": "target", "msg": "Bank #1: 0x500000000 - 0x57fffffff, 2 GiB"}
- {"dt": "2023-03-09T06:14:05.716787", "lvl": "target", "msg": "Bank #2: 0x600000000 - 0x67fffffff, 2 GiB"}
- {"dt": "2023-03-09T06:14:05.718564", "lvl": "target", "msg": "Bank #3: 0x700000000 - 0x77fffffff, 2 GiB"}
- {"dt": "2023-03-09T06:14:05.719660", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:14:05.743046", "lvl": "target", "msg": "MMC:   sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2"}
- {"dt": "2023-03-09T06:14:06.073616", "lvl": "target", "msg": "Loading Environment from MMC... OK"}
- {"dt": "2023-03-09T06:14:06.075763", "lvl": "target", "msg": "In:    serial@e6e88000"}
- {"dt": "2023-03-09T06:14:06.077568", "lvl": "target", "msg": "Out:   serial@e6e88000"}
- {"dt": "2023-03-09T06:14:06.080163", "lvl": "target", "msg": "Err:   serial@e6e88000"}
- {"dt": "2023-03-09T06:14:06.123939", "lvl": "target", "msg": "Net:   eth0: ethernet@e6800000"}
- {"dt": "2023-03-09T06:14:06.126652", "lvl": "input", "msg": "\n"}
- {"dt": "2023-03-09T06:14:06.179222", "lvl": "target", "msg": "Hit any key to stop autoboot:  3 "}
- {"dt": "2023-03-09T06:14:06.182362", "lvl": "debug", "msg": "end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T06:14:06.185029", "lvl": "debug", "msg": "start: 2.4.3 bootloader-commands (timeout 00:19:51) [common]"}
- {"dt": "2023-03-09T06:14:06.187091", "lvl": "debug", "msg": "Setting prompt string to ['=>']"}
- {"dt": "2023-03-09T06:14:06.189120", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)"}
- {"dt": "2023-03-09T06:14:06.203698", "lvl": "target", "msg": "\b\b\b 0 "}
- {"dt": "2023-03-09T06:14:06.205328", "lvl": "debug", "msg": "Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-03-09T06:14:06.206470", "lvl": "input", "msg": "setenv autoload no\n"}
- {"dt": "2023-03-09T06:14:06.308680", "lvl": "target", "msg": "=> setenv autoload no"}
- {"dt": "2023-03-09T06:14:06.311011", "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:51)"}
- {"dt": "2023-03-09T06:14:06.326861", "lvl": "target", "msg": "setenv autoload no"}
- {"dt": "2023-03-09T06:14:06.331107", "lvl": "input", "msg": "setenv initrd_high 0xffffffff\n"}
- {"dt": "2023-03-09T06:14:06.432772", "lvl": "target", "msg": "=> setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-09T06:14:06.435042", "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:51)"}
- {"dt": "2023-03-09T06:14:06.442744", "lvl": "target", "msg": "setenv initrd_high 0xffffffff"}
- {"dt": "2023-03-09T06:14:06.444073", "lvl": "input", "msg": "setenv fdt_high 0xffffffff\n"}
- {"dt": "2023-03-09T06:14:06.545633", "lvl": "target", "msg": "=> setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-09T06:14:06.547821", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-03-09T06:14:06.554515", "lvl": "target", "msg": "setenv fdt_high 0xffffffff"}
- {"dt": "2023-03-09T06:14:06.556931", "lvl": "input", "msg": "dhcp\n"}
- {"dt": "2023-03-09T06:14:06.658468", "lvl": "target", "msg": "=> dhcp"}
- {"dt": "2023-03-09T06:14:06.660737", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50)"}
- {"dt": "2023-03-09T06:14:06.666328", "lvl": "target", "msg": "dhcp"}
- {"dt": "2023-03-09T06:14:12.831064", "lvl": "target", "msg": "ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done"}
- {"dt": "2023-03-09T06:14:12.847101", "lvl": "target", "msg": "BOOTP broadcast 1"}
- {"dt": "2023-03-09T06:14:13.102462", "lvl": "target", "msg": "BOOTP broadcast 2"}
- {"dt": "2023-03-09T06:14:13.597293", "lvl": "target", "msg": "BOOTP broadcast 3"}
- {"dt": "2023-03-09T06:14:14.139419", "lvl": "target", "msg": "DHCP client bound to address 192.168.66.11 (1287 ms)"}
- {"dt": "2023-03-09T06:14:14.141915", "lvl": "input", "msg": "setenv serverip 192.168.66.1\n"}
- {"dt": "2023-03-09T06:14:14.244842", "lvl": "target", "msg": "=> setenv serverip 192.168.66.1"}
- {"dt": "2023-03-09T06:14:14.246998", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:43)"}
- {"dt": "2023-03-09T06:14:14.251384", "lvl": "target", "msg": "setenv serverip 192.168.66.1"}
- {"dt": "2023-03-09T06:14:14.253816", "lvl": "input", "msg": "tftp 0x48080000 32192/nbd-deploy-vnz2pcu2/kernel/Image\n"}
- {"dt": "2023-03-09T06:14:14.356439", "lvl": "target", "msg": "=> tftp 0x48080000 32192/nbd-deploy-vnz2pcu2/kernel/Image"}
- {"dt": "2023-03-09T06:14:14.358493", "lvl": "debug", "msg": "bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:43)"}
- {"dt": "2023-03-09T06:14:14.363121", "lvl": "target", "msg": "tftp 0x48080000 32192/nbd-deploy-vnz2pcu2/kernel/Image"}
- {"dt": "2023-03-09T06:14:14.394832", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-09T06:14:14.397422", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-09T06:14:14.399600", "lvl": "target", "msg": "Filename '32192/nbd-deploy-vnz2pcu2/kernel/Image'."}
- {"dt": "2023-03-09T06:14:14.401389", "lvl": "target", "msg": "Load address: 0x48080000"}
- {"dt": "2023-03-09T06:14:19.726785", "lvl": "target", "msg": "Loading: *\bT #################################################################"}
- {"dt": "2023-03-09T06:14:20.030682", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:20.349902", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:20.684609", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:21.003730", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:21.323404", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:21.626491", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:21.945737", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:22.265039", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:22.584199", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:22.919453", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:23.238680", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:23.557852", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:23.876969", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:24.211723", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:24.515459", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:24.818794", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:25.137997", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:25.473084", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:25.792364", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:26.111496", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:26.430532", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:26.749901", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:27.085019", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:27.404142", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:27.739348", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:28.074313", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:28.393549", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:28.728700", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:29.047889", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:29.367110", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:29.685853", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:30.005452", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:30.324602", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:30.643842", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:30.962984", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:31.282184", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:31.601313", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:31.920524", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:32.255750", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:32.559087", "lvl": "target", "msg": "\t ##############################################################"}
- {"dt": "2023-03-09T06:14:32.561067", "lvl": "target", "msg": "\t 2.1 MiB/s"}
- {"dt": "2023-03-09T06:14:32.562829", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-09T06:14:32.574460", "lvl": "target", "msg": "Bytes transferred = 39076352 (2544200 hex)"}
- {"dt": "2023-03-09T06:14:32.575691", "lvl": "input", "msg": "tftp 0x4c000000 32192/nbd-deploy-vnz2pcu2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz\n"}
- {"dt": "2023-03-09T06:14:32.677327", "lvl": "target", "msg": "=> tftp 0x4c000000 32192/nbd-deploy-vnz2pcu2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T06:14:32.679633", "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:24)"}
- {"dt": "2023-03-09T06:14:32.721869", "lvl": "target", "msg": "tftp 0x4c000000 32192/nbd-deploy-vnz2pcu2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz"}
- {"dt": "2023-03-09T06:14:32.723423", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-09T06:14:32.725279", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-09T06:14:32.726690", "lvl": "target", "msg": "Filename '32192/nbd-deploy-vnz2pcu2/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'."}
- {"dt": "2023-03-09T06:14:32.728365", "lvl": "target", "msg": "Load address: 0x4c000000"}
- {"dt": "2023-03-09T06:14:33.043693", "lvl": "target", "msg": "Loading: *\b#################################################################"}
- {"dt": "2023-03-09T06:14:33.363024", "lvl": "target", "msg": "\t #################################################################"}
- {"dt": "2023-03-09T06:14:33.410549", "lvl": "target", "msg": "\t ###########"}
- {"dt": "2023-03-09T06:14:33.430283", "lvl": "target", "msg": "\t 2.8 MiB/s"}
- {"dt": "2023-03-09T06:14:33.431665", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-09T06:14:33.432855", "lvl": "target", "msg": "Bytes transferred = 2057818 (1f665a hex)"}
- {"dt": "2023-03-09T06:14:33.434639", "lvl": "input", "msg": "setenv initrd_size ${filesize}\n"}
- {"dt": "2023-03-09T06:14:33.536757", "lvl": "target", "msg": "=> setenv initrd_size ${filesize}"}
- {"dt": "2023-03-09T06:14:33.539014", "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:23)"}
- {"dt": "2023-03-09T06:14:33.544398", "lvl": "target", "msg": "setenv initrd_size ${filesize}"}
- {"dt": "2023-03-09T06:14:33.546854", "lvl": "input", "msg": "tftp 0x48000000 32192/nbd-deploy-vnz2pcu2/dtb/r8a77951-agl-refhw.dtb\n"}
- {"dt": "2023-03-09T06:14:33.648638", "lvl": "target", "msg": "=> tftp 0x48000000 32192/nbd-deploy-vnz2pcu2/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T06:14:33.650320", "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:23)"}
- {"dt": "2023-03-09T06:14:33.655096", "lvl": "target", "msg": "tftp 0x48000000 32192/nbd-deploy-vnz2pcu2/dtb/r8a77951-agl-refhw.dtb"}
- {"dt": "2023-03-09T06:14:33.692791", "lvl": "target", "msg": "Using ethernet@e6800000 device"}
- {"dt": "2023-03-09T06:14:33.694376", "lvl": "target", "msg": "TFTP from server 192.168.66.1; our IP address is 192.168.66.11"}
- {"dt": "2023-03-09T06:14:33.695366", "lvl": "target", "msg": "Filename '32192/nbd-deploy-vnz2pcu2/dtb/r8a77951-agl-refhw.dtb'."}
- {"dt": "2023-03-09T06:14:33.696132", "lvl": "target", "msg": "Load address: 0x48000000"}
- {"dt": "2023-03-09T06:14:33.738570", "lvl": "target", "msg": "Loading: *\b#######"}
- {"dt": "2023-03-09T06:14:33.740187", "lvl": "target", "msg": "\t 2.2 MiB/s"}
- {"dt": "2023-03-09T06:14:33.741401", "lvl": "target", "msg": "done"}
- {"dt": "2023-03-09T06:14:33.742590", "lvl": "target", "msg": "Bytes transferred = 88958 (15b7e hex)"}
- {"dt": "2023-03-09T06:14:33.744338", "lvl": "input", "msg": "setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61957 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'\n"}
- {"dt": "2023-03-09T06:14:33.846566", "lvl": "target", "msg": "=>setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61957 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-03-09T06:14:33.848579", "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:23)"}
- {"dt": "2023-03-09T06:14:33.852498", "lvl": "target", "msg": " setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61957 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'"}
- {"dt": "2023-03-09T06:14:33.868889", "lvl": "input", "msg": "booti 0x48080000 0x4c000000:${initrd_size} 0x48000000\n"}
- {"dt": "2023-03-09T06:14:33.970470", "lvl": "target", "msg": "=> booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-09T06:14:33.972269", "lvl": "debug", "msg": "Setting prompt string to ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image']"}
- {"dt": "2023-03-09T06:14:33.973773", "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:23)"}
- {"dt": "2023-03-09T06:14:33.980216", "lvl": "target", "msg": "booti 0x48080000 0x4c000000:${initrd_size} 0x48000000"}
- {"dt": "2023-03-09T06:14:34.117409", "lvl": "target", "msg": "## Flattened Device Tree blob at 48000000"}
- {"dt": "2023-03-09T06:14:34.118581", "lvl": "target", "msg": "   Booting using the fdt blob at 0x48000000"}
- {"dt": "2023-03-09T06:14:34.119628", "lvl": "target", "msg": "   Loading Ramdisk to bfc0e000, end bfe0465a ... OK"}
- {"dt": "2023-03-09T06:14:34.121592", "lvl": "target", "msg": "   Loading Device Tree to 00000000bfbf5000, end 00000000bfc0db7d ... OK"}
- {"dt": "2023-03-09T06:14:34.122784", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:14:34.123762", "lvl": "target", "msg": "Starting kernel ..."}
- {"dt": "2023-03-09T06:14:34.124674", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:14:34.126329", "lvl": "debug", "msg": "end: 2.4.3 bootloader-commands (duration 00:00:28) [common]"}
- {"dt": "2023-03-09T06:14:34.127680", "lvl": "debug", "msg": "start: 2.4.4 auto-login-action (timeout 00:19:23) [common]"}
- {"dt": "2023-03-09T06:14:34.128777", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"}
- {"dt": "2023-03-09T06:14:34.129785", "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": "2023-03-09T06:14:34.130796", "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": "2023-03-09T06:14:35.423805", "lvl": "target", "msg": "[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073]"}
- {"dt": "2023-03-09T06:14:35.426061", "lvl": "target", "msg": "[    0.000000] Linux version 5.10.41-yocto-standard (oe-user@oe-host) (aarch64-agl-linux-gcc (GCC) 11.3.0, GNU ld (GNU Binutils) 2.38.20220708) #1 SMP PREEMPT Mon Jan 30 17:33:20 UTC 2023"}
- {"dt": "2023-03-09T06:14:35.428099", "lvl": "target", "msg": "[    0.000000] Machine model: AGL Reference Hardware based on r8a77951 ES3.0+ with 8GiB (4 x 2 GiB)"}
- {"dt": "2023-03-09T06:14:35.429871", "lvl": "target", "msg": "[    0.000000] efi: UEFI not found."}
- {"dt": "2023-03-09T06:14:35.432109", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000057000000, size 16 MiB"}
- {"dt": "2023-03-09T06:14:35.435386", "lvl": "debug", "msg": "start: 2.4.4.1 login-action (timeout 00:19:22) [common]"}
- {"dt": "2023-03-09T06:14:35.436099", "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": "2023-03-09T06:14:35.436779", "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": "2023-03-09T06:14:35.437396", "lvl": "debug", "msg": "Using line separator: #'\\n'#"}
- {"dt": "2023-03-09T06:14:35.438056", "lvl": "info", "msg": "Waiting for the login prompt"}
- {"dt": "2023-03-09T06:14:35.438713", "lvl": "info", "msg": "Parsing kernel messages"}
- {"dt": "2023-03-09T06:14:35.439996", "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": "2023-03-09T06:14:35.442183", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:19:22)"}
- {"dt": "2023-03-09T06:14:35.465933", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,adsp@57000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-09T06:14:35.467967", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000058000000, size 384 MiB"}
- {"dt": "2023-03-09T06:14:35.469544", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,cma@58000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-09T06:14:35.470685", "lvl": "target", "msg": "[    0.000000] Reserved memory: created CMA memory pool at 0x0000000070000000, size 256 MiB"}
- {"dt": "2023-03-09T06:14:35.472587", "lvl": "target", "msg": "[    0.000000] OF: reserved mem: initialized node linux,multimedia@70000000, compatible id shared-dma-pool"}
- {"dt": "2023-03-09T06:14:35.508980", "lvl": "target", "msg": "[    0.000000] NUMA: No NUMA configuration found"}
- {"dt": "2023-03-09T06:14:35.511533", "lvl": "target", "msg": "[    0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2023-03-09T06:14:35.513331", "lvl": "target", "msg": "[    0.000000] NUMA: NODE_DATA [mem 0x77efdfb00-0x77efe1fff]"}
- {"dt": "2023-03-09T06:14:35.514672", "lvl": "target", "msg": "[    0.000000] Zone ranges:"}
- {"dt": "2023-03-09T06:14:35.516089", "lvl": "target", "msg": "[    0.000000]   DMA      [mem 0x0000000048000000-0x00000000ffffffff]"}
- {"dt": "2023-03-09T06:14:35.517357", "lvl": "target", "msg": "[    0.000000]   DMA32    empty"}
- {"dt": "2023-03-09T06:14:35.518571", "lvl": "target", "msg": "[    0.000000]   Normal   [mem 0x0000000100000000-0x000000077fffffff]"}
- {"dt": "2023-03-09T06:14:35.519505", "lvl": "target", "msg": "[    0.000000] Movable zone start for each node"}
- {"dt": "2023-03-09T06:14:35.520046", "lvl": "target", "msg": "[    0.000000] Early memory node ranges"}
- {"dt": "2023-03-09T06:14:35.552026", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000048000000-0x0000000053ffffff]"}
- {"dt": "2023-03-09T06:14:35.553857", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000054000000-0x0000000056ffffff]"}
- {"dt": "2023-03-09T06:14:35.554934", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000057000000-0x00000000bfffffff]"}
- {"dt": "2023-03-09T06:14:35.555878", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000500000000-0x000000057fffffff]"}
- {"dt": "2023-03-09T06:14:35.556833", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000600000000-0x000000067fffffff]"}
- {"dt": "2023-03-09T06:14:35.557797", "lvl": "target", "msg": "[    0.000000]   node   0: [mem 0x0000000700000000-0x000000077fffffff]"}
- {"dt": "2023-03-09T06:14:35.594822", "lvl": "target", "msg": "[    0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff]"}
- {"dt": "2023-03-09T06:14:35.596753", "lvl": "target", "msg": "[    0.000000] psci: probing for conduit method from DT."}
- {"dt": "2023-03-09T06:14:35.598512", "lvl": "target", "msg": "[    0.000000] psci: PSCIv1.1 detected in firmware."}
- {"dt": "2023-03-09T06:14:35.601528", "lvl": "target", "msg": "[    0.000000] psci: Using standard PSCI v0.2 function IDs"}
- {"dt": "2023-03-09T06:14:35.603474", "lvl": "target", "msg": "[    0.000000] psci: Trusted OS resident on physical CPU 0x0"}
- {"dt": "2023-03-09T06:14:35.605156", "lvl": "target", "msg": "[    0.000000] psci: SMC Calling Convention v1.1"}
- {"dt": "2023-03-09T06:14:35.606772", "lvl": "target", "msg": "[    0.000000] percpu: Embedded 33 pages/cpu s94296 r8192 d32680 u135168"}
- {"dt": "2023-03-09T06:14:35.607582", "lvl": "target", "msg": "[    0.000000] Detected PIPT I-cache on CPU0"}
- {"dt": "2023-03-09T06:14:35.608002", "lvl": "target", "msg": "[    0.000000] CPU features: detected: EL2 vector hardening"}
- {"dt": "2023-03-09T06:14:35.637640", "lvl": "target", "msg": "[    0.000000] CPU features: detected: Spectre-v2"}
- {"dt": "2023-03-09T06:14:35.639306", "lvl": "target", "msg": "[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923"}
- {"dt": "2023-03-09T06:14:35.640380", "lvl": "target", "msg": "[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2032128"}
- {"dt": "2023-03-09T06:14:35.641447", "lvl": "target", "msg": "[    0.000000] Policy zone: Normal"}
- {"dt": "2023-03-09T06:14:35.642611", "lvl": "target", "msg": "[    0.000000] Kernel command line: console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61957 root=/dev/ram0 ramdisk_size=16384 rootdelay=7  systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw"}
- {"dt": "2023-03-09T06:14:35.680768", "lvl": "target", "msg": "[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)"}
- {"dt": "2023-03-09T06:14:35.681827", "lvl": "target", "msg": "[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)"}
- {"dt": "2023-03-09T06:14:35.682811", "lvl": "target", "msg": "[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"}
- {"dt": "2023-03-09T06:14:35.684389", "lvl": "target", "msg": "[    0.000000] software IO TLB: mapped [mem 0x00000000bbbf5000-0x00000000bfbf5000] (64MB)"}
- {"dt": "2023-03-09T06:14:35.723256", "lvl": "target", "msg": "[    0.000000] Memory: 7268892K/8257536K available (17600K kernel code, 3792K rwdata, 9264K rodata, 7360K init, 574K bss, 316900K reserved, 671744K cma-reserved)"}
- {"dt": "2023-03-09T06:14:35.725868", "lvl": "target", "msg": "[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1"}
- {"dt": "2023-03-09T06:14:35.727523", "lvl": "target", "msg": "[    0.000000] rcu: Preemptible hierarchical RCU implementation."}
- {"dt": "2023-03-09T06:14:35.728970", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU event tracing is enabled."}
- {"dt": "2023-03-09T06:14:35.730452", "lvl": "target", "msg": "[    0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8."}
- {"dt": "2023-03-09T06:14:35.731984", "lvl": "target", "msg": "[    0.000000] \tTrampoline variant of Tasks RCU enabled."}
- {"dt": "2023-03-09T06:14:35.733373", "lvl": "target", "msg": "[    0.000000] \tTracing variant of Tasks RCU enabled."}
- {"dt": "2023-03-09T06:14:35.735063", "lvl": "target", "msg": "[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies."}
- {"dt": "2023-03-09T06:14:35.765940", "lvl": "target", "msg": "[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8"}
- {"dt": "2023-03-09T06:14:35.767452", "lvl": "target", "msg": "[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0"}
- {"dt": "2023-03-09T06:14:35.768453", "lvl": "target", "msg": "[    0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000"}
- {"dt": "2023-03-09T06:14:35.769487", "lvl": "target", "msg": "[    0.000000] GIC: Using split EOI/Deactivate mode"}
- {"dt": "2023-03-09T06:14:35.770611", "lvl": "target", "msg": "[    0.000000] random: get_random_bytes called from start_kernel+0x384/0x548 with crng_init=0"}
- {"dt": "2023-03-09T06:14:35.771654", "lvl": "target", "msg": "[    0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys)."}
- {"dt": "2023-03-09T06:14:35.809168", "lvl": "target", "msg": "[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns"}
- {"dt": "2023-03-09T06:14:35.811993", "lvl": "target", "msg": "[    0.000003] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns"}
- {"dt": "2023-03-09T06:14:35.813423", "lvl": "target", "msg": "[    0.000161] Console: colour dummy device 80x25"}
- {"dt": "2023-03-09T06:14:35.814719", "lvl": "target", "msg": "[    0.000245] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333)"}
- {"dt": "2023-03-09T06:14:35.816103", "lvl": "target", "msg": "[    0.000257] pid_max: default: 32768 minimum: 301"}
- {"dt": "2023-03-09T06:14:35.817332", "lvl": "target", "msg": "[    0.000312] LSM: Security Framework initializing"}
- {"dt": "2023-03-09T06:14:35.818573", "lvl": "target", "msg": "[    0.000340] SELinux:  Initializing."}
- {"dt": "2023-03-09T06:14:35.820166", "lvl": "target", "msg": "[    0.000380] LSM support for eBPF active"}
- {"dt": "2023-03-09T06:14:35.852917", "lvl": "target", "msg": "[    0.000450] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T06:14:35.853870", "lvl": "target", "msg": "[    0.000496] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T06:14:35.854847", "lvl": "target", "msg": "[    0.002038] rcu: Hierarchical SRCU implementation."}
- {"dt": "2023-03-09T06:14:35.855755", "lvl": "target", "msg": "[    0.003693] Detected Renesas R-Car Gen3 r8a7795 ES3.0"}
- {"dt": "2023-03-09T06:14:35.856542", "lvl": "target", "msg": "[    0.005689] EFI services will not be available."}
- {"dt": "2023-03-09T06:14:35.857533", "lvl": "target", "msg": "[    0.006016] smp: Bringing up secondary CPUs ..."}
- {"dt": "2023-03-09T06:14:35.858567", "lvl": "target", "msg": "[    0.006488] Detected PIPT I-cache on CPU1"}
- {"dt": "2023-03-09T06:14:35.895138", "lvl": "target", "msg": "[    0.006543] CPU1: Booted secondary processor 0x0000000001 [0x411fd073]"}
- {"dt": "2023-03-09T06:14:35.895935", "lvl": "target", "msg": "[    0.007029] Detected PIPT I-cache on CPU2"}
- {"dt": "2023-03-09T06:14:35.897659", "lvl": "target", "msg": "[    0.007056] CPU2: Booted secondary processor 0x0000000002 [0x411fd073]"}
- {"dt": "2023-03-09T06:14:35.898728", "lvl": "target", "msg": "[    0.007490] Detected PIPT I-cache on CPU3"}
- {"dt": "2023-03-09T06:14:35.899796", "lvl": "target", "msg": "[    0.007513] CPU3: Booted secondary processor 0x0000000003 [0x411fd073]"}
- {"dt": "2023-03-09T06:14:35.900770", "lvl": "target", "msg": "[    0.007958] CPU features: detected: ARM erratum 845719"}
- {"dt": "2023-03-09T06:14:35.901765", "lvl": "target", "msg": "[    0.007971] Detected VIPT I-cache on CPU4"}
- {"dt": "2023-03-09T06:14:35.902749", "lvl": "target", "msg": "[    0.008019] CPU4: Booted secondary processor 0x0000000100 [0x410fd034]"}
- {"dt": "2023-03-09T06:14:35.903952", "lvl": "target", "msg": "[    0.008527] Detected VIPT I-cache on CPU5"}
- {"dt": "2023-03-09T06:14:35.938027", "lvl": "target", "msg": "[    0.008556] CPU5: Booted secondary processor 0x0000000101 [0x410fd034]"}
- {"dt": "2023-03-09T06:14:35.940329", "lvl": "target", "msg": "[    0.009033] Detected VIPT I-cache on CPU6"}
- {"dt": "2023-03-09T06:14:35.941619", "lvl": "target", "msg": "[    0.009061] CPU6: Booted secondary processor 0x0000000102 [0x410fd034]"}
- {"dt": "2023-03-09T06:14:35.942827", "lvl": "target", "msg": "[    0.009505] Detected VIPT I-cache on CPU7"}
- {"dt": "2023-03-09T06:14:35.944079", "lvl": "target", "msg": "[    0.009532] CPU7: Booted secondary processor 0x0000000103 [0x410fd034]"}
- {"dt": "2023-03-09T06:14:35.945274", "lvl": "target", "msg": "[    0.009615] smp: Brought up 1 node, 8 CPUs"}
- {"dt": "2023-03-09T06:14:35.946475", "lvl": "target", "msg": "[    0.009642] SMP: Total of 8 processors activated."}
- {"dt": "2023-03-09T06:14:35.947951", "lvl": "target", "msg": "[    0.009648] CPU features: detected: 32-bit EL0 Support"}
- {"dt": "2023-03-09T06:14:35.980896", "lvl": "target", "msg": "[    0.009653] CPU features: detected: CRC32 instructions"}
- {"dt": "2023-03-09T06:14:35.982525", "lvl": "target", "msg": "[    0.009658] CPU features: detected: 32-bit EL1 Support"}
- {"dt": "2023-03-09T06:14:35.983639", "lvl": "target", "msg": "[    0.022890] CPU: All CPU(s) started at EL2"}
- {"dt": "2023-03-09T06:14:35.984636", "lvl": "target", "msg": "[    0.022928] alternatives: patching kernel code"}
- {"dt": "2023-03-09T06:14:35.985623", "lvl": "target", "msg": "[    0.024003] devtmpfs: initialized"}
- {"dt": "2023-03-09T06:14:35.986598", "lvl": "target", "msg": "[    0.031376] KASLR disabled due to lack of seed"}
- {"dt": "2023-03-09T06:14:35.987662", "lvl": "target", "msg": "[    0.031512] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns"}
- {"dt": "2023-03-09T06:14:35.988830", "lvl": "target", "msg": "[    0.031526] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T06:14:36.023924", "lvl": "target", "msg": "[    0.040192] pinctrl core: initialized pinctrl subsystem"}
- {"dt": "2023-03-09T06:14:36.026152", "lvl": "target", "msg": "[    0.041579] DMI not present or invalid."}
- {"dt": "2023-03-09T06:14:36.027516", "lvl": "target", "msg": "[    0.041945] NET: Registered protocol family 16"}
- {"dt": "2023-03-09T06:14:36.028698", "lvl": "target", "msg": "[    0.042889] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations"}
- {"dt": "2023-03-09T06:14:36.029912", "lvl": "target", "msg": "[    0.043059] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations"}
- {"dt": "2023-03-09T06:14:36.031181", "lvl": "target", "msg": "[    0.043342] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations"}
- {"dt": "2023-03-09T06:14:36.032378", "lvl": "target", "msg": "[    0.043379] audit: initializing netlink subsys (disabled)"}
- {"dt": "2023-03-09T06:14:36.067084", "lvl": "target", "msg": "[    0.043553] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1"}
- {"dt": "2023-03-09T06:14:36.069761", "lvl": "target", "msg": "[    0.044682] thermal_sys: Registered thermal governor 'power_allocator'"}
- {"dt": "2023-03-09T06:14:36.071440", "lvl": "target", "msg": "[    0.045207] cpuidle: using governor menu"}
- {"dt": "2023-03-09T06:14:36.072970", "lvl": "target", "msg": "[    0.045378] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers."}
- {"dt": "2023-03-09T06:14:36.074481", "lvl": "target", "msg": "[    0.045501] ASID allocator initialised with 65536 entries"}
- {"dt": "2023-03-09T06:14:36.076107", "lvl": "target", "msg": "[    0.046721] Serial: AMBA PL011 UART driver"}
- {"dt": "2023-03-09T06:14:36.077601", "lvl": "target", "msg": "[    0.089317] sh-pfc e6060000.pinctrl: r8a77951_pfc support registered"}
- {"dt": "2023-03-09T06:14:36.079494", "lvl": "target", "msg": "[    0.103843] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-09T06:14:36.110308", "lvl": "target", "msg": "[    0.103851] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-09T06:14:36.111215", "lvl": "target", "msg": "[    0.103856] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-09T06:14:36.112260", "lvl": "target", "msg": "[    0.103861] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages"}
- {"dt": "2023-03-09T06:14:36.113263", "lvl": "target", "msg": "[    0.104848] cryptd: max_cpu_qlen set to 1000"}
- {"dt": "2023-03-09T06:14:36.114424", "lvl": "target", "msg": "[    0.107062] ACPI: Interpreter disabled."}
- {"dt": "2023-03-09T06:14:36.115495", "lvl": "target", "msg": "[    0.110821] rcar-avs: use avs value: 0"}
- {"dt": "2023-03-09T06:14:36.116492", "lvl": "target", "msg": "[    0.110831] rcar-avs: cpu@0 is running with: opp_table0"}
- {"dt": "2023-03-09T06:14:36.117671", "lvl": "target", "msg": "[    0.110838] rcar-avs: cpu@1 is running with: opp_table0"}
- {"dt": "2023-03-09T06:14:36.152885", "lvl": "target", "msg": "[    0.110844] rcar-avs: cpu@2 is running with: opp_table0"}
- {"dt": "2023-03-09T06:14:36.155168", "lvl": "target", "msg": "[    0.110851] rcar-avs: cpu@3 is running with: opp_table0"}
- {"dt": "2023-03-09T06:14:36.156456", "lvl": "target", "msg": "[    0.110927] rcar-avs: gsx@fd000000 is running with: gsx_opp_table0"}
- {"dt": "2023-03-09T06:14:36.157640", "lvl": "target", "msg": "[    0.112299] iommu: Default domain type: Translated "}
- {"dt": "2023-03-09T06:14:36.158826", "lvl": "target", "msg": "[    0.112842] ipmmu-vmsa e67b0000.iommu: IPMMU context 0 is reserved"}
- {"dt": "2023-03-09T06:14:36.160075", "lvl": "target", "msg": "[    0.113803] vgaarb: loaded"}
- {"dt": "2023-03-09T06:14:36.161263", "lvl": "target", "msg": "[    0.113999] SCSI subsystem initialized"}
- {"dt": "2023-03-09T06:14:36.162447", "lvl": "target", "msg": "[    0.114257] usbcore: registered new interface driver usbfs"}
- {"dt": "2023-03-09T06:14:36.195635", "lvl": "target", "msg": "[    0.114280] usbcore: registered new interface driver hub"}
- {"dt": "2023-03-09T06:14:36.197153", "lvl": "target", "msg": "[    0.114303] usbcore: registered new device driver usb"}
- {"dt": "2023-03-09T06:14:36.198082", "lvl": "target", "msg": "[    0.117686] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz"}
- {"dt": "2023-03-09T06:14:36.198975", "lvl": "target", "msg": "[    0.118201] mc: Linux media interface: v0.10"}
- {"dt": "2023-03-09T06:14:36.199863", "lvl": "target", "msg": "[    0.118220] videodev: Linux video capture interface: v2.00"}
- {"dt": "2023-03-09T06:14:36.200706", "lvl": "target", "msg": "[    0.118267] pps_core: LinuxPPS API ver. 1 registered"}
- {"dt": "2023-03-09T06:14:36.201707", "lvl": "target", "msg": "[    0.118272] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>"}
- {"dt": "2023-03-09T06:14:36.202590", "lvl": "target", "msg": "[    0.118281] PTP clock support registered"}
- {"dt": "2023-03-09T06:14:36.238754", "lvl": "target", "msg": "[    0.118442] EDAC MC: Ver: 3.0.0"}
- {"dt": "2023-03-09T06:14:36.239724", "lvl": "target", "msg": "[    0.120295] FPGA manager framework"}
- {"dt": "2023-03-09T06:14:36.241409", "lvl": "target", "msg": "[    0.120345] Advanced Linux Sound Architecture Driver Initialized."}
- {"dt": "2023-03-09T06:14:36.242501", "lvl": "target", "msg": "[    0.120917] clocksource: Switched to clocksource arch_sys_counter"}
- {"dt": "2023-03-09T06:14:36.243529", "lvl": "target", "msg": "[    0.563954] VFS: Disk quotas dquot_6.6.0"}
- {"dt": "2023-03-09T06:14:36.244505", "lvl": "target", "msg": "[    0.563999] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)"}
- {"dt": "2023-03-09T06:14:36.245494", "lvl": "target", "msg": "[    0.564165] pnp: PnP ACPI: disabled"}
- {"dt": "2023-03-09T06:14:36.246459", "lvl": "target", "msg": "[    0.568245] NET: Registered protocol family 2"}
- {"dt": "2023-03-09T06:14:36.247715", "lvl": "target", "msg": "[    0.568634] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2023-03-09T06:14:36.283072", "lvl": "target", "msg": "[    0.570878] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)"}
- {"dt": "2023-03-09T06:14:36.284536", "lvl": "target", "msg": "[    0.570967] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)"}
- {"dt": "2023-03-09T06:14:36.286106", "lvl": "target", "msg": "[    0.571247] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)"}
- {"dt": "2023-03-09T06:14:36.287518", "lvl": "target", "msg": "[    0.571823] TCP: Hash tables configured (established 65536 bind 65536)"}
- {"dt": "2023-03-09T06:14:36.288840", "lvl": "target", "msg": "[    0.571960] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T06:14:36.324683", "lvl": "target", "msg": "[    0.572070] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)"}
- {"dt": "2023-03-09T06:14:36.326093", "lvl": "target", "msg": "[    0.572290] NET: Registered protocol family 1"}
- {"dt": "2023-03-09T06:14:36.328708", "lvl": "target", "msg": "[    0.572685] RPC: Registered named UNIX socket transport module."}
- {"dt": "2023-03-09T06:14:36.330103", "lvl": "target", "msg": "[    0.572691] RPC: Registered udp transport module."}
- {"dt": "2023-03-09T06:14:36.331505", "lvl": "target", "msg": "[    0.572695] RPC: Registered tcp transport module."}
- {"dt": "2023-03-09T06:14:36.332844", "lvl": "target", "msg": "[    0.572699] RPC: Registered tcp NFSv4.1 backchannel transport module."}
- {"dt": "2023-03-09T06:14:36.334184", "lvl": "target", "msg": "[    0.572709] PCI: CLS 0 bytes, default 64"}
- {"dt": "2023-03-09T06:14:36.335409", "lvl": "target", "msg": "[    0.572862] Trying to unpack rootfs image as initramfs..."}
- {"dt": "2023-03-09T06:14:36.336050", "lvl": "target", "msg": "[    0.573073] rootfs image is not initramfs (no cpio magic); looks like an initrd"}
- {"dt": "2023-03-09T06:14:36.367590", "lvl": "target", "msg": "[    0.576040] Freeing initrd memory: 2008K"}
- {"dt": "2023-03-09T06:14:36.369502", "lvl": "target", "msg": "[    0.576987] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available"}
- {"dt": "2023-03-09T06:14:36.370514", "lvl": "target", "msg": "[    0.577273] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available"}
- {"dt": "2023-03-09T06:14:36.371490", "lvl": "target", "msg": "[    0.577875] kvm [1]: IPA Size Limit: 40 bits"}
- {"dt": "2023-03-09T06:14:36.372408", "lvl": "target", "msg": "[    0.578746] kvm [1]: vgic interrupt IRQ9"}
- {"dt": "2023-03-09T06:14:36.373297", "lvl": "target", "msg": "[    0.578913] kvm [1]: Hyp mode initialized successfully"}
- {"dt": "2023-03-09T06:14:36.374355", "lvl": "target", "msg": "[    0.582195] Initialise system trusted keyrings"}
- {"dt": "2023-03-09T06:14:36.410646", "lvl": "target", "msg": "[    0.582326] workingset: timestamp_bits=42 max_order=21 bucket_order=0"}
- {"dt": "2023-03-09T06:14:36.413088", "lvl": "target", "msg": "[    0.585741] squashfs: version 4.0 (2009/01/31) Phillip Lougher"}
- {"dt": "2023-03-09T06:14:36.414369", "lvl": "target", "msg": "[    0.586155] NFS: Registering the id_resolver key type"}
- {"dt": "2023-03-09T06:14:36.415634", "lvl": "target", "msg": "[    0.586179] Key type id_resolver registered"}
- {"dt": "2023-03-09T06:14:36.416831", "lvl": "target", "msg": "[    0.586183] Key type id_legacy registered"}
- {"dt": "2023-03-09T06:14:36.418042", "lvl": "target", "msg": "[    0.586237] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."}
- {"dt": "2023-03-09T06:14:36.419283", "lvl": "target", "msg": "[    0.586243] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."}
- {"dt": "2023-03-09T06:14:36.420700", "lvl": "target", "msg": "[    0.586400] 9p: Installing v9fs 9p2000 file system support"}
- {"dt": "2023-03-09T06:14:36.453467", "lvl": "target", "msg": "[    0.612669] NET: Registered protocol family 38"}
- {"dt": "2023-03-09T06:14:36.454418", "lvl": "target", "msg": "[    0.612679] Key type asymmetric registered"}
- {"dt": "2023-03-09T06:14:36.456399", "lvl": "target", "msg": "[    0.612685] Asymmetric key parser 'x509' registered"}
- {"dt": "2023-03-09T06:14:36.457471", "lvl": "target", "msg": "[    0.612716] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)"}
- {"dt": "2023-03-09T06:14:36.458441", "lvl": "target", "msg": "[    0.612722] io scheduler mq-deadline registered"}
- {"dt": "2023-03-09T06:14:36.459364", "lvl": "target", "msg": "[    0.612727] io scheduler kyber registered"}
- {"dt": "2023-03-09T06:14:36.460319", "lvl": "target", "msg": "[    0.627882] gpio_rcar e6050000.gpio: driving 16 GPIOs"}
- {"dt": "2023-03-09T06:14:36.461296", "lvl": "target", "msg": "[    0.628190] gpio_rcar e6051000.gpio: driving 29 GPIOs"}
- {"dt": "2023-03-09T06:14:36.462459", "lvl": "target", "msg": "[    0.628474] gpio_rcar e6052000.gpio: driving 15 GPIOs"}
- {"dt": "2023-03-09T06:14:36.496410", "lvl": "target", "msg": "[    0.628746] gpio_rcar e6053000.gpio: driving 16 GPIOs"}
- {"dt": "2023-03-09T06:14:36.498443", "lvl": "target", "msg": "[    0.629035] gpio_rcar e6054000.gpio: driving 18 GPIOs"}
- {"dt": "2023-03-09T06:14:36.499570", "lvl": "target", "msg": "[    0.629317] gpio_rcar e6055000.gpio: driving 26 GPIOs"}
- {"dt": "2023-03-09T06:14:36.500552", "lvl": "target", "msg": "[    0.629492] gpio-369 (wlan-pwr-en): hogged as output/high"}
- {"dt": "2023-03-09T06:14:36.501525", "lvl": "target", "msg": "[    0.629511] gpio-381 (can-1-transceiver-stb): hogged as output/low"}
- {"dt": "2023-03-09T06:14:36.502511", "lvl": "target", "msg": "[    0.629529] gpio-372 (can-2-transceiver-stb): hogged as output/low"}
- {"dt": "2023-03-09T06:14:36.503545", "lvl": "target", "msg": "[    0.629647] gpio_rcar e6055400.gpio: driving 32 GPIOs"}
- {"dt": "2023-03-09T06:14:36.504711", "lvl": "target", "msg": "[    0.629916] gpio_rcar e6055800.gpio: driving 4 GPIOs"}
- {"dt": "2023-03-09T06:14:36.539454", "lvl": "target", "msg": "[    0.632131] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges:"}
- {"dt": "2023-03-09T06:14:36.541462", "lvl": "target", "msg": "[    0.632154] rcar-pcie fe000000.pcie:       IO 0x00fe100000..0x00fe1fffff -> 0x0000000000"}
- {"dt": "2023-03-09T06:14:36.542533", "lvl": "target", "msg": "[    0.632172] rcar-pcie fe000000.pcie:      MEM 0x00fe200000..0x00fe3fffff -> 0x00fe200000"}
- {"dt": "2023-03-09T06:14:36.543581", "lvl": "target", "msg": "[    0.632187] rcar-pcie fe000000.pcie:      MEM 0x0030000000..0x0037ffffff -> 0x0030000000"}
- {"dt": "2023-03-09T06:14:36.544572", "lvl": "target", "msg": "[    0.632198] rcar-pcie fe000000.pcie:      MEM 0x0038000000..0x003fffffff -> 0x0038000000"}
- {"dt": "2023-03-09T06:14:36.582498", "lvl": "target", "msg": "[    0.632211] rcar-pcie fe000000.pcie:   IB MEM 0x0040000000..0x00bfffffff -> 0x0040000000"}
- {"dt": "2023-03-09T06:14:36.585213", "lvl": "target", "msg": "[    0.632249] rcar-pcie fe000000.pcie: no pcie3v3 regulator found"}
- {"dt": "2023-03-09T06:14:36.586622", "lvl": "target", "msg": "[    0.632257] rcar-pcie fe000000.pcie: no pcie1v8 regulator found"}
- {"dt": "2023-03-09T06:14:36.587815", "lvl": "target", "msg": "[    0.696539] rcar-pcie fe000000.pcie: PCIe link down"}
- {"dt": "2023-03-09T06:14:36.588806", "lvl": "target", "msg": "[    0.696695] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges:"}
- {"dt": "2023-03-09T06:14:36.589810", "lvl": "target", "msg": "[    0.696713] rcar-pcie ee800000.pcie:       IO 0x00ee900000..0x00ee9fffff -> 0x0000000000"}
- {"dt": "2023-03-09T06:14:36.591023", "lvl": "target", "msg": "[    0.696729] rcar-pcie ee800000.pcie:      MEM 0x00eea00000..0x00eebfffff -> 0x00eea00000"}
- {"dt": "2023-03-09T06:14:36.626527", "lvl": "target", "msg": "[    0.696742] rcar-pcie ee800000.pcie:      MEM 0x00c0000000..0x00c7ffffff -> 0x00c0000000"}
- {"dt": "2023-03-09T06:14:36.628033", "lvl": "target", "msg": "[    0.696753] rcar-pcie ee800000.pcie:      MEM 0x00c8000000..0x00cfffffff -> 0x00c8000000"}
- {"dt": "2023-03-09T06:14:36.629620", "lvl": "target", "msg": "[    0.696765] rcar-pcie ee800000.pcie:   IB MEM 0x0040000000..0x00bfffffff -> 0x0040000000"}
- {"dt": "2023-03-09T06:14:36.631011", "lvl": "target", "msg": "[    0.696792] rcar-pcie ee800000.pcie: no pcie3v3 regulator found"}
- {"dt": "2023-03-09T06:14:36.632239", "lvl": "target", "msg": "[    0.696799] rcar-pcie ee800000.pcie: no pcie1v8 regulator found"}
- {"dt": "2023-03-09T06:14:36.633776", "lvl": "target", "msg": "[    0.760542] rcar-pcie ee800000.pcie: PCIe link down"}
- {"dt": "2023-03-09T06:14:36.635091", "lvl": "target", "msg": "[    0.762875] EINJ: ACPI disabled."}
- {"dt": "2023-03-09T06:14:36.669171", "lvl": "target", "msg": "[    0.794170] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled"}
- {"dt": "2023-03-09T06:14:36.670231", "lvl": "target", "msg": "[    0.796585] SuperH (H)SCI(F) driver initialized"}
- {"dt": "2023-03-09T06:14:36.671341", "lvl": "target", "msg": "[    0.796961] e6540000.serial: ttySC4 at MMIO 0xe6540000 (irq = 44, base_baud = 0) is a hscif"}
- {"dt": "2023-03-09T06:14:36.672426", "lvl": "target", "msg": "[    0.797127] serial serial0: tty port ttySC4 registered"}
- {"dt": "2023-03-09T06:14:36.673690", "lvl": "target", "msg": "[    0.797539] e6550000.serial: ttySC3 at MMIO 0xe6550000 (irq = 45, base_baud = 0) is a hscif"}
- {"dt": "2023-03-09T06:14:36.674794", "lvl": "target", "msg": "[    0.798077] e6560000.serial: ttySC5 at MMIO 0xe6560000 (irq = 46, base_baud = 0) is a hscif"}
- {"dt": "2023-03-09T06:14:36.719671", "lvl": "target", "msg": "[    0.798654] e6e68000.serial: ttySC1 at MMIO 0xe6e68000 (irq = 134, base_baud = 0) is a scif"}
- {"dt": "2023-03-09T06:14:36.720849", "lvl": "target", "msg": "[    0.799163] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 135, base_baud = 0) is a scif"}
- {"dt": "2023-03-09T06:14:36.722132", "lvl": "target", "msg": "[    2.101328] printk: console [ttySC0] enabled"}
- {"dt": "2023-03-09T06:14:36.723308", "lvl": "target", "msg": "[    2.106059] e6f30000.serial: ttySC2 at MMIO 0xe6f30000 (irq = 136, base_baud = 0) is a scif"}
- {"dt": "2023-03-09T06:14:36.724365", "lvl": "target", "msg": "[    2.115507] msm_serial: driver initialized"}
- {"dt": "2023-03-09T06:14:36.725626", "lvl": "target", "msg": "[    2.125213] rcar-dw-hdmi fead0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2023-03-09T06:14:36.756622", "lvl": "target", "msg": "[    2.135070] rcar-dw-hdmi fead0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2023-03-09T06:14:36.759315", "lvl": "target", "msg": "[    2.143250] rcar-dw-hdmi feae0000.hdmi: Detected HDMI TX controller v2.01a with HDCP (DWC HDMI 2.0 TX PHY)"}
- {"dt": "2023-03-09T06:14:36.760725", "lvl": "target", "msg": "[    2.153075] rcar-dw-hdmi feae0000.hdmi: registered DesignWare HDMI I2C bus driver"}
- {"dt": "2023-03-09T06:14:36.762373", "lvl": "target", "msg": "[    2.167950] brd: module loaded"}
- {"dt": "2023-03-09T06:14:36.774867", "lvl": "target", "msg": "[    2.176495] loop: module loaded"}
- {"dt": "2023-03-09T06:14:36.823299", "lvl": "target", "msg": "[    2.231697] megasas: 07.714.04.00-rc1"}
- {"dt": "2023-03-09T06:14:36.867928", "lvl": "target", "msg": "[    2.240480] libphy: Fixed MDIO Bus: probed"}
- {"dt": "2023-03-09T06:14:36.871449", "lvl": "target", "msg": "[    2.245613] tun: Universal TUN/TAP device driver, 1.6"}
- {"dt": "2023-03-09T06:14:36.873316", "lvl": "target", "msg": "[    2.251495] thunder_xcv, ver 1.0"}
- {"dt": "2023-03-09T06:14:36.875090", "lvl": "target", "msg": "[    2.254737] thunder_bgx, ver 1.0"}
- {"dt": "2023-03-09T06:14:36.876837", "lvl": "target", "msg": "[    2.257969] nicpf, ver 1.0"}
- {"dt": "2023-03-09T06:14:36.878569", "lvl": "target", "msg": "[    2.261987] hclge is initializing"}
- {"dt": "2023-03-09T06:14:36.879635", "lvl": "target", "msg": "[    2.265304] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version"}
- {"dt": "2023-03-09T06:14:36.880154", "lvl": "target", "msg": "[    2.272489] hns3: Copyright (c) 2017 Huawei Corporation."}
- {"dt": "2023-03-09T06:14:36.880666", "lvl": "target", "msg": "[    2.277803] e1000: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2023-03-09T06:14:36.915164", "lvl": "target", "msg": "[    2.282657] e1000: Copyright (c) 1999-2006 Intel Corporation."}
- {"dt": "2023-03-09T06:14:36.916684", "lvl": "target", "msg": "[    2.288394] e1000e: Intel(R) PRO/1000 Network Driver"}
- {"dt": "2023-03-09T06:14:36.917666", "lvl": "target", "msg": "[    2.293338] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."}
- {"dt": "2023-03-09T06:14:36.918567", "lvl": "target", "msg": "[    2.299250] igb: Intel(R) Gigabit Ethernet Network Driver"}
- {"dt": "2023-03-09T06:14:36.919556", "lvl": "target", "msg": "[    2.304623] igb: Copyright (c) 2007-2014 Intel Corporation."}
- {"dt": "2023-03-09T06:14:36.920625", "lvl": "target", "msg": "[    2.310181] igbvf: Intel(R) Gigabit Virtual Function Network Driver"}
- {"dt": "2023-03-09T06:14:36.921629", "lvl": "target", "msg": "[    2.316416] igbvf: Copyright (c) 2009 - 2012 Intel Corporation."}
- {"dt": "2023-03-09T06:14:36.922701", "lvl": "target", "msg": "[    2.322694] sky2: driver version 1.30"}
- {"dt": "2023-03-09T06:14:36.923714", "lvl": "target", "msg": "[    2.327595] libphy: ravb_mii: probed"}
- {"dt": "2023-03-09T06:14:36.962703", "lvl": "target", "msg": "[    2.333807] ravb e6800000.ethernet eth0: Base address at 0xe6800000, 00:80:45:1b:40:7f, IRQ 129."}
- {"dt": "2023-03-09T06:14:36.964266", "lvl": "target", "msg": "[    2.343902] VFIO - User Level meta-driver version: 0.3"}
- {"dt": "2023-03-09T06:14:36.965631", "lvl": "target", "msg": "[    2.350995] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"}
- {"dt": "2023-03-09T06:14:36.966987", "lvl": "target", "msg": "[    2.357501] ehci-pci: EHCI PCI platform driver"}
- {"dt": "2023-03-09T06:14:36.968560", "lvl": "target", "msg": "[    2.361944] ehci-platform: EHCI generic platform driver"}
- {"dt": "2023-03-09T06:14:37.009281", "lvl": "target", "msg": "[    2.392985] ehci-platform ee080100.usb: EHCI Host Controller"}
- {"dt": "2023-03-09T06:14:37.011158", "lvl": "target", "msg": "[    2.398634] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1"}
- {"dt": "2023-03-09T06:14:37.012100", "lvl": "target", "msg": "[    2.406494] ehci-platform ee080100.usb: irq 182, io mem 0xee080100"}
- {"dt": "2023-03-09T06:14:37.053003", "lvl": "target", "msg": "[    2.424923] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-09T06:14:37.054298", "lvl": "target", "msg": "[    2.431476] hub 1-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.055566", "lvl": "target", "msg": "[    2.435229] hub 1-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.056750", "lvl": "target", "msg": "[    2.439590] ehci-platform ee0a0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-09T06:14:37.058158", "lvl": "target", "msg": "[    2.445251] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2"}
- {"dt": "2023-03-09T06:14:37.059468", "lvl": "target", "msg": "[    2.453111] ehci-platform ee0a0100.usb: irq 183, io mem 0xee0a0100"}
- {"dt": "2023-03-09T06:14:37.103602", "lvl": "target", "msg": "[    2.472923] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-09T06:14:37.104682", "lvl": "target", "msg": "[    2.479365] hub 2-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.105670", "lvl": "target", "msg": "[    2.483115] hub 2-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.106840", "lvl": "target", "msg": "[    2.487409] ehci-platform ee0c0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-09T06:14:37.107974", "lvl": "target", "msg": "[    2.493067] ehci-platform ee0c0100.usb: new USB bus registered, assigned bus number 3"}
- {"dt": "2023-03-09T06:14:37.109155", "lvl": "target", "msg": "[    2.500930] ehci-platform ee0c0100.usb: irq 184, io mem 0xee0c0100"}
- {"dt": "2023-03-09T06:14:37.129854", "lvl": "target", "msg": "[    2.520923] ehci-platform ee0c0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-09T06:14:37.132115", "lvl": "target", "msg": "[    2.527354] hub 3-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.134341", "lvl": "target", "msg": "[    2.531103] hub 3-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.171748", "lvl": "target", "msg": "[    2.560959] ehci-platform ee0e0100.usb: EHCI Host Controller"}
- {"dt": "2023-03-09T06:14:37.174419", "lvl": "target", "msg": "[    2.566602] ehci-platform ee0e0100.usb: new USB bus registered, assigned bus number 4"}
- {"dt": "2023-03-09T06:14:37.176025", "lvl": "target", "msg": "[    2.574433] ehci-platform ee0e0100.usb: irq 185, io mem 0xee0e0100"}
- {"dt": "2023-03-09T06:14:37.219860", "lvl": "target", "msg": "[    2.592919] ehci-platform ee0e0100.usb: USB 2.0 started, EHCI 1.10"}
- {"dt": "2023-03-09T06:14:37.221549", "lvl": "target", "msg": "[    2.599351] hub 4-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.223146", "lvl": "target", "msg": "[    2.603100] hub 4-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.224670", "lvl": "target", "msg": "[    2.607380] ehci-orion: EHCI orion driver"}
- {"dt": "2023-03-09T06:14:37.226191", "lvl": "target", "msg": "[    2.611613] ehci-exynos: EHCI Exynos driver"}
- {"dt": "2023-03-09T06:14:37.228085", "lvl": "target", "msg": "[    2.615904] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"}
- {"dt": "2023-03-09T06:14:37.229641", "lvl": "target", "msg": "[    2.622088] ohci-pci: OHCI PCI platform driver"}
- {"dt": "2023-03-09T06:14:37.231234", "lvl": "target", "msg": "[    2.626556] ohci-platform: OHCI generic platform driver"}
- {"dt": "2023-03-09T06:14:37.244360", "lvl": "target", "msg": "[    2.631959] ohci-platform ee080000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-09T06:14:37.246368", "lvl": "target", "msg": "[    2.638644] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 5"}
- {"dt": "2023-03-09T06:14:37.248469", "lvl": "target", "msg": "[    2.646483] ohci-platform ee080000.usb: irq 182, io mem 0xee080000"}
- {"dt": "2023-03-09T06:14:37.361172", "lvl": "target", "msg": "[    2.739327] hub 5-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.362300", "lvl": "target", "msg": "[    2.743078] hub 5-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.364821", "lvl": "target", "msg": "[    2.747349] ohci-platform ee0a0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-09T06:14:37.366219", "lvl": "target", "msg": "[    2.754044] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 6"}
- {"dt": "2023-03-09T06:14:37.367894", "lvl": "target", "msg": "[    2.761893] ohci-platform ee0a0000.usb: irq 183, io mem 0xee0a0000"}
- {"dt": "2023-03-09T06:14:37.443341", "lvl": "target", "msg": "[    2.855314] hub 6-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.477975", "lvl": "target", "msg": "[    2.859063] hub 6-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.481017", "lvl": "target", "msg": "[    2.863315] ohci-platform ee0c0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-09T06:14:37.482626", "lvl": "target", "msg": "[    2.870010] ohci-platform ee0c0000.usb: new USB bus registered, assigned bus number 7"}
- {"dt": "2023-03-09T06:14:37.483955", "lvl": "target", "msg": "[    2.877872] ohci-platform ee0c0000.usb: irq 184, io mem 0xee0c0000"}
- {"dt": "2023-03-09T06:14:37.560033", "lvl": "target", "msg": "[    2.971310] hub 7-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.594710", "lvl": "target", "msg": "[    2.975058] hub 7-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.597756", "lvl": "target", "msg": "[    2.979330] ohci-platform ee0e0000.usb: Generic Platform OHCI controller"}
- {"dt": "2023-03-09T06:14:37.599400", "lvl": "target", "msg": "[    2.986030] ohci-platform ee0e0000.usb: new USB bus registered, assigned bus number 8"}
- {"dt": "2023-03-09T06:14:37.601157", "lvl": "target", "msg": "[    2.993876] ohci-platform ee0e0000.usb: irq 185, io mem 0xee0e0000"}
- {"dt": "2023-03-09T06:14:37.706510", "lvl": "target", "msg": "[    3.087313] hub 8-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.710054", "lvl": "target", "msg": "[    3.091063] hub 8-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.712042", "lvl": "target", "msg": "[    3.095329] ohci-exynos: OHCI Exynos driver"}
- {"dt": "2023-03-09T06:14:37.713772", "lvl": "target", "msg": "[    3.100158] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2023-03-09T06:14:37.715895", "lvl": "target", "msg": "[    3.105387] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 9"}
- {"dt": "2023-03-09T06:14:37.754574", "lvl": "target", "msg": "[    3.128616] xhci-hcd ee000000.usb: hcc params 0x014051ce hci version 0x100 quirks 0x0000000000830410"}
- {"dt": "2023-03-09T06:14:37.755803", "lvl": "target", "msg": "[    3.137730] xhci-hcd ee000000.usb: irq 180, io mem 0xee000000"}
- {"dt": "2023-03-09T06:14:37.757142", "lvl": "target", "msg": "[    3.143903] hub 9-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.758263", "lvl": "target", "msg": "[    3.147658] hub 9-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.759383", "lvl": "target", "msg": "[    3.151717] xhci-hcd ee000000.usb: xHCI Host Controller"}
- {"dt": "2023-03-09T06:14:37.760460", "lvl": "target", "msg": "[    3.156926] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 10"}
- {"dt": "2023-03-09T06:14:37.761734", "lvl": "target", "msg": "[    3.164379] xhci-hcd ee000000.usb: Host supports USB 3.0 SuperSpeed"}
- {"dt": "2023-03-09T06:14:37.799212", "lvl": "target", "msg": "[    3.170664] usb usb10: We don't know the algorithms for LPM for this host, disabling LPM."}
- {"dt": "2023-03-09T06:14:37.800852", "lvl": "target", "msg": "[    3.179092] hub 10-0:1.0: USB hub found"}
- {"dt": "2023-03-09T06:14:37.802270", "lvl": "target", "msg": "[    3.182931] hub 10-0:1.0: 1 port detected"}
- {"dt": "2023-03-09T06:14:37.803740", "lvl": "target", "msg": "[    3.187710] usbcore: registered new interface driver usb-storage"}
- {"dt": "2023-03-09T06:14:37.805200", "lvl": "target", "msg": "[    3.193767] usbcore: registered new interface driver usbserial_generic"}
- {"dt": "2023-03-09T06:14:37.806661", "lvl": "target", "msg": "[    3.200275] usbserial: USB Serial support registered for generic"}
- {"dt": "2023-03-09T06:14:37.808413", "lvl": "target", "msg": "[    3.206297] usbcore: registered new interface driver cp210x"}
- {"dt": "2023-03-09T06:14:37.844412", "lvl": "target", "msg": "[    3.211852] usbserial: USB Serial support registered for cp210x"}
- {"dt": "2023-03-09T06:14:37.845945", "lvl": "target", "msg": "[    3.219389] renesas_usbhs e6590000.usb: host probed"}
- {"dt": "2023-03-09T06:14:37.846976", "lvl": "target", "msg": "[    3.224258] renesas_usbhs e6590000.usb: no transceiver found"}
- {"dt": "2023-03-09T06:14:37.847904", "lvl": "target", "msg": "[    3.229970] renesas_usbhs e6590000.usb: gadget probed"}
- {"dt": "2023-03-09T06:14:37.848819", "lvl": "target", "msg": "[    3.235170] renesas_usbhs e6590000.usb: probed"}
- {"dt": "2023-03-09T06:14:37.849775", "lvl": "target", "msg": "[    3.239981] renesas_usbhs e659c000.usb: host probed"}
- {"dt": "2023-03-09T06:14:37.850850", "lvl": "target", "msg": "[    3.244848] renesas_usbhs e659c000.usb: no transceiver found"}
- {"dt": "2023-03-09T06:14:37.851867", "lvl": "target", "msg": "[    3.250556] renesas_usbhs e659c000.usb: gadget probed"}
- {"dt": "2023-03-09T06:14:37.852784", "lvl": "target", "msg": "[    3.255729] renesas_usbhs e659c000.usb: probed"}
- {"dt": "2023-03-09T06:14:37.867820", "lvl": "target", "msg": "[    3.261208] renesas_usb3 ee020000.usb: probed with phy"}
- {"dt": "2023-03-09T06:14:37.871454", "lvl": "target", "msg": "[    3.268373] i2c /dev entries driver"}
- {"dt": "2023-03-09T06:14:37.912314", "lvl": "target", "msg": "[    3.284980] cs2000-cp 2-004f: revision - C1"}
- {"dt": "2023-03-09T06:14:37.915065", "lvl": "target", "msg": "[    3.289207] i2c-rcar e6510000.i2c: probed"}
- {"dt": "2023-03-09T06:14:37.916476", "lvl": "target", "msg": "[    3.294075] i2c-rcar e66d0000.i2c: probed"}
- {"dt": "2023-03-09T06:14:37.917804", "lvl": "target", "msg": "[    3.296949] usb usb7-port1: over-current condition"}
- {"dt": "2023-03-09T06:14:37.919195", "lvl": "target", "msg": "[    3.300284] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-09T06:14:37.920534", "lvl": "target", "msg": "[    3.308846] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-09T06:14:37.921870", "lvl": "target", "msg": "[    3.314905] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-09T06:14:37.923388", "lvl": "target", "msg": "[    3.320902] vc5 4-006a: Invalid clock output configuration (04)"}
- {"dt": "2023-03-09T06:14:37.962519", "lvl": "target", "msg": "[    3.326860] i2c-rcar e66d8000.i2c: probed"}
- {"dt": "2023-03-09T06:14:37.963646", "lvl": "target", "msg": "[    3.331546] i2c-rcar e66e0000.i2c: probed"}
- {"dt": "2023-03-09T06:14:37.965655", "lvl": "target", "msg": "[    3.336394] usbcore: registered new interface driver i2c-tiny-usb"}
- {"dt": "2023-03-09T06:14:37.966719", "lvl": "target", "msg": "[    3.342900] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@8/endpoint on port 8"}
- {"dt": "2023-03-09T06:14:37.967761", "lvl": "target", "msg": "[    3.351843] adv748x 2-0070: Endpoint /soc/i2c@e6510000/video-receiver@70/port@a/endpoint on port 10"}
- {"dt": "2023-03-09T06:14:37.968923", "lvl": "target", "msg": "[    3.362001] adv748x 2-0070: chip found @ 0xe0 revision 2143"}
- {"dt": "2023-03-09T06:14:37.980901", "lvl": "target", "msg": "[    3.386678] random: fast init done"}
- {"dt": "2023-03-09T06:14:38.087557", "lvl": "target", "msg": "[    3.460469] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@8/endpoint on port 8"}
- {"dt": "2023-03-09T06:14:38.089223", "lvl": "target", "msg": "[    3.469417] adv748x 2-0071: Endpoint /soc/i2c@e6510000/video-receiver@71/port@a/endpoint on port 10"}
- {"dt": "2023-03-09T06:14:38.091034", "lvl": "target", "msg": "[    3.479578] adv748x 2-0071: chip found @ 0xe2 revision 2143"}
- {"dt": "2023-03-09T06:14:38.092843", "lvl": "target", "msg": "[    3.484923] usb 9-1: new high-speed USB device number 2 using xhci-hcd"}
- {"dt": "2023-03-09T06:14:38.168673", "lvl": "target", "msg": "[    3.579502] rcar-csi2 feaa0000.csi2: 4 lanes found"}
- {"dt": "2023-03-09T06:14:38.215407", "lvl": "target", "msg": "[    3.584574] rcar-csi2 feab0000.csi2: 4 lanes found"}
- {"dt": "2023-03-09T06:14:38.217350", "lvl": "target", "msg": "[    3.594822] rcar-vin e6ef0000.video: Device registered as video0"}
- {"dt": "2023-03-09T06:14:38.220798", "lvl": "target", "msg": "[    3.600928] rcar-vin e6ef1000.video: Device registered as video1"}
- {"dt": "2023-03-09T06:14:38.222682", "lvl": "target", "msg": "[    3.607058] rcar-vin e6ef2000.video: Device registered as video2"}
- {"dt": "2023-03-09T06:14:38.224591", "lvl": "target", "msg": "[    3.613158] rcar-vin e6ef3000.video: Device registered as video3"}
- {"dt": "2023-03-09T06:14:38.226363", "lvl": "target", "msg": "[    3.619211] rcar-vin e6ef4000.video: Device registered as video4"}
- {"dt": "2023-03-09T06:14:38.228223", "lvl": "target", "msg": "[    3.625277] rcar-vin e6ef5000.video: Device registered as video5"}
- {"dt": "2023-03-09T06:14:38.279080", "lvl": "target", "msg": "[    3.631332] rcar-vin e6ef6000.video: Device registered as video6"}
- {"dt": "2023-03-09T06:14:38.280693", "lvl": "target", "msg": "[    3.637375] rcar-vin e6ef7000.video: Device registered as video7"}
- {"dt": "2023-03-09T06:14:38.281749", "lvl": "target", "msg": "[    3.645907] rcar_gen3_thermal e6198000.thermal: is using pseudo fixed FUSE values"}
- {"dt": "2023-03-09T06:14:38.282741", "lvl": "target", "msg": "[    3.658011] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points"}
- {"dt": "2023-03-09T06:14:38.283782", "lvl": "target", "msg": "[    3.669243] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points"}
- {"dt": "2023-03-09T06:14:38.284929", "lvl": "target", "msg": "[    3.680380] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 3 trip points"}
- {"dt": "2023-03-09T06:14:38.324910", "lvl": "target", "msg": "[    3.692199] sdhci: Secure Digital Host Controller Interface driver"}
- {"dt": "2023-03-09T06:14:38.326015", "lvl": "target", "msg": "[    3.698388] sdhci: Copyright(c) Pierre Ossman"}
- {"dt": "2023-03-09T06:14:38.328323", "lvl": "target", "msg": "[    3.703535] Synopsys Designware Multimedia Card Interface Driver"}
- {"dt": "2023-03-09T06:14:38.329851", "lvl": "target", "msg": "[    3.703818] renesas_sdhi_internal_dmac ee140000.mmc: Adding to iommu group 0"}
- {"dt": "2023-03-09T06:14:38.331264", "lvl": "target", "msg": "[    3.710827] sdhci-pltfm: SDHCI platform and OF driver helper"}
- {"dt": "2023-03-09T06:14:38.332596", "lvl": "target", "msg": "[    3.716575] renesas_sdhi_internal_dmac ee140000.mmc: Using IPMMU context 1"}
- {"dt": "2023-03-09T06:14:38.333919", "lvl": "target", "msg": "[    3.729160] ledtrig-cpu: registered to indicate activity on CPUs"}
- {"dt": "2023-03-09T06:14:38.335539", "lvl": "target", "msg": "[    3.736674] usbcore: registered new interface driver usbhid"}
- {"dt": "2023-03-09T06:14:38.343150", "lvl": "target", "msg": "[    3.742230] usbhid: USB HID core driver"}
- {"dt": "2023-03-09T06:14:38.386406", "lvl": "target", "msg": "[    3.779792] renesas_sdhi_internal_dmac ee140000.mmc: mmc0 base at 0x00000000ee140000, max clock rate 200 MHz"}
- {"dt": "2023-03-09T06:14:38.491261", "lvl": "target", "msg": "[    3.860399] mmc0: new HS400 MMC card at address 0001"}
- {"dt": "2023-03-09T06:14:38.492700", "lvl": "target", "msg": "[    3.866035] mmcblk0: mmc0:0001 CJUD4R 59.6 GiB "}
- {"dt": "2023-03-09T06:14:38.495505", "lvl": "target", "msg": "[    3.870940] mmcblk0boot0: mmc0:0001 CJUD4R partition 1 31.9 MiB"}
- {"dt": "2023-03-09T06:14:38.497124", "lvl": "target", "msg": "[    3.877157] st_lsm6dsx_i2c 3-006a: mounting matrix not found: using identity..."}
- {"dt": "2023-03-09T06:14:38.498637", "lvl": "target", "msg": "[    3.877232] mmcblk0boot1: mmc0:0001 CJUD4R partition 2 31.9 MiB"}
- {"dt": "2023-03-09T06:14:38.500241", "lvl": "target", "msg": "[    3.887038] optee: probing for conduit method."}
- {"dt": "2023-03-09T06:14:38.501754", "lvl": "target", "msg": "[    3.890505] mmcblk0rpmb: mmc0:0001 CJUD4R partition 3 4.00 MiB, chardev (508:0)"}
- {"dt": "2023-03-09T06:14:38.503571", "lvl": "target", "msg": "[    3.894763] optee: revision 3.1 (19fb6dcb)"}
- {"dt": "2023-03-09T06:14:38.518009", "lvl": "target", "msg": "[    3.902539] optee driver R-Car Rev.1.0.11"}
- {"dt": "2023-03-09T06:14:38.518662", "lvl": "target", "msg": "[    3.910976] optee: initialized driver"}
- {"dt": "2023-03-09T06:14:38.520304", "lvl": "target", "msg": "[    3.914696]  mmcblk0: p1 p2"}
- {"dt": "2023-03-09T06:14:38.522267", "lvl": "target", "msg": "[    3.916248] usbcore: registered new interface driver snd-usb-audio"}
- {"dt": "2023-03-09T06:14:38.556120", "lvl": "target", "msg": "[    3.934035] rcar_sound ec500000.sound: probed"}
- {"dt": "2023-03-09T06:14:38.558146", "lvl": "target", "msg": "[    3.938953] NET: Registered protocol family 17"}
- {"dt": "2023-03-09T06:14:38.559363", "lvl": "target", "msg": "[    3.943514] 9pnet: Installing 9P2000 support"}
- {"dt": "2023-03-09T06:14:38.560442", "lvl": "target", "msg": "[    3.947828] Key type dns_resolver registered"}
- {"dt": "2023-03-09T06:14:38.561518", "lvl": "target", "msg": "[    3.952452] registered taskstats version 1"}
- {"dt": "2023-03-09T06:14:38.563353", "lvl": "target", "msg": "[    3.956561] Loading compiled-in X.509 certificates"}
- {"dt": "2023-03-09T06:14:38.608953", "lvl": "target", "msg": "[    3.973032] thermal emergency: set temperature to 110 celsius"}
- {"dt": "2023-03-09T06:14:38.611182", "lvl": "target", "msg": "[    3.978940] thermal emergency: shutdown target cpus 1-7"}
- {"dt": "2023-03-09T06:14:38.612343", "lvl": "target", "msg": "[    3.984140] thermal emergency: freq scaled target cpus 0"}
- {"dt": "2023-03-09T06:14:38.613400", "lvl": "target", "msg": "[    3.989784] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs"}
- {"dt": "2023-03-09T06:14:38.614463", "lvl": "target", "msg": "[    4.000970] [drm] Initialized rcar-du 1.0.0 20130110 for feb00000.display on minor 0"}
- {"dt": "2023-03-09T06:14:38.615798", "lvl": "target", "msg": "[    4.008682] [drm] Device feb00000.display probed"}
- {"dt": "2023-03-09T06:14:38.981393", "lvl": "target", "msg": "[    4.338672] Console: switching to colour frame buffer device 240x67"}
- {"dt": "2023-03-09T06:14:38.983128", "lvl": "target", "msg": "[    4.361094] rcar-du feb00000.display: [drm] fb0: rcar-dudrmfb frame buffer device"}
- {"dt": "2023-03-09T06:14:38.984651", "lvl": "target", "msg": "[    4.373959] cpu cpu0: EM: created perf domain"}
- {"dt": "2023-03-09T06:14:38.986424", "lvl": "target", "msg": "[    4.378727] cpu cpu4: EM: created perf domain"}
- {"dt": "2023-03-09T06:14:39.041522", "lvl": "target", "msg": "[    4.434633] renesas_sdhi_internal_dmac ee100000.mmc: mmc1 base at 0x00000000ee100000, max clock rate 200 MHz"}
- {"dt": "2023-03-09T06:14:39.117394", "lvl": "target", "msg": "[    4.510843] renesas_sdhi_internal_dmac ee160000.mmc: mmc2 base at 0x00000000ee160000, max clock rate 50 MHz"}
- {"dt": "2023-03-09T06:14:39.213949", "lvl": "target", "msg": "[    4.583625] Micrel KSZ9031 Gigabit PHY e6800000.ethernet-ffffffff:00: *-skew-ps values should be used only with phy-mode = \\\"rgmii\\\""}
- {"dt": "2023-03-09T06:14:39.217623", "lvl": "target", "msg": "[    4.599462] 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": "2023-03-09T06:14:45.828289", "lvl": "target", "msg": "[   11.219348] ravb e6800000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off"}
- {"dt": "2023-03-09T06:14:48.558210", "lvl": "target", "msg": "[   11.248921] Sending DHCP requests .., OK"}
- {"dt": "2023-03-09T06:14:48.560805", "lvl": "target", "msg": "[   13.932842] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.11"}
- {"dt": "2023-03-09T06:14:48.562251", "lvl": "target", "msg": "[   13.940644] IP-Config: Complete:"}
- {"dt": "2023-03-09T06:14:48.563655", "lvl": "target", "msg": "[   13.943858]      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": "2023-03-09T06:14:48.564988", "lvl": "target", "msg": "[   13.954071]      host=192.168.66.11, domain=, nis-domain=(none)"}
- {"dt": "2023-03-09T06:14:48.566325", "lvl": "target", "msg": "[   13.959961]      bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath="}
- {"dt": "2023-03-09T06:14:48.567955", "lvl": "target", "msg": "[   13.959965]      nameserver0=10.1.1.1"}
- {"dt": "2023-03-09T06:14:48.579117", "lvl": "target", "msg": "[   13.970177] ALSA device list:"}
- {"dt": "2023-03-09T06:14:48.579766", "lvl": "target", "msg": "[   13.973143]   #0: ak4613"}
- {"dt": "2023-03-09T06:14:48.582841", "lvl": "target", "msg": "[   13.975889] Waiting 7 sec before mounting root device..."}
- {"dt": "2023-03-09T06:14:55.811207", "lvl": "target", "msg": "[   21.213150] RAMDISK: gzip image found at block 0"}
- {"dt": "2023-03-09T06:14:55.956124", "lvl": "target", "msg": "[   21.318444] EXT4-fs (ram0): mounted filesystem with ordered data mode. Opts: (null)"}
- {"dt": "2023-03-09T06:14:55.957908", "lvl": "target", "msg": "[   21.326144] VFS: Mounted root (ext4 filesystem) on device 1:0."}
- {"dt": "2023-03-09T06:14:55.959674", "lvl": "target", "msg": "[   21.332059] devtmpfs: mounted"}
- {"dt": "2023-03-09T06:14:55.961296", "lvl": "target", "msg": "[   21.338435] Freeing unused kernel memory: 7360K"}
- {"dt": "2023-03-09T06:14:55.963249", "lvl": "target", "msg": "[   21.349122] Run /sbin/init as init process"}
- {"dt": "2023-03-09T06:14:55.997388", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-09T06:14:56.001088", "lvl": "target", "msg": "         ___   ________                _   __     __  __                __"}
- {"dt": "2023-03-09T06:14:56.003050", "lvl": "target", "msg": "        /   | / ____/ /               / | / /__  / /_/ /_  ____  ____  / /_"}
- {"dt": "2023-03-09T06:14:56.004923", "lvl": "target", "msg": "       / /| |/ / __/ /      ______   /  |/ / _ \\/ __/ __ \\/ __ \\/ __ \\/ __/"}
- {"dt": "2023-03-09T06:14:56.006709", "lvl": "target", "msg": "      / ___ / /_/ / /___   /_____/  / /|  /  __/ /_/ /_/ / /_/ / /_/ / /_"}
- {"dt": "2023-03-09T06:14:56.008571", "lvl": "target", "msg": "     /_/  |_\\____/_____/           /_/ |_/\\___/\\__/_.___/\\____/\\____/\\__/"}
- {"dt": "2023-03-09T06:14:56.041827", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-09T06:14:56.044552", "lvl": "target", "msg": "/sbin/init[1]: starting initrd script"}
- {"dt": "2023-03-09T06:14:56.045797", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: proc /proc"}
- {"dt": "2023-03-09T06:14:56.047086", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: sysfs /sys"}
- {"dt": "2023-03-09T06:14:56.048337", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devtmpfs /dev"}
- {"dt": "2023-03-09T06:14:56.049532", "lvl": "target", "msg": "/sbin/init[1]: /dev (devtmpfs) already mounted"}
- {"dt": "2023-03-09T06:14:56.050721", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: devpts /dev/pts"}
- {"dt": "2023-03-09T06:14:56.051594", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /dev/shm"}
- {"dt": "2023-03-09T06:14:56.052126", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /tmp"}
- {"dt": "2023-03-09T06:14:56.052632", "lvl": "target", "msg": "/sbin/init[1]: mounting FS: tmpfs /run"}
- {"dt": "2023-03-09T06:14:56.053251", "lvl": "target", "msg": "/sbin/init[1]: NBD parameters: device /dev/nbd0, server 192.168.66.1:61957"}
- {"dt": "2023-03-09T06:14:56.059604", "lvl": "target", "msg": "/sbin/init[1]: Starting NBD client"}
- {"dt": "2023-03-09T06:14:56.091073", "lvl": "target", "msg": "/sbin/init[1]: NBD client successfully started"}
- {"dt": "2023-03-09T06:14:56.132765", "lvl": "target", "msg": "[   21.512842] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null)"}
- {"dt": "2023-03-09T06:14:56.133939", "lvl": "target", "msg": "/sbin/init[1]: Moving /dev to new rootfs"}
- {"dt": "2023-03-09T06:14:56.136562", "lvl": "target", "msg": "/sbin/init[1]: Moving /proc to new rootfs"}
- {"dt": "2023-03-09T06:14:56.138027", "lvl": "target", "msg": "/sbin/init[1]: Moving /sys to new rootfs"}
- {"dt": "2023-03-09T06:14:56.153465", "lvl": "target", "msg": "/sbin/init[1]: Moving /tmp to new rootfs"}
- {"dt": "2023-03-09T06:14:56.156816", "lvl": "target", "msg": "/sbin/init[1]: Moving /run to new rootfs"}
- {"dt": "2023-03-09T06:14:56.157910", "lvl": "target", "msg": "/sbin/init[1]: Switching to new rootfs"}
- {"dt": "2023-03-09T06:14:56.548888", "lvl": "target", "msg": "/sbin/init[1]: find_active_interface: first active interface is eth0"}
- {"dt": "2023-03-09T06:14:56.552630", "lvl": "target", "msg": "/sbin/init[1]: Adjusting Connman command line. Will be: 'connmand -r -n -I eth0'"}
- {"dt": "2023-03-09T06:14:56.613879", "lvl": "target", "msg": "/sbin/init[1]: Unmounting /tmp and /run"}
- {"dt": "2023-03-09T06:14:56.694111", "lvl": "target", "msg": "[   22.087989] random: crng init done"}
- {"dt": "2023-03-09T06:14:56.741194", "lvl": "target", "msg": "/sbin/init[1]: Exec'ing systemd"}
- {"dt": "2023-03-09T06:14:56.744801", "lvl": "target", "msg": "________________________________________________________________________________"}
- {"dt": "2023-03-09T06:14:56.746045", "lvl": "target", "msg": "      ___   ________       ____              __  _"}
- {"dt": "2023-03-09T06:14:56.747614", "lvl": "target", "msg": "     /   | / ____/ /      / __ )____  ____  / /_(_)___  ____ _"}
- {"dt": "2023-03-09T06:14:56.748882", "lvl": "target", "msg": "    / /| |/ / __/ /      / __  / __ \\/ __ \\/ __/ / __ \\/ __ `/"}
- {"dt": "2023-03-09T06:14:56.750443", "lvl": "target", "msg": "   / ___ / /_/ / /___   / /_/ / /_/ / /_/ / /_/ / / / / /_/ /   _     _     _"}
- {"dt": "2023-03-09T06:14:56.751763", "lvl": "target", "msg": "  /_/  |_\\____/_____/  /_____/\\____/\\____/\\__/_/_/ /_/\\__, /   (_)   (_)   (_)"}
- {"dt": "2023-03-09T06:14:56.758223", "lvl": "target", "msg": "_____________________________________________________/____/_____________________"}
- {"dt": "2023-03-09T06:14:57.531757", "lvl": "target", "msg": "[   22.886973] SELinux:  policy capability network_peer_controls=1"}
- {"dt": "2023-03-09T06:14:57.533786", "lvl": "target", "msg": "[   22.892905] SELinux:  policy capability open_perms=1"}
- {"dt": "2023-03-09T06:14:57.535476", "lvl": "target", "msg": "[   22.897854] SELinux:  policy capability extended_socket_class=1"}
- {"dt": "2023-03-09T06:14:57.536395", "lvl": "target", "msg": "[   22.903743] SELinux:  policy capability always_check_network=0"}
- {"dt": "2023-03-09T06:14:57.536950", "lvl": "target", "msg": "[   22.909550] SELinux:  policy capability cgroup_seclabel=1"}
- {"dt": "2023-03-09T06:14:57.537594", "lvl": "target", "msg": "[   22.914921] SELinux:  policy capability nnp_nosuid_transition=1"}
- {"dt": "2023-03-09T06:14:57.538149", "lvl": "target", "msg": "[   22.920810] SELinux:  policy capability genfs_seclabel_symlinks=0"}
- {"dt": "2023-03-09T06:14:57.607326", "lvl": "target", "msg": "[   22.993109] audit: type=1403 audit(22.991:2): auid=4294967295 ses=4294967295 lsm=selinux res=1"}
- {"dt": "2023-03-09T06:14:57.634107", "lvl": "target", "msg": "[   23.020247] systemd[1]: Successfully loaded SELinux policy in 444.333ms."}
- {"dt": "2023-03-09T06:14:57.797958", "lvl": "target", "msg": "[   23.184361] systemd[1]: System time before build time, advancing clock."}
- {"dt": "2023-03-09T06:14:57.990138", "lvl": "target", "msg": "[   23.355952] audit: type=1400 audit(1675100000.167:3): avc:  denied  { confidentiality } for  pid=1 comm=\\\"systemd\\\" lockdown_reason=\\\"use of tracefs\\\" scontext=system_u:system_r:init_t:s0 tcontext=system_u:system_r:init_t:s0 tclass=lockdown permissive=1"}
- {"dt": "2023-03-09T06:14:57.991679", "lvl": "target", "msg": "[   23.356381] NET: Registered protocol family 10"}
- {"dt": "2023-03-09T06:14:57.994248", "lvl": "target", "msg": "[   23.383366] Segment Routing with IPv6"}
- {"dt": "2023-03-09T06:14:58.101127", "lvl": "target", "msg": "[   23.486637] systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 32.910ms."}
- {"dt": "2023-03-09T06:14:58.195302", "lvl": "target", "msg": "[   23.551868] systemd[1]: systemd 250.5+ running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA -SMACK +SECCOMP -GCRYPT -GNUTLS -OPENSSL +ACL +BLKID -CURL -ELFUTILS -FIDO2 -IDN2 -IDN +IPTC +KMOD -LIBCRYPTSETUP +LIBFDISK -PCRE2 -PWQUALITY -P11KIT -QRENCODE -BZIP2 -LZ4 -XZ -ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=hybrid)"}
- {"dt": "2023-03-09T06:14:58.198968", "lvl": "target", "msg": "[   23.583541] systemd[1]: Detected architecture arm64."}
- {"dt": "2023-03-09T06:14:58.227190", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:14:58.249879", "lvl": "target", "msg": "Welcome to Automotive Grade Linux 15.90.0+snapshot-65dafea22018052fe7b2e17e6e4d7eb754224d38 (pike)!"}
- {"dt": "2023-03-09T06:14:58.255294", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:14:58.263624", "lvl": "target", "msg": "[   23.654634] systemd[1]: Hostname set to <h3ulcb>."}
- {"dt": "2023-03-09T06:14:58.295924", "lvl": "target", "msg": "[   23.682136] systemd[1]: Initializing machine ID from random generator."}
- {"dt": "2023-03-09T06:14:58.853858", "lvl": "target", "msg": "[   24.243085] systemd[1]: Queued start job for default target Graphical Interface."}
- {"dt": "2023-03-09T06:14:58.983507", "lvl": "target", "msg": "[   24.310096] systemd[1]: Created slice Slice /system/agl-session."}
- {"dt": "2023-03-09T06:14:58.984826", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/agl-session."}
- {"dt": "2023-03-09T06:14:58.986014", "lvl": "target", "msg": "[   24.330703] systemd[1]: Created slice Slice /system/getty."}
- {"dt": "2023-03-09T06:14:58.987122", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/getty."}
- {"dt": "2023-03-09T06:14:58.988278", "lvl": "target", "msg": "[   24.350310] systemd[1]: Created slice Slice /system/modprobe."}
- {"dt": "2023-03-09T06:14:58.989683", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/modprobe."}
- {"dt": "2023-03-09T06:14:58.990957", "lvl": "target", "msg": "[   24.370317] systemd[1]: Created slice Slice /system/serial-getty."}
- {"dt": "2023-03-09T06:14:58.992111", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/serial-getty."}
- {"dt": "2023-03-09T06:14:59.054490", "lvl": "target", "msg": "[   24.390171] systemd[1]: Created slice Slice /system/wireplumber."}
- {"dt": "2023-03-09T06:14:59.056170", "lvl": "target", "msg": "[  OK  ] Created slice Slice /system/wireplumber."}
- {"dt": "2023-03-09T06:14:59.057227", "lvl": "target", "msg": "[   24.409927] systemd[1]: Created slice User and Session Slice."}
- {"dt": "2023-03-09T06:14:59.058185", "lvl": "target", "msg": "[  OK  ] Created slice User and Session Slice."}
- {"dt": "2023-03-09T06:14:59.059204", "lvl": "target", "msg": "[   24.429534] systemd[1]: Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2023-03-09T06:14:59.060198", "lvl": "target", "msg": "[  OK  ] Started Dispatch Password Requests to Console Directory Watch."}
- {"dt": "2023-03-09T06:14:59.061314", "lvl": "target", "msg": "[   24.453355] systemd[1]: Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2023-03-09T06:14:59.071983", "lvl": "target", "msg": "[  OK  ] Started Forward Password Requests to Wall Directory Watch."}
- {"dt": "2023-03-09T06:14:59.124197", "lvl": "target", "msg": "[   24.477102] systemd[1]: Reached target Host and Network Name Lookups."}
- {"dt": "2023-03-09T06:14:59.125696", "lvl": "target", "msg": "[  OK  ] Reached target Host and Network Name Lookups."}
- {"dt": "2023-03-09T06:14:59.128357", "lvl": "target", "msg": "[   24.496983] systemd[1]: Reached target Path Units."}
- {"dt": "2023-03-09T06:14:59.129811", "lvl": "target", "msg": "[  OK  ] Reached target Path Units."}
- {"dt": "2023-03-09T06:14:59.131205", "lvl": "target", "msg": "[   24.512998] systemd[1]: Reached target Remote File Systems."}
- {"dt": "2023-03-09T06:14:59.132543", "lvl": "target", "msg": "[  OK  ] Reached target Remote File Systems."}
- {"dt": "2023-03-09T06:14:59.157896", "lvl": "target", "msg": "[   24.532970] systemd[1]: Reached target Slice Units."}
- {"dt": "2023-03-09T06:14:59.159286", "lvl": "target", "msg": "[  OK  ] Reached target Slice Units."}
- {"dt": "2023-03-09T06:14:59.161752", "lvl": "target", "msg": "[   24.549010] systemd[1]: Reached target Swaps."}
- {"dt": "2023-03-09T06:14:59.163133", "lvl": "target", "msg": "[  OK  ] Reached target Swaps."}
- {"dt": "2023-03-09T06:14:59.221862", "lvl": "target", "msg": "[   24.571683] systemd[1]: Listening on Process Core Dump Socket."}
- {"dt": "2023-03-09T06:14:59.223342", "lvl": "target", "msg": "[  OK  ] Listening on Process Core Dump Socket."}
- {"dt": "2023-03-09T06:14:59.226683", "lvl": "target", "msg": "[   24.589656] systemd[1]: Listening on initctl Compatibility Named Pipe."}
- {"dt": "2023-03-09T06:14:59.228418", "lvl": "target", "msg": "[  OK  ] Listening on initctl Compatibility Named Pipe."}
- {"dt": "2023-03-09T06:14:59.229940", "lvl": "target", "msg": "[   24.610610] systemd[1]: Listening on Journal Audit Socket."}
- {"dt": "2023-03-09T06:14:59.231552", "lvl": "target", "msg": "[  OK  ] Listening on Journal Audit Socket."}
- {"dt": "2023-03-09T06:14:59.262455", "lvl": "target", "msg": "[   24.630879] systemd[1]: Listening on Journal Socket (/dev/log)."}
- {"dt": "2023-03-09T06:14:59.263332", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket (/dev/log)."}
- {"dt": "2023-03-09T06:14:59.265457", "lvl": "target", "msg": "[   24.649947] systemd[1]: Listening on Journal Socket."}
- {"dt": "2023-03-09T06:14:59.266808", "lvl": "target", "msg": "[  OK  ] Listening on Journal Socket."}
- {"dt": "2023-03-09T06:14:59.283329", "lvl": "target", "msg": "[   24.669980] systemd[1]: Listening on Network Service Netlink Socket."}
- {"dt": "2023-03-09T06:14:59.286816", "lvl": "target", "msg": "[  OK  ] Listening on Network Service Netlink Socket."}
- {"dt": "2023-03-09T06:14:59.324585", "lvl": "target", "msg": "[   24.691549] systemd[1]: Listening on udev Control Socket."}
- {"dt": "2023-03-09T06:14:59.326271", "lvl": "target", "msg": "[  OK  ] Listening on udev Control Socket."}
- {"dt": "2023-03-09T06:14:59.329950", "lvl": "target", "msg": "[   24.709894] systemd[1]: Listening on udev Kernel Socket."}
- {"dt": "2023-03-09T06:14:59.331924", "lvl": "target", "msg": "[  OK  ] Listening on udev Kernel Socket."}
- {"dt": "2023-03-09T06:14:59.345310", "lvl": "target", "msg": "[   24.730674] systemd[1]: Listening on User Database Manager Socket."}
- {"dt": "2023-03-09T06:14:59.348875", "lvl": "target", "msg": "[  OK  ] Listening on User Database Manager Socket."}
- {"dt": "2023-03-09T06:14:59.367980", "lvl": "target", "msg": "[   24.757539] systemd[1]: Mounting Huge Pages File System..."}
- {"dt": "2023-03-09T06:14:59.371627", "lvl": "target", "msg": "         Mounting Huge Pages File System..."}
- {"dt": "2023-03-09T06:14:59.398083", "lvl": "target", "msg": "[   24.781022] systemd[1]: Mounting POSIX Message Queue File System..."}
- {"dt": "2023-03-09T06:14:59.399625", "lvl": "target", "msg": "         Mounting POSIX Message Queue File System..."}
- {"dt": "2023-03-09T06:14:59.417888", "lvl": "target", "msg": "[   24.804617] systemd[1]: Mounting Kernel Debug File System..."}
- {"dt": "2023-03-09T06:14:59.421469", "lvl": "target", "msg": "         Mounting Kernel Debug File System..."}
- {"dt": "2023-03-09T06:14:59.443671", "lvl": "target", "msg": "[   24.828835] systemd[1]: Mounting Kernel Trace File System..."}
- {"dt": "2023-03-09T06:14:59.445359", "lvl": "target", "msg": "         Mounting Kernel Trace File System..."}
- {"dt": "2023-03-09T06:14:59.469346", "lvl": "target", "msg": "[   24.854734] systemd[1]: Mounting Temporary Directory /tmp..."}
- {"dt": "2023-03-09T06:14:59.470544", "lvl": "target", "msg": "         Mounting Temporary Directory /tmp..."}
- {"dt": "2023-03-09T06:14:59.494666", "lvl": "target", "msg": "[   24.877071] systemd[1]: Starting Create List of Static Device Nodes..."}
- {"dt": "2023-03-09T06:14:59.497622", "lvl": "target", "msg": "         Starting Create List of Static Device Nodes..."}
- {"dt": "2023-03-09T06:14:59.514669", "lvl": "target", "msg": "[   24.900641] systemd[1]: Starting Load Kernel Module configfs..."}
- {"dt": "2023-03-09T06:14:59.518230", "lvl": "target", "msg": "         Starting Load Kernel Module configfs..."}
- {"dt": "2023-03-09T06:14:59.535684", "lvl": "target", "msg": "[   24.924617] systemd[1]: Starting Load Kernel Module drm..."}
- {"dt": "2023-03-09T06:14:59.539393", "lvl": "target", "msg": "         Starting Load Kernel Module drm..."}
- {"dt": "2023-03-09T06:14:59.563715", "lvl": "target", "msg": "[   24.952512] systemd[1]: Starting Load Kernel Module fuse..."}
- {"dt": "2023-03-09T06:14:59.567203", "lvl": "target", "msg": "         Starting Load Kernel Module fuse..."}
- {"dt": "2023-03-09T06:14:59.596820", "lvl": "target", "msg": "[   24.978740] systemd[1]: Starting Start psplash boot splash screen..."}
- {"dt": "2023-03-09T06:14:59.598096", "lvl": "target", "msg": "         Starting Start psplash boot splash screen..."}
- {"dt": "2023-03-09T06:14:59.600096", "lvl": "target", "msg": "[   24.989330] fuse: init (API version 7.32)"}
- {"dt": "2023-03-09T06:14:59.624620", "lvl": "target", "msg": "[   25.006380] systemd[1]: Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-09T06:14:59.627478", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-09T06:14:59.660840", "lvl": "target", "msg": "[   25.034708] systemd[1]: Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-09T06:14:59.664348", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-09T06:14:59.695999", "lvl": "target", "msg": "[   25.069423] systemd[1]: File System Check on Root Device was skipped because of a failed condition check (ConditionPathIsReadWrite=!/)."}
- {"dt": "2023-03-09T06:14:59.699625", "lvl": "target", "msg": "[   25.086092] systemd[1]: Starting Journal Service..."}
- {"dt": "2023-03-09T06:14:59.700677", "lvl": "target", "msg": "         Starting Journal Service..."}
- {"dt": "2023-03-09T06:14:59.764243", "lvl": "target", "msg": "[   25.150213] systemd[1]: Starting Load Kernel Modules..."}
- {"dt": "2023-03-09T06:14:59.767770", "lvl": "target", "msg": "         Starting Load Kernel Modules..."}
- {"dt": "2023-03-09T06:14:59.797488", "lvl": "target", "msg": "[   25.180240] systemd[1]: Starting Remount Root and Kernel File Systems..."}
- {"dt": "2023-03-09T06:14:59.801249", "lvl": "target", "msg": "         Starting Remount Root and Kernel File Systems..."}
- {"dt": "2023-03-09T06:14:59.822499", "lvl": "target", "msg": "[   25.208416] systemd[1]: Starting Coldplug All udev Devices..."}
- {"dt": "2023-03-09T06:14:59.823864", "lvl": "target", "msg": "         Starting Coldplug All udev Devices..."}
- {"dt": "2023-03-09T06:14:59.865109", "lvl": "target", "msg": "[   25.229022] audit: type=1400 audit(1675100002.039:4): avc:  denied  { read } for  pid=355 comm=\\\"systemd-journal\\\" name=\\\"machine-id\\\" dev=\\\"nbd0\\\" ino=194 scontext=system_u:system_r:syslogd_t:s0 tcontext=system_u:object_r:unlabeled_t:s0 tclass=file permissive=1"}
- {"dt": "2023-03-09T06:14:59.867895", "lvl": "target", "msg": "[   25.229163] EXT4-fs (nbd0): re-mounted. Opts: (null)"}
- {"dt": "2023-03-09T06:14:59.870815", "lvl": "target", "msg": "[   25.242662] systemd[1]: Started Start psplash boot splash screen."}
- {"dt": "2023-03-09T06:14:59.895002", "lvl": "target", "msg": "[   25.251992] audit: type=1400 audit(1675100002.047:5): avc:  denied  { open } for  pid=355 comm=\\\"systemd-journal\\\" path=\\\"/etc/machine-id\\\" dev=\\\"nbd0\\\" ino=194 scontext=system_u:system_r:syslogd_t:s0 tcontext=system_u:object_r:unlabeled_t:s0 tclass=file permissive=1"}
- {"dt": "2023-03-09T06:14:59.898699", "lvl": "target", "msg": "[  OK  ] Started Start psplash boot splash screen."}
- {"dt": "2023-03-09T06:14:59.957192", "lvl": "target", "msg": "[   25.301645] audit: type=1130 audit(1675100002.115:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=psplash-start comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T06:14:59.958241", "lvl": "target", "msg": "[   25.305006] systemd[1]: Mounted Huge Pages File System."}
- {"dt": "2023-03-09T06:14:59.959551", "lvl": "target", "msg": "[  OK  ] Mounted Huge Pages File System."}
- {"dt": "2023-03-09T06:14:59.961722", "lvl": "target", "msg": "[   25.341574] systemd[1]: Mounted POSIX Message Queue File System."}
- {"dt": "2023-03-09T06:14:59.963642", "lvl": "target", "msg": "[  OK  ] Mounted POSIX Message Queue File System."}
- {"dt": "2023-03-09T06:14:59.977483", "lvl": "target", "msg": "[   25.365902] systemd[1]: Started Journal Service."}
- {"dt": "2023-03-09T06:14:59.980896", "lvl": "target", "msg": "[  OK  ] Started Journal Service."}
- {"dt": "2023-03-09T06:15:00.015328", "lvl": "target", "msg": "[   25.385325] audit: type=1130 audit(1675100002.199:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-journald comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T06:15:00.018480", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Debug File System."}
- {"dt": "2023-03-09T06:15:00.032676", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Trace File System."}
- {"dt": "2023-03-09T06:15:00.048763", "lvl": "target", "msg": "[  OK  ] Mounted Temporary Directory /tmp."}
- {"dt": "2023-03-09T06:15:00.064784", "lvl": "target", "msg": "[  OK  ] Finished Create List of Static Device Nodes."}
- {"dt": "2023-03-09T06:15:00.106048", "lvl": "target", "msg": "[   25.477169] audit: type=1130 audit(1675100002.291:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=kmod-static-nodes comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T06:15:00.108448", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module configfs."}
- {"dt": "2023-03-09T06:15:00.152197", "lvl": "target", "msg": "[   25.517360] audit: type=1130 audit(1675100002.331:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T06:15:00.202255", "lvl": "target", "msg": "[   25.539066] audit: type=1131 audit(1675100002.331:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=modprobe@configfs comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T06:15:00.204204", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module drm."}
- {"dt": "2023-03-09T06:15:00.207605", "lvl": "target", "msg": "[   25.573193] audit: type=1130 audit(1675100002.387:11): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=modprobe@drm comm=\\\"systemd\\\" exe=\\\"/usr/lib/systemd/systemd\\\" hostname=? addr=? terminal=? res=success'"}
- {"dt": "2023-03-09T06:15:00.209500", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Module fuse."}
- {"dt": "2023-03-09T06:15:00.220234", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-09T06:15:00.236190", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2023-03-09T06:15:00.252441", "lvl": "target", "msg": "[  OK  ] Finished Load Kernel Modules."}
- {"dt": "2023-03-09T06:15:00.268596", "lvl": "target", "msg": "[  OK  ] Finished Remount Root and Kernel File Systems."}
- {"dt": "2023-03-09T06:15:00.300076", "lvl": "target", "msg": "         Mounting FUSE Control File System..."}
- {"dt": "2023-03-09T06:15:00.316251", "lvl": "target", "msg": "         Mounting Kernel Configuration File System..."}
- {"dt": "2023-03-09T06:15:00.349208", "lvl": "target", "msg": "[  OK  ] Started Start psplash-systemd progress communication helper."}
- {"dt": "2023-03-09T06:15:00.363407", "lvl": "target", "msg": "         Starting Flush Journal to Persistent Storage..."}
- {"dt": "2023-03-09T06:15:00.414239", "lvl": "target", "msg": "         Starting Apply Kernel Variables..."}
- {"dt": "2023-03-09T06:15:00.418216", "lvl": "target", "msg": "[   25.800546] systemd-journald[355]: Received client request to flush runtime journal."}
- {"dt": "2023-03-09T06:15:00.420181", "lvl": "target", "msg": "         Starting Create System Users..."}
- {"dt": "2023-03-09T06:15:00.447714", "lvl": "target", "msg": "[  OK  ] Mounted FUSE Control File System."}
- {"dt": "2023-03-09T06:15:00.463879", "lvl": "target", "msg": "[  OK  ] Mounted Kernel Configuration File System."}
- {"dt": "2023-03-09T06:15:00.479688", "lvl": "target", "msg": "[  OK  ] Finished Flush Journal to Persistent Storage."}
- {"dt": "2023-03-09T06:15:00.511947", "lvl": "target", "msg": "[  OK  ] Finished Apply Kernel Variables."}
- {"dt": "2023-03-09T06:15:00.543857", "lvl": "target", "msg": "[  OK  ] Finished Create System Users."}
- {"dt": "2023-03-09T06:15:00.559719", "lvl": "target", "msg": "         Starting Create Static Device Nodes in /dev..."}
- {"dt": "2023-03-09T06:15:00.607942", "lvl": "target", "msg": "[  OK  ] Finished Coldplug All udev Devices."}
- {"dt": "2023-03-09T06:15:00.648569", "lvl": "target", "msg": "         Starting Wait for udev To Complete Device Initialization..."}
- {"dt": "2023-03-09T06:15:00.663299", "lvl": "target", "msg": "[  OK  ] Finished Create Static Device Nodes in /dev."}
- {"dt": "2023-03-09T06:15:00.679414", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Local File Systems."}
- {"dt": "2023-03-09T06:15:00.695332", "lvl": "target", "msg": "         Mounting /var/volatile..."}
- {"dt": "2023-03-09T06:15:00.733658", "lvl": "target", "msg": "         Starting Rule-based Manager for Device Events and Files..."}
- {"dt": "2023-03-09T06:15:00.747939", "lvl": "target", "msg": "[  OK  ] Mounted /var/volatile."}
- {"dt": "2023-03-09T06:15:00.764002", "lvl": "target", "msg": "         Starting Load/Save Random Seed..."}
- {"dt": "2023-03-09T06:15:00.780123", "lvl": "target", "msg": "[  OK  ] Reached target Local File Systems."}
- {"dt": "2023-03-09T06:15:00.795834", "lvl": "target", "msg": "         Starting Rebuild Dynamic Linker Cache..."}
- {"dt": "2023-03-09T06:15:00.828086", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-09T06:15:00.843950", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-09T06:15:00.860188", "lvl": "target", "msg": "         Starting Create Volatile Files and Directories..."}
- {"dt": "2023-03-09T06:15:00.875813", "lvl": "target", "msg": "[  OK  ] Finished Load/Save Random Seed."}
- {"dt": "2023-03-09T06:15:00.891874", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2023-03-09T06:15:00.907823", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2023-03-09T06:15:00.939799", "lvl": "target", "msg": "         Starting Generated file SELinux relabeling..."}
- {"dt": "2023-03-09T06:15:01.003801", "lvl": "target", "msg": "[  OK  ] Finished Create Volatile Files and Directories."}
- {"dt": "2023-03-09T06:15:01.019518", "lvl": "target", "msg": "         Starting Security Auditing Service..."}
- {"dt": "2023-03-09T06:15:01.035612", "lvl": "target", "msg": "         Starting Rebuild Journal Catalog..."}
- {"dt": "2023-03-09T06:15:01.449425", "lvl": "target", "msg": "[  OK  ] Started Rule-based Manager for Device Events and Files."}
- {"dt": "2023-03-09T06:15:01.465152", "lvl": "target", "msg": "[  OK  ] Finished Generated file SELinux relabeling."}
- {"dt": "2023-03-09T06:15:01.481118", "lvl": "target", "msg": "[  OK  ] Finished Rebuild Journal Catalog."}
- {"dt": "2023-03-09T06:15:01.576086", "lvl": "target", "msg": "[  OK  ] Started Security Auditing Service."}
- {"dt": "2023-03-09T06:15:01.608743", "lvl": "target", "msg": "         Starting Record System Boot/Shutdown in UTMP..."}
- {"dt": "2023-03-09T06:15:01.687998", "lvl": "target", "msg": "[  OK  ] Finished Record System Boot/Shutdown in UTMP."}
- {"dt": "2023-03-09T06:15:01.733534", "lvl": "target", "msg": "[   27.116291] at24 7-0050: supply vcc not found, using dummy regulator"}
- {"dt": "2023-03-09T06:15:01.736862", "lvl": "target", "msg": "[  OK  ] Reached target Hardware activated USB gadget."}
- {"dt": "2023-03-09T06:15:01.761624", "lvl": "target", "msg": "[   27.146934] at24 7-0050: 128 byte br24t01 EEPROM, writable, 8 bytes/write"}
- {"dt": "2023-03-09T06:15:01.824649", "lvl": "target", "msg": "[   27.212566] CAN device driver interface"}
- {"dt": "2023-03-09T06:15:01.942442", "lvl": "target", "msg": "[   27.303623] rcar_canfd e66c0000.can: can_clk rate is 19968000"}
- {"dt": "2023-03-09T06:15:01.943294", "lvl": "target", "msg": "[   27.309746] rcar_canfd e66c0000.can: device registered (channel 0)"}
- {"dt": "2023-03-09T06:15:01.943954", "lvl": "target", "msg": "[   27.320577] rcar_canfd e66c0000.can: can_clk rate is 19968000"}
- {"dt": "2023-03-09T06:15:01.944551", "lvl": "target", "msg": "[   27.326799] rcar_canfd e66c0000.can: device registered (channel 1)"}
- {"dt": "2023-03-09T06:15:01.946238", "lvl": "target", "msg": "[   27.333121] rcar_canfd e66c0000.can: global operational state (clk 0, fdmode 1)"}
- {"dt": "2023-03-09T06:15:01.990215", "lvl": "target", "msg": "[   27.375234] ccree e6601000.crypto: ARM CryptoCell 630P Driver: HW version 0xAF400001/0xDCC63000, Driver version 5.0"}
- {"dt": "2023-03-09T06:15:02.050500", "lvl": "target", "msg": "[   27.435279] ccree e6601000.crypto: ARM ccree device initialized"}
- {"dt": "2023-03-09T06:15:02.145092", "lvl": "target", "msg": "[   27.509241] Bluetooth: Core ver 2.22"}
- {"dt": "2023-03-09T06:15:02.145927", "lvl": "target", "msg": "[   27.513589] NET: Registered protocol family 31"}
- {"dt": "2023-03-09T06:15:02.146611", "lvl": "target", "msg": "[   27.518334] Bluetooth: HCI device and connection manager initialized"}
- {"dt": "2023-03-09T06:15:02.147364", "lvl": "target", "msg": "[   27.525375] Bluetooth: HCI socket layer initialized"}
- {"dt": "2023-03-09T06:15:02.148114", "lvl": "target", "msg": "[   27.530359] Bluetooth: L2CAP socket layer initialized"}
- {"dt": "2023-03-09T06:15:02.149572", "lvl": "target", "msg": "[   27.535549] Bluetooth: SCO socket layer initialized"}
- {"dt": "2023-03-09T06:15:02.205026", "lvl": "target", "msg": "[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch."}
- {"dt": "2023-03-09T06:15:02.264706", "lvl": "target", "msg": "[   27.630318] Bluetooth: HCI UART driver ver 2.3"}
- {"dt": "2023-03-09T06:15:02.266632", "lvl": "target", "msg": "[   27.634761] Bluetooth: HCI UART protocol H4 registered"}
- {"dt": "2023-03-09T06:15:02.267577", "lvl": "target", "msg": "[   27.639897] Bluetooth: HCI UART protocol BCSP registered"}
- {"dt": "2023-03-09T06:15:02.268322", "lvl": "target", "msg": "[   27.642021] usb 9-1: dvb_usb_v2: found a 'Realtek RTL2832U reference design' in warm state"}
- {"dt": "2023-03-09T06:15:02.269023", "lvl": "target", "msg": "[   27.645294] Bluetooth: HCI UART protocol LL registered"}
- {"dt": "2023-03-09T06:15:02.269720", "lvl": "target", "msg": "         Startin[   27.659109] Bluetooth: HCI UART protocol Broadcom registered"}
- {"dt": "2023-03-09T06:15:02.270656", "lvl": "target", "msg": "g Load/Save RF K[   27.665970] Bluetooth: HCI UART protocol QCA registered"}
- {"dt": "2023-03-09T06:15:02.282639", "lvl": "target", "msg": "ill Switch Status..."}
- {"dt": "2023-03-09T06:15:02.345471", "lvl": "target", "msg": "[   27.719403] usb 9-1: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer"}
- {"dt": "2023-03-09T06:15:02.346314", "lvl": "target", "msg": "[   27.728729] dvbdev: DVB: registering new adapter (Realtek RTL2832U reference design)"}
- {"dt": "2023-03-09T06:15:02.348720", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2023-03-09T06:15:02.363089", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-09T06:15:02.417427", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-09T06:15:02.419363", "lvl": "target", "msg": "[   27.791110] i2c i2c-10: Added multiplexed i2c bus 11"}
- {"dt": "2023-03-09T06:15:02.420259", "lvl": "target", "msg": "[   27.796088] rtl2832 10-0010: Realtek RTL2832 successfully attached"}
- {"dt": "2023-03-09T06:15:02.422642", "lvl": "target", "msg": "[   27.804211] usb 9-1: DVB: registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))..."}
- {"dt": "2023-03-09T06:15:02.435995", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-09T06:15:02.451876", "lvl": "target", "msg": "         Starting SELinux init for /dev service loading..."}
- {"dt": "2023-03-09T06:15:02.489139", "lvl": "target", "msg": "[   27.861038] Bluetooth: hci0: Failed to get CTS"}
- {"dt": "2023-03-09T06:15:02.491184", "lvl": "target", "msg": "[  OK  ] Finished SELinu[   27.867114] r820t 11-001a: creating new instance"}
- {"dt": "2023-03-09T06:15:02.492140", "lvl": "target", "msg": "x resolv.conf relabeling."}
- {"dt": "2023-03-09T06:15:02.493180", "lvl": "target", "msg": "[   27.880341] r820t 11-001a: Rafael Micro r820t successfully identified"}
- {"dt": "2023-03-09T06:15:02.549903", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-09T06:15:02.551668", "lvl": "target", "msg": "[   27.909902] rtl2832_sdr rtl2832_sdr.8.auto: Registered as swradio0"}
- {"dt": "2023-03-09T06:15:02.553143", "lvl": "target", "msg": "[   27.916299] rtl2832_sdr rtl2832_sdr.8.auto: Realtek RTL2832 SDR attached"}
- {"dt": "2023-03-09T06:15:02.554620", "lvl": "target", "msg": "[   27.922993] rtl2832_sdr rtl2832_sdr.8.auto: SDR API is still slightly experimental and functionality changes may follow"}
- {"dt": "2023-03-09T06:15:02.556183", "lvl": "target", "msg": "[   27.942227] Registered IR keymap rc-empty"}
- {"dt": "2023-03-09T06:15:02.559049", "lvl": "target", "msg": "[   27.946711] rc rc0: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0"}
- {"dt": "2023-03-09T06:15:02.614525", "lvl": "target", "msg": "[  OK  ] Finishe[   27.956759] input: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0/input0"}
- {"dt": "2023-03-09T06:15:02.615529", "lvl": "target", "msg": "d SELinux init service loading.[   27.969602] usb 9-1: dvb_usb_v2: schedule remote query interval to 200 msecs"}
- {"dt": "2023-03-09T06:15:02.616284", "lvl": "target", "msg": "[   27.987765] usb 9-1: dvb_usb_v2: 'Realtek RTL2832U reference design' successfully initialized and connected"}
- {"dt": "2023-03-09T06:15:02.617824", "lvl": "target", "msg": "[   27.999563] usbcore: registered new interface driver dvb_usb_rtl28xxu"}
- {"dt": "2023-03-09T06:15:02.618833", "lvl": "target", "msg": "         Starting SELinux resolv.conf relabeling..."}
- {"dt": "2023-03-09T06:15:02.632127", "lvl": "target", "msg": "         Starting SELinux autorelabel service loading..."}
- {"dt": "2023-03-09T06:15:02.648634", "lvl": "target", "msg": "         Starting SELinux init service loading..."}
- {"dt": "2023-03-09T06:15:02.681289", "lvl": "target", "msg": "[  OK  ] Finished SELinux init for /dev service loading."}
- {"dt": "2023-03-09T06:15:02.696101", "lvl": "target", "msg": "[  OK  ] Finished SELinux resolv.conf relabeling."}
- {"dt": "2023-03-09T06:15:02.711842", "lvl": "target", "msg": "[  OK  ] Finished SELinux autorelabel service loading."}
- {"dt": "2023-03-09T06:15:02.728168", "lvl": "target", "msg": "[  OK  ] Finished SELinux init service loading."}
- {"dt": "2023-03-09T06:15:02.750416", "lvl": "target", "msg": "[  OK  ] Finished Wait for udev To Complete Device Initialization."}
- {"dt": "2023-03-09T06:15:02.781070", "lvl": "target", "msg": "[  OK  ] Started Hardware RNG Entropy Gatherer Daemon."}
- {"dt": "2023-03-09T06:15:04.865773", "lvl": "target", "msg": "[*     ] A start job is running for Rebuild \u2026namic Linker Cache (2s / no limit)"}
- {"dt": "2023-03-09T06:15:05.366191", "lvl": "target", "msg": "M[K[**    ] A start job is running for Rebuild \u2026namic Linker Cache (2s / no limit)"}
- {"dt": "2023-03-09T06:15:05.866421", "lvl": "target", "msg": "M[K[***   ] A start job is running for Rebuild \u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2023-03-09T06:15:06.367985", "lvl": "target", "msg": "M[K[ ***  ] A start job is running for Rebuild \u2026namic Linker Cache (3s / no limit)"}
- {"dt": "2023-03-09T06:15:06.867210", "lvl": "target", "msg": "M[K[  *** ] A start job is running for Rebuild \u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2023-03-09T06:15:07.325207", "lvl": "target", "msg": "M[K[   ***] A start job is running for Rebuild \u2026namic Linker Cache (4s / no limit)"}
- {"dt": "2023-03-09T06:15:07.387304", "lvl": "target", "msg": "M[K[  OK  ] Finished Rebuild Dynamic Linker Cache."}
- {"dt": "2023-03-09T06:15:07.419370", "lvl": "target", "msg": "[K         Starting Update is Completed..."}
- {"dt": "2023-03-09T06:15:07.467403", "lvl": "target", "msg": "[  OK  ] Finished Update is Completed."}
- {"dt": "2023-03-09T06:15:07.483504", "lvl": "target", "msg": "[  OK  ] Reached target System Initialization."}
- {"dt": "2023-03-09T06:15:07.499391", "lvl": "target", "msg": "[  OK  ] Started Daily Cleanup of Temporary Directories."}
- {"dt": "2023-03-09T06:15:07.515139", "lvl": "target", "msg": "[  OK  ] Reached target Timer Units."}
- {"dt": "2023-03-09T06:15:07.537295", "lvl": "target", "msg": "[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket."}
- {"dt": "2023-03-09T06:15:07.551990", "lvl": "target", "msg": "[  OK  ] Listening on D-Bus System Message Bus Socket."}
- {"dt": "2023-03-09T06:15:07.590295", "lvl": "target", "msg": "[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets."}
- {"dt": "2023-03-09T06:15:07.604951", "lvl": "target", "msg": "[  OK  ] Listening on mpd.socket."}
- {"dt": "2023-03-09T06:15:07.636948", "lvl": "target", "msg": "         Starting PipeWire Multimedia System Socket..."}
- {"dt": "2023-03-09T06:15:07.652934", "lvl": "target", "msg": "         Starting sshd.socket..."}
- {"dt": "2023-03-09T06:15:07.668707", "lvl": "target", "msg": "[  OK  ] Listening on PipeWire Multimedia System Socket."}
- {"dt": "2023-03-09T06:15:07.700842", "lvl": "target", "msg": "[  OK  ] Listening on sshd.socket."}
- {"dt": "2023-03-09T06:15:07.716943", "lvl": "target", "msg": "[  OK  ] Reached target Socket Units."}
- {"dt": "2023-03-09T06:15:07.732834", "lvl": "target", "msg": "[  OK  ] Reached target Basic System."}
- {"dt": "2023-03-09T06:15:07.748957", "lvl": "target", "msg": "         Starting Save/Restore Sound Card State..."}
- {"dt": "2023-03-09T06:15:07.780479", "lvl": "target", "msg": "         Starting Bluetooth service..."}
- {"dt": "2023-03-09T06:15:07.796611", "lvl": "target", "msg": "         Starting CAN interface helper..."}
- {"dt": "2023-03-09T06:15:07.812484", "lvl": "target", "msg": "         Starting D-Bus System Message Bus..."}
- {"dt": "2023-03-09T06:15:07.844086", "lvl": "target", "msg": "         Starting IPv6 Packet Filtering Framework..."}
- {"dt": "2023-03-09T06:15:07.860170", "lvl": "target", "msg": "         Starting IPv4 Packet Filtering Framework..."}
- {"dt": "2023-03-09T06:15:07.892279", "lvl": "target", "msg": "         Starting Telephony service..."}
- {"dt": "2023-03-09T06:15:07.908620", "lvl": "target", "msg": "[  OK  ] Started PipeWire Multimedia Service."}
- {"dt": "2023-03-09T06:15:07.940707", "lvl": "target", "msg": "         Starting User Login Management..."}
- {"dt": "2023-03-09T06:15:07.956745", "lvl": "target", "msg": "         Starting Disk Manager..."}
- {"dt": "2023-03-09T06:15:07.987804", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager."}
- {"dt": "2023-03-09T06:15:08.016346", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (bluetooth)."}
- {"dt": "2023-03-09T06:15:08.037637", "lvl": "target", "msg": "[  OK  ] Started Multimedia Service Session Manager (policy)."}
- {"dt": "2023-03-09T06:15:08.053972", "lvl": "target", "msg": "         Starting OpenSSH Key Generation..."}
- {"dt": "2023-03-09T06:15:08.069759", "lvl": "target", "msg": "[  OK  ] Finished Save/Restore Sound Card State."}
- {"dt": "2023-03-09T06:15:08.086524", "lvl": "target", "msg": "[  OK  ] Finished CAN interface helper."}
- {"dt": "2023-03-09T06:15:08.101888", "lvl": "target", "msg": "[  OK  ] Finished IPv6 Packet Filtering Framework."}
- {"dt": "2023-03-09T06:15:08.118255", "lvl": "target", "msg": "[  OK  ] Finished IPv4 Packet Filtering Framework."}
- {"dt": "2023-03-09T06:15:08.134032", "lvl": "target", "msg": "[  OK  ] Reached target Preparation for Network."}
- {"dt": "2023-03-09T06:15:08.149778", "lvl": "target", "msg": "[  OK  ] Reached target Sound Card."}
- {"dt": "2023-03-09T06:15:08.181281", "lvl": "target", "msg": "         Starting Network Configuration..."}
- {"dt": "2023-03-09T06:15:09.065984", "lvl": "target", "msg": "[   34.446030] cfg80211: Loading compiled-in X.509 certificates for regulatory database"}
- {"dt": "2023-03-09T06:15:09.080472", "lvl": "target", "msg": "[   34.464966] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"}
- {"dt": "2023-03-09T06:15:09.355686", "lvl": "target", "msg": "[  OK  ] Started D-Bus System Message Bus."}
- {"dt": "2023-03-09T06:15:09.358707", "lvl": "target", "msg": "[   34.705582] rcar_canfd e66c0000.can can1: bitrate error 0.1%"}
- {"dt": "2023-03-09T06:15:09.360068", "lvl": "target", "msg": "[   34.713809] rcar_canfd e66c0000.can can0: bitrate error 0.1%"}
- {"dt": "2023-03-09T06:15:09.361283", "lvl": "target", "msg": "[   34.723066] rcar_canfd e66c0000.can can1: incorrect/missing data bit-timing"}
- {"dt": "2023-03-09T06:15:09.362472", "lvl": "target", "msg": "[   34.730095] rcar_canfd e66c0000.can can1: open_candev() failed, error -22"}
- {"dt": "2023-03-09T06:15:09.363692", "lvl": "target", "msg": "[   34.737933] rcar_canfd e66c0000.can can0: incorrect/missing data bit-timing"}
- {"dt": "2023-03-09T06:15:09.365086", "lvl": "target", "msg": "[   34.745072] rcar_canfd e66c0000.can can0: open_candev() failed, error -22"}
- {"dt": "2023-03-09T06:15:09.373524", "lvl": "target", "msg": "[  OK  ] Started Network Configuration."}
- {"dt": "2023-03-09T06:15:09.405570", "lvl": "target", "msg": "         Starting Connection service..."}
- {"dt": "2023-03-09T06:15:09.438238", "lvl": "target", "msg": "[  OK  ] Started User Login Management."}
- {"dt": "2023-03-09T06:15:09.453941", "lvl": "target", "msg": "[  OK  ] Started Bluetooth service."}
- {"dt": "2023-03-09T06:15:09.469529", "lvl": "target", "msg": "[  OK  ] Started Telephony service."}
- {"dt": "2023-03-09T06:15:09.486135", "lvl": "target", "msg": "[  OK  ] Reached target Bluetooth Support."}
- {"dt": "2023-03-09T06:15:09.574436", "lvl": "target", "msg": "[   34.954008] Bluetooth: BNEP (Ethernet Emulation) ver 1.3"}
- {"dt": "2023-03-09T06:15:09.576125", "lvl": "target", "msg": "[   34.959405] Bluetooth: BNEP filters: protocol multicast"}
- {"dt": "2023-03-09T06:15:09.578239", "lvl": "target", "msg": "[   34.964724] Bluetooth: BNEP socket layer initialized"}
- {"dt": "2023-03-09T06:15:09.656535", "lvl": "target", "msg": "         Starting Authorization Manager..."}
- {"dt": "2023-03-09T06:15:09.688202", "lvl": "target", "msg": "         Starting Hostname Service..."}
- {"dt": "2023-03-09T06:15:09.880633", "lvl": "target", "msg": "[  OK  ] Started Connection service."}
- {"dt": "2023-03-09T06:15:09.895541", "lvl": "target", "msg": "[  OK  ] Reached target Network."}
- {"dt": "2023-03-09T06:15:09.927818", "lvl": "target", "msg": "[  OK  ] Started agl-service-radio.service."}
- {"dt": "2023-03-09T06:15:09.959549", "lvl": "target", "msg": "[  OK  ] Started applaunchd.service."}
- {"dt": "2023-03-09T06:15:09.991604", "lvl": "target", "msg": "         Starting Avahi mDNS/DNS-SD Stack..."}
- {"dt": "2023-03-09T06:15:10.055303", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val VIS server."}
- {"dt": "2023-03-09T06:15:10.087673", "lvl": "target", "msg": "[  OK  ] Started agl-service-audiomixer.service."}
- {"dt": "2023-03-09T06:15:10.135195", "lvl": "target", "msg": "[  OK  ] Started agl-service-hvac.service."}
- {"dt": "2023-03-09T06:15:10.199577", "lvl": "target", "msg": "[  OK  ] Started Eclipse KUKSA.val DBC feeder."}
- {"dt": "2023-03-09T06:15:10.215058", "lvl": "target", "msg": "         Starting Permit User Sessions..."}
- {"dt": "2023-03-09T06:15:10.231473", "lvl": "target", "msg": "[  OK  ] Started Authorization Manager."}
- {"dt": "2023-03-09T06:15:10.247069", "lvl": "target", "msg": "[  OK  ] Started Hostname Service."}
- {"dt": "2023-03-09T06:15:10.263353", "lvl": "target", "msg": "[  OK  ] Started Avahi mDNS/DNS-SD Stack."}
- {"dt": "2023-03-09T06:15:10.295314", "lvl": "target", "msg": "[  OK  ] Finished Permit User Sessions."}
- {"dt": "2023-03-09T06:15:10.327184", "lvl": "target", "msg": "[  OK  ] Started AGL user session."}
- {"dt": "2023-03-09T06:15:10.375095", "lvl": "target", "msg": "[  OK  ] Started Getty on tty1."}
- {"dt": "2023-03-09T06:15:10.422753", "lvl": "target", "msg": "[  OK  ] Started Serial Getty on ttySC0."}
- {"dt": "2023-03-09T06:15:10.438791", "lvl": "target", "msg": "[  OK  ] Reached target Login Prompts."}
- {"dt": "2023-03-09T06:15:11.013428", "lvl": "target", "msg": "[  OK  ] Started Disk Manager."}
- {"dt": "2023-03-09T06:15:11.029200", "lvl": "target", "msg": "[  OK  ] Started Automount Disk Manager."}
- {"dt": "2023-03-09T06:15:11.045546", "lvl": "target", "msg": "[  OK  ] Reached target Multi-User System."}
- {"dt": "2023-03-09T06:15:11.061508", "lvl": "target", "msg": "[  OK  ] Reached target Graphical Interface."}
- {"dt": "2023-03-09T06:15:11.093569", "lvl": "target", "msg": "         Starting Record Runlevel Change in UTMP..."}
- {"dt": "2023-03-09T06:15:11.205416", "lvl": "target", "msg": "         Starting WPA supplicant..."}
- {"dt": "2023-03-09T06:15:11.269157", "lvl": "target", "msg": "[  OK  ] Finished Record Runlevel Change in UTMP."}
- {"dt": "2023-03-09T06:15:11.285151", "lvl": "target", "msg": "[   36.683040] can: controller area network core"}
- {"dt": "2023-03-09T06:15:11.300965", "lvl": "target", "msg": "[   36.687592] NET: Registered protocol family 29"}
- {"dt": "2023-03-09T06:15:11.412879", "lvl": "target", "msg": "[   36.803179] can: raw protocol"}
- {"dt": "2023-03-09T06:15:12.019622", "lvl": "target", "msg": "[  OK  ] Started WPA supplicant."}
- {"dt": "2023-03-09T06:15:12.131220", "lvl": "target", "msg": "[   37.526403] r820t 11-001a: destroying instance"}
- {"dt": "2023-03-09T06:15:12.160441", "lvl": "target", "msg": "[   37.540789] dvb_usb_v2: 'Realtek RTL2832U reference design:9-1' successfully deinitialized and disconnected"}
- {"dt": "2023-03-09T06:15:12.271012", "lvl": "target", "msg": "         Starting Load/Save RF Kill Switch Status..."}
- {"dt": "2023-03-09T06:15:12.318744", "lvl": "target", "msg": "         Starting User Database Manager..."}
- {"dt": "2023-03-09T06:15:12.334753", "lvl": "target", "msg": "[  OK  ] Started Load/Save RF Kill Switch Status."}
- {"dt": "2023-03-09T06:15:12.622131", "lvl": "target", "msg": "[  OK  ] Started User Database Manager."}
- {"dt": "2023-03-09T06:15:12.712257", "lvl": "target", "msg": "[   38.092741] usb 9-1: dvb_usb_v2: found a 'Realtek RTL2832U reference design' in warm state"}
- {"dt": "2023-03-09T06:15:12.815692", "lvl": "target", "msg": "[   38.191148] usb 9-1: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer"}
- {"dt": "2023-03-09T06:15:12.818570", "lvl": "target", "msg": "[   38.200598] dvbdev: DVB: registering new adapter (Realtek RTL2832U reference design)"}
- {"dt": "2023-03-09T06:15:12.870049", "lvl": "target", "msg": "[   38.230584] i2c i2c-10: Added multiplexed i2c bus 11"}
- {"dt": "2023-03-09T06:15:12.872381", "lvl": "target", "msg": "[   38.235975] rtl2832 10-0010: Realtek RTL2832 successfully attached"}
- {"dt": "2023-03-09T06:15:12.873541", "lvl": "target", "msg": "[   38.248160] usb 9-1: DVB: registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))..."}
- {"dt": "2023-03-09T06:15:12.874806", "lvl": "target", "msg": "[   38.258435] r820t 11-001a: creating new instance"}
- {"dt": "2023-03-09T06:15:12.897382", "lvl": "target", "msg": "[   38.277895] r820t 11-001a: Rafael Micro r820t successfully identified"}
- {"dt": "2023-03-09T06:15:12.957505", "lvl": "target", "msg": "[   38.294879] rtl2832_sdr rtl2832_sdr.8.auto: Registered as swradio0"}
- {"dt": "2023-03-09T06:15:12.961079", "lvl": "target", "msg": "[   38.301386] rtl2832_sdr rtl2832_sdr.8.auto: Realtek RTL2832 SDR attached"}
- {"dt": "2023-03-09T06:15:12.962999", "lvl": "target", "msg": "[   38.311578] rtl2832_sdr rtl2832_sdr.8.auto: SDR API is still slightly experimental and functionality changes may follow"}
- {"dt": "2023-03-09T06:15:12.964840", "lvl": "target", "msg": "[  OK  ] Created slice User Slice of UID 1001."}
- {"dt": "2023-03-09T06:15:12.966561", "lvl": "target", "msg": "[   38.340119] Registered IR keymap rc-empty"}
- {"dt": "2023-03-09T06:15:12.968368", "lvl": "target", "msg": "[   38.344696] rc rc0: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0"}
- {"dt": "2023-03-09T06:15:12.979785", "lvl": "target", "msg": "[   38.356622] input: Realtek RTL2832U reference design as /devices/platform/soc/ee000000.usb/usb9/9-1/rc/rc0/input1"}
- {"dt": "2023-03-09T06:15:12.983658", "lvl": "target", "msg": "         Starting User Runtime Directory /run/user/1001..."}
- {"dt": "2023-03-09T06:15:13.013284", "lvl": "target", "msg": "[   38.393147] usb 9-1: dvb_usb_v2: schedule remote query interval to 200 msecs"}
- {"dt": "2023-03-09T06:15:13.043348", "lvl": "target", "msg": "[   38.423318] usb 9-1: dvb_usb_v2: 'Realtek RTL2832U reference design' successfully initialized and connected"}
- {"dt": "2023-03-09T06:15:13.122199", "lvl": "target", "msg": "[  OK  ] Finished User Runtime Directory /run/user/1001."}
- {"dt": "2023-03-09T06:15:13.154218", "lvl": "target", "msg": "         Starting User Manager for UID 1001..."}
- {"dt": "2023-03-09T06:15:13.313414", "lvl": "target", "msg": "[   38.710274] r820t 11-001a: destroying instance"}
- {"dt": "2023-03-09T06:15:13.336717", "lvl": "target", "msg": "[   38.716124] dvb_usb_v2: 'Realtek RTL2832U reference design:9-1' successfully deinitialized and disconnected"}
- {"dt": "2023-03-09T06:15:13.894330", "lvl": "target", "msg": "[  OK  ] Started User Manager for UID 1001."}
- {"dt": "2023-03-09T06:15:13.910170", "lvl": "target", "msg": "[  OK  ] Started Session c1 of User agl-driver."}
- {"dt": "2023-03-09T06:15:16.450872", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:15:16.454607", "lvl": "target", "msg": "Automotive Grade Linux 15.90.0+snapshot-65dafea22018052fe7b2e17e6e4d7eb754224d38 h3ulcb ttySC0"}
- {"dt": "2023-03-09T06:15:16.455986", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:15:16.458249", "lvl": "debug", "msg": "Matched prompt #13: login:"}
- {"dt": "2023-03-09T06:15:16.459209", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "41.02", "extra": {"extra": []}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:15:16.460616", "lvl": "debug", "msg": "Sending username root"}
- {"dt": "2023-03-09T06:15:16.461312", "lvl": "input", "msg": "root\n"}
- {"dt": "2023-03-09T06:15:16.563080", "lvl": "target", "msg": "h3ulcb login: root"}
- {"dt": "2023-03-09T06:15:16.565187", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-09T06:15:16.567466", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #', 'Login incorrect', 'Login timed out'] (timeout 00:18:40)"}
- {"dt": "2023-03-09T06:15:16.580500", "lvl": "target", "msg": "root"}
- {"dt": "2023-03-09T06:15:18.626518", "lvl": "target", "msg": "7[r[999;999H[6n[   43.996971] SDHI0 Vcc: disabling"}
- {"dt": "2023-03-09T06:15:18.628313", "lvl": "target", "msg": "[   44.000196] SDHI3 Vcc: disabling"}
- {"dt": "2023-03-09T06:15:18.629798", "lvl": "target", "msg": "[   44.003433] wlan-en-regulator: disabling"}
- {"dt": "2023-03-09T06:15:18.631997", "lvl": "target", "msg": "[   44.007353] SDHI0 VccQ: disabling"}
- {"dt": "2023-03-09T06:15:18.632950", "lvl": "target", "msg": "[   44.010665] SDHI3 VccQ: disabling"}
- {"dt": "2023-03-09T06:15:20.816075", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-09T06:15:20.818352", "lvl": "debug", "msg": "end: 2.4.4.1 login-action (duration 00:00:45) [common]"}
- {"dt": "2023-03-09T06:15:20.820348", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "45.38", "extra": {"success": "No kernel warnings or errors detected."}, "level": "2.4.4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:15:20.824288", "lvl": "debug", "msg": "end: 2.4.4 auto-login-action (duration 00:00:47) [common]"}
- {"dt": "2023-03-09T06:15:20.826519", "lvl": "debug", "msg": "start: 2.4.5 expect-shell-connection (timeout 00:18:36) [common]"}
- {"dt": "2023-03-09T06:15:20.827321", "lvl": "debug", "msg": "Setting prompt string to ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-09T06:15:20.827926", "lvl": "debug", "msg": "Forcing a shell prompt, looking for ['root@h3ulcb:~', 'h3ulcb:~#', '/ #']"}
- {"dt": "2023-03-09T06:15:20.828603", "lvl": "input", "msg": "\n"}
- {"dt": "2023-03-09T06:15:20.879725", "lvl": "target", "msg": "root@h3ulcb:~# "}
- {"dt": "2023-03-09T06:15:20.881437", "lvl": "debug", "msg": "expect-shell-connection: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #'] (timeout 00:20:00)"}
- {"dt": "2023-03-09T06:15:20.882855", "lvl": "debug", "msg": "Waiting using forced prompt support (timeout 00:10:00)"}
- {"dt": "2023-03-09T06:15:20.894708", "lvl": "target", "msg": ""}
- {"dt": "2023-03-09T06:15:20.911831", "lvl": "debug", "msg": "end: 2.4.5 expect-shell-connection (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:15:20.913878", "lvl": "debug", "msg": "start: 2.4.6 overlay-unpack (timeout 00:18:36) [common]"}
- {"dt": "2023-03-09T06:15:20.915778", "lvl": "input", "msg": "rm overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-09T06:15:21.018262", "lvl": "target", "msg": "root@h3ulcb:~# rm overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T06:15:21.039051", "lvl": "target", "msg": "rm overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T06:15:21.063127", "lvl": "target", "msg": "rm: cannot remove 'overlay-1.5.5.tar.gz': No such file or directory"}
- {"dt": "2023-03-09T06:15:21.065801", "lvl": "input", "msg": "wget http://192.168.66.1/tmp/32192/compress-overlay-l_1qjcly/overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-09T06:15:21.168837", "lvl": "target", "msg": "root@h3ulcb:~# wget http://192.168.66.1/tmp/32192/compress-overlay-l_1qjcly/overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T06:15:21.190004", "lvl": "target", "msg": "wget http://192.168.66.1/tmp/32192/compress-overlay-l_1qjcly/overllay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T06:15:21.204985", "lvl": "target", "msg": "Connecting to 192.168.66.1 (192.168.66.1:80)"}
- {"dt": "2023-03-09T06:15:21.230703", "lvl": "target", "msg": "saving to 'overlay-1.5.5.tar.gz'"}
- {"dt": "2023-03-09T06:15:21.233147", "lvl": "target", "msg": "overlay-1.5.5.tar.gz 100% |********************************|  7697  0:00:00 ETA"}
- {"dt": "2023-03-09T06:15:21.234564", "lvl": "target", "msg": "'overlay-1.5.5.tar.gz' saved"}
- {"dt": "2023-03-09T06:15:21.236630", "lvl": "input", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz\n"}
- {"dt": "2023-03-09T06:15:21.339255", "lvl": "target", "msg": "root@h3ulcb:~# tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T06:15:21.361780", "lvl": "target", "msg": "tar -C / -xvpf overlay-1.5.5.tar.gz"}
- {"dt": "2023-03-09T06:15:21.469442", "lvl": "target", "msg": "x lava-32192/"}
- {"dt": "2023-03-09T06:15:21.471360", "lvl": "target", "msg": "x lava-32192/0/"}
- {"dt": "2023-03-09T06:15:21.473782", "lvl": "target", "msg": "x lava-32192/0/lava-test-runner.conf"}
- {"dt": "2023-03-09T06:15:21.475447", "lvl": "target", "msg": "x lava-32192/0/tests/"}
- {"dt": "2023-03-09T06:15:21.476351", "lvl": "target", "msg": "x lava-32192/0/tests/0_check_disk_space/"}
- {"dt": "2023-03-09T06:15:21.477210", "lvl": "target", "msg": "x lava-32192/0/tests/0_check_disk_space/README"}
- {"dt": "2023-03-09T06:15:21.478050", "lvl": "target", "msg": "x lava-32192/0/tests/0_check_disk_space/check_space.sh"}
- {"dt": "2023-03-09T06:15:21.478983", "lvl": "target", "msg": "x lava-32192/0/tests/0_check_disk_space/check_space.yaml"}
- {"dt": "2023-03-09T06:15:21.479917", "lvl": "target", "msg": "x lava-32192/0/tests/0_check_disk_space/run.sh"}
- {"dt": "2023-03-09T06:15:21.480809", "lvl": "target", "msg": "x lava-32192/0/tests/0_check_disk_space/testdef.yaml"}
- {"dt": "2023-03-09T06:15:21.481903", "lvl": "target", "msg": "x lava-32192/0/tests/0_check_disk_space/testdef_metadata"}
- {"dt": "2023-03-09T06:15:21.512182", "lvl": "target", "msg": "x lava-32192/0/tests/0_check_disk_space/uuid"}
- {"dt": "2023-03-09T06:15:21.513411", "lvl": "target", "msg": "x lava-32192/bin/"}
- {"dt": "2023-03-09T06:15:21.514453", "lvl": "target", "msg": "x lava-32192/bin/lava-add-keys"}
- {"dt": "2023-03-09T06:15:21.516099", "lvl": "target", "msg": "x lava-32192/bin/lava-add-sources"}
- {"dt": "2023-03-09T06:15:21.517214", "lvl": "target", "msg": "x lava-32192/bin/lava-background-process-start"}
- {"dt": "2023-03-09T06:15:21.518294", "lvl": "target", "msg": "x lava-32192/bin/lava-background-process-stop"}
- {"dt": "2023-03-09T06:15:21.519460", "lvl": "target", "msg": "x lava-32192/bin/lava-common-functions"}
- {"dt": "2023-03-09T06:15:21.520569", "lvl": "target", "msg": "x lava-32192/bin/lava-echo-ipv4"}
- {"dt": "2023-03-09T06:15:21.521606", "lvl": "target", "msg": "x lava-32192/bin/lava-install-packages"}
- {"dt": "2023-03-09T06:15:21.522585", "lvl": "target", "msg": "x lava-32192/bin/lava-installed-packages"}
- {"dt": "2023-03-09T06:15:21.523423", "lvl": "target", "msg": "x lava-32192/bin/lava-os-build"}
- {"dt": "2023-03-09T06:15:21.523834", "lvl": "target", "msg": "x lava-32192/bin/lava-probe-channel"}
- {"dt": "2023-03-09T06:15:21.524235", "lvl": "target", "msg": "x lava-32192/bin/lava-probe-ip"}
- {"dt": "2023-03-09T06:15:21.524737", "lvl": "target", "msg": "x lava-32192/bin/lava-target-ip"}
- {"dt": "2023-03-09T06:15:21.565716", "lvl": "target", "msg": "x lava-32192/bin/lava-target-mac"}
- {"dt": "2023-03-09T06:15:21.566719", "lvl": "target", "msg": "x lava-32192/bin/lava-target-storage"}
- {"dt": "2023-03-09T06:15:21.567915", "lvl": "target", "msg": "x lava-32192/bin/lava-test-case"}
- {"dt": "2023-03-09T06:15:21.568921", "lvl": "target", "msg": "x lava-32192/bin/lava-test-event"}
- {"dt": "2023-03-09T06:15:21.570352", "lvl": "target", "msg": "x lava-32192/bin/lava-test-feedback"}
- {"dt": "2023-03-09T06:15:21.571364", "lvl": "target", "msg": "x lava-32192/bin/lava-test-raise"}
- {"dt": "2023-03-09T06:15:21.572254", "lvl": "target", "msg": "x lava-32192/bin/lava-test-reference"}
- {"dt": "2023-03-09T06:15:21.573097", "lvl": "target", "msg": "x lava-32192/bin/lava-test-runner"}
- {"dt": "2023-03-09T06:15:21.573974", "lvl": "target", "msg": "x lava-32192/bin/lava-test-set"}
- {"dt": "2023-03-09T06:15:21.574781", "lvl": "target", "msg": "x lava-32192/bin/lava-test-shell"}
- {"dt": "2023-03-09T06:15:21.575673", "lvl": "target", "msg": "x lava-32192/environment"}
- {"dt": "2023-03-09T06:15:21.576562", "lvl": "target", "msg": "x lava-32192/lava"}
- {"dt": "2023-03-09T06:15:21.577425", "lvl": "target", "msg": "x lava-32192/lava.pub"}
- {"dt": "2023-03-09T06:15:21.578292", "lvl": "target", "msg": "x lava-32192/results/"}
- {"dt": "2023-03-09T06:15:21.579199", "lvl": "target", "msg": "x lava-32192/tests/"}
- {"dt": "2023-03-09T06:15:21.580164", "lvl": "debug", "msg": "end: 2.4.6 overlay-unpack (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T06:15:21.580881", "lvl": "debug", "msg": "start: 2.4.7 export-device-env (timeout 00:18:35) [common]"}
- {"dt": "2023-03-09T06:15:21.581549", "lvl": "debug", "msg": "end: 2.4.7 export-device-env (duration 00:00:00) [common]"}
- {"dt": "2023-03-09T06:15:21.582189", "lvl": "debug", "msg": "end: 2.4 uboot-commands (duration 00:01:21) [common]"}
- {"dt": "2023-03-09T06:15:21.582824", "lvl": "info", "msg": "end: 2 uboot-action (duration 00:01:25) [common]"}
- {"dt": "2023-03-09T06:15:21.583536", "lvl": "info", "msg": "start: 3 lava-test-retry (timeout 00:15:00) [common]"}
- {"dt": "2023-03-09T06:15:21.584184", "lvl": "debug", "msg": "start: 3.1 lava-test-shell (timeout 00:15:00) [common]"}
- {"dt": "2023-03-09T06:15:21.584706", "lvl": "debug", "msg": "Using namespace: common"}
- {"dt": "2023-03-09T06:15:21.585212", "lvl": "input", "msg": "#\n"}
- {"dt": "2023-03-09T06:15:21.686561", "lvl": "target", "msg": "root@h3ulcb:~# #"}
- {"dt": "2023-03-09T06:15:21.688423", "lvl": "debug", "msg": "lava-test-shell: Wait for prompt ['root@h3ulcb:~', 'h3ulcb:~#', '/ #'] (timeout 00:15:00)"}
- {"dt": "2023-03-09T06:15:21.695287", "lvl": "target", "msg": "#"}
- {"dt": "2023-03-09T06:15:21.711399", "lvl": "debug", "msg": "Using /lava-32192"}
- {"dt": "2023-03-09T06:15:21.712256", "lvl": "input", "msg": "export SHELL=/bin/sh\n"}
- {"dt": "2023-03-09T06:15:21.813888", "lvl": "target", "msg": "root@h3ulcb:~# export SHELL=/bin/sh"}
- {"dt": "2023-03-09T06:15:21.822548", "lvl": "target", "msg": "export SHELL=/bin/sh"}
- {"dt": "2023-03-09T06:15:21.839779", "lvl": "input", "msg": ". /lava-32192/environment\n"}
- {"dt": "2023-03-09T06:15:21.942399", "lvl": "target", "msg": "root@h3ulcb:~# . /lava-32192/environment"}
- {"dt": "2023-03-09T06:15:21.950593", "lvl": "target", "msg": ". /lava-32192/environment"}
- {"dt": "2023-03-09T06:15:21.967370", "lvl": "input", "msg": "/lava-32192/bin/lava-test-runner /lava-32192/0\n"}
- {"dt": "2023-03-09T06:15:22.069648", "lvl": "target", "msg": "root@h3ulcb:~# /lava-32192/bin/lava-test-runner /lava-32192/0"}
- {"dt": "2023-03-09T06:15:22.071848", "lvl": "info", "msg": "Test shell timeout: 900s (minimum of the action and connection timeout)"}
- {"dt": "2023-03-09T06:15:22.078289", "lvl": "target", "msg": "/lava-32192/bin/lava-test-runner /lava-32192/0"}
- {"dt": "2023-03-09T06:15:22.220507", "lvl": "target", "msg": "+ export TESTRUN_ID=0_check_disk_space"}
- {"dt": "2023-03-09T06:15:22.222462", "lvl": "target", "msg": "+ TESTRUN_ID=0_check_disk_space"}
- {"dt": "2023-03-09T06:15:22.224294", "lvl": "target", "msg": "+ cd /lava-32192/0/tests/0_check_disk_space"}
- {"dt": "2023-03-09T06:15:22.226047", "lvl": "target", "msg": "++ cat uuid"}
- {"dt": "2023-03-09T06:15:22.227862", "lvl": "target", "msg": "+ UUID=32192_1.5.4.1"}
- {"dt": "2023-03-09T06:15:22.230169", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2023-03-09T06:15:22.230760", "lvl": "target", "msg": "<LAVA_SIGNAL_STARTRUN 0_check_disk_space 32192_1.5.4.1>"}
- {"dt": "2023-03-09T06:15:22.231390", "lvl": "target", "msg": "+ ./check_space.sh"}
- {"dt": "2023-03-09T06:15:22.231926", "lvl": "target", "msg": "Space info in /usr:"}
- {"dt": "2023-03-09T06:15:22.232424", "lvl": "target", "msg": "Filesystem      Size  Used Avail Use% Mounted on"}
- {"dt": "2023-03-09T06:15:22.232929", "lvl": "target", "msg": "/dev/nbd0       3.5G  2.5G  863M  75% /"}
- {"dt": "2023-03-09T06:15:22.233416", "lvl": "target", "msg": "Space info in /var/run"}
- {"dt": "2023-03-09T06:15:22.233899", "lvl": "target", "msg": "Filesystem      Size  Used Avail Use% Mounted on"}
- {"dt": "2023-03-09T06:15:22.234380", "lvl": "target", "msg": "tmpfs           1.6G  9.4M  1.6G   1% /run"}
- {"dt": "2023-03-09T06:15:22.234871", "lvl": "target", "msg": "+ set +x"}
- {"dt": "2023-03-09T06:15:22.235768", "lvl": "debug", "msg": "Received signal: <STARTRUN> 0_check_disk_space 32192_1.5.4.1"}
- {"dt": "2023-03-09T06:15:22.236392", "lvl": "info", "msg": "Starting test lava.0_check_disk_space (32192_1.5.4.1)"}
- {"dt": "2023-03-09T06:15:22.237068", "lvl": "info", "msg": "Skipping test definition patterns."}
- {"dt": "2023-03-09T06:15:22.237961", "lvl": "target", "msg": "<LAVA_SIGNAL_ENDRUN 0_check_disk_space 32192_1.5.4.1>"}
- {"dt": "2023-03-09T06:15:22.238580", "lvl": "target", "msg": "<LAVA_TEST_RUNNER EXIT>"}
- {"dt": "2023-03-09T06:15:22.239612", "lvl": "debug", "msg": "Received signal: <ENDRUN> 0_check_disk_space 32192_1.5.4.1"}
- {"dt": "2023-03-09T06:15:22.240290", "lvl": "info", "msg": "Ending use of test pattern."}
- {"dt": "2023-03-09T06:15:22.240848", "lvl": "info", "msg": "Ending test lava.0_check_disk_space (32192_1.5.4.1), duration 0.00"}
- {"dt": "2023-03-09T06:15:22.241471", "lvl": "results", "msg": {"case": "0_check_disk_space", "commit_id": "e4513698c438fef88cabd871178580a0c36ed5ce", "definition": "lava", "duration": "0.01", "namespace": "common", "path": "./check_space.yaml", "repository": "https://github.com/duerpei/yan_test.git", "result": "pass", "revision": "unspecified", "uuid": "32192_1.5.4.1"}}
- {"dt": "2023-03-09T06:15:22.242792", "lvl": "info", "msg": "ok: lava_test_shell seems to have completed"}
- {"dt": "2023-03-09T06:15:22.243625", "lvl": "debug", "msg": "end: 3.1 lava-test-shell (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T06:15:22.244463", "lvl": "info", "msg": "end: 3 lava-test-retry (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T06:15:22.245347", "lvl": "info", "msg": "start: 4 finalize (timeout 00:30:00) [common]"}
- {"dt": "2023-03-09T06:15:22.246169", "lvl": "debug", "msg": "start: 4.1 power-off (timeout 00:00:10) [common]"}
- {"dt": "2023-03-09T06:15:22.247263", "lvl": "debug", "msg": "Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '8'"}
- {"dt": "2023-03-09T06:15:25.674606", "lvl": "debug", "msg": ">> Success\r"}
- {"dt": "2023-03-09T06:15:25.710416", "lvl": "debug", "msg": "Returned 0 in 3 seconds"}
- {"dt": "2023-03-09T06:15:25.814757", "lvl": "debug", "msg": "end: 4.1 power-off (duration 00:00:04) [common]"}
- {"dt": "2023-03-09T06:15:25.817031", "lvl": "results", "msg": {"case": "power-off", "definition": "lava", "duration": "3.57", "extra": {"status": "success"}, "level": "4.1", "namespace": "common", "result": "pass"}}
- {"dt": "2023-03-09T06:15:25.821343", "lvl": "debug", "msg": "start: 4.2 read-feedback (timeout 00:29:56) [common]"}
- {"dt": "2023-03-09T06:15:25.826159", "lvl": "debug", "msg": "Listened to connection for namespace 'common' for up to 1s"}
- {"dt": "2023-03-09T06:15:26.829426", "lvl": "info", "msg": "Finalising connection for namespace 'common'"}
- {"dt": "2023-03-09T06:15:26.831761", "lvl": "info", "msg": "Disconnecting from shell: Finalise"}
- {"dt": "2023-03-09T06:15:26.833709", "lvl": "target", "msg": "root@h3ulcb:~# "}
- {"dt": "2023-03-09T06:15:26.937256", "lvl": "debug", "msg": "end: 4.2 read-feedback (duration 00:00:01) [common]"}
- {"dt": "2023-03-09T06:15:26.938629", "lvl": "debug", "msg": "lava-xnbd cleanup"}
- {"dt": "2023-03-09T06:15:26.939841", "lvl": "debug", "msg": "clean NBD port 61957"}
- {"dt": "2023-03-09T06:15:30.205848", "lvl": "info", "msg": "end: 4 finalize (duration 00:00:08) [common]"}
- {"dt": "2023-03-09T06:15:30.211853", "lvl": "info", "msg": "Cleaning after the job"}
- {"dt": "2023-03-09T06:15:30.214204", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/initrd"}
- {"dt": "2023-03-09T06:15:30.216858", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/kernel"}
- {"dt": "2023-03-09T06:15:30.226014", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/dtb"}
- {"dt": "2023-03-09T06:15:30.227533", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32192/nbd-deploy-vnz2pcu2/nbdroot"}
- {"dt": "2023-03-09T06:15:31.018207", "lvl": "info", "msg": "Override tmp directory removed at /var/lib/lava/dispatcher/tmp/32192"}
- {"dt": "2023-03-09T06:15:31.027020", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32192"}
- {"dt": "2023-03-09T06:15:31.028053", "lvl": "info", "msg": "Job finished correctly"}
- {"dt": "2023-03-09T06:15:31.028732", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}
