- {"dt": "2022-09-06T11:16:15.082339", "lvl": "info", "msg": "lava-dispatcher, installed at version: 2022.08"} - {"dt": "2022-09-06T11:16:15.082453", "lvl": "info", "msg": "start: 0 validate"} - {"dt": "2022-09-06T11:16:15.082531", "lvl": "info", "msg": "Start time: 2022-09-06 11:16:15.082526+00:00 (UTC)"} - {"dt": "2022-09-06T11:16:15.082631", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/1002/zImage exists"} - {"dt": "2022-09-06T11:16:15.082718", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxrun/assets/https___storage.lkft.org_rootfs_oe-kirkstone_20220824-114729_am57xx-evm_lkft-tux-image-am57xx-evm-20220824120704.rootfs.ext4.gz exists"} - {"dt": "2022-09-06T11:16:15.082811", "lvl": "debug", "msg": "Validating that file:///home/anders/.cache/tuxmake/builds/1002/modules.tar.xz exists"} - {"dt": "2022-09-06T11:16:15.094075", "lvl": "info", "msg": "qemu-system-arm, installed at version: 1:7.0+dfsg-2~bpo11+2, host architecture: amd64"} - {"dt": "2022-09-06T11:16:15.094552", "lvl": "warning", "msg": "Missing image arg for rootfs.modules"} - {"dt": "2022-09-06T11:16:15.094697", "lvl": "info", "msg": "validate duration: 0.01"} - {"dt": "2022-09-06T11:16:15.094767", "lvl": "results", "msg": {"case": "validate", "definition": "lava", "result": "pass"}} - {"dt": "2022-09-06T11:16:15.094894", "lvl": "info", "msg": "start: 1 deployimages (timeout 00:05:00) [common]"} - {"dt": "2022-09-06T11:16:15.094993", "lvl": "debug", "msg": "start: 1.1 download-retry (timeout 00:05:00) [common]"} - {"dt": "2022-09-06T11:16:15.095058", "lvl": "debug", "msg": "start: 1.1.1 file-download (timeout 00:05:00) [common]"} - {"dt": "2022-09-06T11:16:15.095299", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/1002/zImage"} - {"dt": "2022-09-06T11:16:15.095358", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/kernel/zImage"} - {"dt": "2022-09-06T11:16:15.095409", "lvl": "debug", "msg": "total size: 11817472 (11MB)"} - {"dt": "2022-09-06T11:16:15.095457", "lvl": "debug", "msg": "No compression specified"} - {"dt": "2022-09-06T11:16:15.095585", "lvl": "debug", "msg": "progress 0% (0MB)"} - {"dt": "2022-09-06T11:16:15.097435", "lvl": "debug", "msg": "progress 5% (0MB)"} - {"dt": "2022-09-06T11:16:15.099251", "lvl": "debug", "msg": "progress 10% (1MB)"} - {"dt": "2022-09-06T11:16:15.101051", "lvl": "debug", "msg": "progress 15% (1MB)"} - {"dt": "2022-09-06T11:16:15.102888", "lvl": "debug", "msg": "progress 20% (2MB)"} - {"dt": "2022-09-06T11:16:15.104707", "lvl": "debug", "msg": "progress 25% (2MB)"} - {"dt": "2022-09-06T11:16:15.106531", "lvl": "debug", "msg": "progress 30% (3MB)"} - {"dt": "2022-09-06T11:16:15.108362", "lvl": "debug", "msg": "progress 35% (3MB)"} - {"dt": "2022-09-06T11:16:15.110209", "lvl": "debug", "msg": "progress 40% (4MB)"} - {"dt": "2022-09-06T11:16:15.112060", "lvl": "debug", "msg": "progress 45% (5MB)"} - {"dt": "2022-09-06T11:16:15.113891", "lvl": "debug", "msg": "progress 50% (5MB)"} - {"dt": "2022-09-06T11:16:15.116347", "lvl": "debug", "msg": "progress 55% (6MB)"} - {"dt": "2022-09-06T11:16:15.118274", "lvl": "debug", "msg": "progress 60% (6MB)"} - {"dt": "2022-09-06T11:16:15.120112", "lvl": "debug", "msg": "progress 65% (7MB)"} - {"dt": "2022-09-06T11:16:15.121942", "lvl": "debug", "msg": "progress 70% (7MB)"} - {"dt": "2022-09-06T11:16:15.123783", "lvl": "debug", "msg": "progress 75% (8MB)"} - {"dt": "2022-09-06T11:16:15.125599", "lvl": "debug", "msg": "progress 80% (9MB)"} - {"dt": "2022-09-06T11:16:15.127450", "lvl": "debug", "msg": "progress 85% (9MB)"} - {"dt": "2022-09-06T11:16:15.129297", "lvl": "debug", "msg": "progress 90% (10MB)"} - {"dt": "2022-09-06T11:16:15.131171", "lvl": "debug", "msg": "progress 95% (10MB)"} - {"dt": "2022-09-06T11:16:15.133048", "lvl": "debug", "msg": "progress 100% (11MB)"} - {"dt": "2022-09-06T11:16:15.144317", "lvl": "info", "msg": "11MB downloaded in 0.05s (230.46MB/s)"} - {"dt": "2022-09-06T11:16:15.144498", "lvl": "debug", "msg": "end: 1.1.1 file-download (duration 00:00:00) [common]"} - {"dt": "2022-09-06T11:16:15.144583", "lvl": "results", "msg": {"case": "file-download", "definition": "lava", "duration": "0.05", "extra": {"label": "kernel", "md5sum": "ffd85d23328b8fae4f958a7c739776b1", "sha256sum": "ec0fb4291abd3dc12037efa37e98a0c44802621f6049b5e9fc3bc772e750aaa6", "sha512sum": "aba9acf9fd47d98b47b2bbd11e186a98bc1926f344e46d04b365b2aed3c547da41632bd53fb1bda1ce49951b3eefc41014cbcfdd2b2f5f554e302c97275789df", "size": ! "11817472"}, "level": "1.1.1", "namespace": "common", "result": "pass"}} - {"dt": "2022-09-06T11:16:15.144761", "lvl": "debug", "msg": "end: 1.1 download-retry (duration 00:00:00) [common]"} - {"dt": "2022-09-06T11:16:15.144850", "lvl": "debug", "msg": "start: 1.2 download-retry (timeout 00:05:00) [common]"} - {"dt": "2022-09-06T11:16:15.144935", "lvl": "debug", "msg": "start: 1.2.1 file-download (timeout 00:05:00) [common]"} - {"dt": "2022-09-06T11:16:15.145205", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxrun/assets/https___storage.lkft.org_rootfs_oe-kirkstone_20220824-114729_am57xx-evm_lkft-tux-image-am57xx-evm-20220824120704.rootfs.ext4.gz"} - {"dt": "2022-09-06T11:16:15.145285", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/rootfs/https___storage.lkft.org_rootfs_oe-kirkstone_20220824-114729_am57xx-evm_lkft-tux-image-am57xx-evm-20220824120704.rootfs.ext4"} - {"dt": "2022-09-06T11:16:15.145353", "lvl": "debug", "msg": "total size: 209993468 (200MB)"} - {"dt": "2022-09-06T11:16:15.145416", "lvl": "info", "msg": "Using gunzip to decompress gz"} - {"dt": "2022-09-06T11:16:15.147825", "lvl": "debug", "msg": "progress 0% (0MB)"} - {"dt": "2022-09-06T11:16:15.674122", "lvl": "debug", "msg": "progress 5% (10MB)"} - {"dt": "2022-09-06T11:16:15.728501", "lvl": "debug", "msg": "progress 10% (20MB)"} - {"dt": "2022-09-06T11:16:15.828033", "lvl": "debug", "msg": "progress 15% (30MB)"} - {"dt": "2022-09-06T11:16:15.918848", "lvl": "debug", "msg": "progress 20% (40MB)"} - {"dt": "2022-09-06T11:16:16.104130", "lvl": "debug", "msg": "progress 25% (50MB)"} - {"dt": "2022-09-06T11:16:16.404872", "lvl": "debug", "msg": "progress 30% (60MB)"} - {"dt": "2022-09-06T11:16:16.626146", "lvl": "debug", "msg": "progress 35% (70MB)"} - {"dt": "2022-09-06T11:16:16.785150", "lvl": "debug", "msg": "progress 40% (80MB)"} - {"dt": "2022-09-06T11:16:16.990139", "lvl": "debug", "msg": "progress 45% (90MB)"} - {"dt": "2022-09-06T11:16:17.148228", "lvl": "debug", "msg": "progress 50% (100MB)"} - {"dt": "2022-09-06T11:16:17.307403", "lvl": "debug", "msg": "progress 55% (110MB)"} - {"dt": "2022-09-06T11:16:17.512771", "lvl": "debug", "msg": "progress 60% (120MB)"} - {"dt": "2022-09-06T11:16:17.675287", "lvl": "debug", "msg": "progress 65% (130MB)"} - {"dt": "2022-09-06T11:16:17.889298", "lvl": "debug", "msg": "progress 70% (140MB)"} - {"dt": "2022-09-06T11:16:18.047597", "lvl": "debug", "msg": "progress 75% (150MB)"} - {"dt": "2022-09-06T11:16:18.210064", "lvl": "debug", "msg": "progress 80% (160MB)"} - {"dt": "2022-09-06T11:16:18.438992", "lvl": "debug", "msg": "progress 85% (170MB)"} - {"dt": "2022-09-06T11:16:18.688724", "lvl": "debug", "msg": "progress 90% (180MB)"} - {"dt": "2022-09-06T11:16:18.977090", "lvl": "debug", "msg": "progress 95% (190MB)"} - {"dt": "2022-09-06T11:16:21.179930", "lvl": "debug", "msg": "progress 100% (200MB)"} - {"dt": "2022-09-06T11:16:21.638334", "lvl": "info", "msg": "200MB downloaded in 6.49s (30.84MB/s)"} - {"dt": "2022-09-06T11:16:21.638755", "lvl": "debug", "msg": "end: 1.2.1 file-download (duration 00:00:06) [common]"} - {"dt": "2022-09-06T11:16:21.638838", "lvl": "results", "msg": {"case": "file-download", "definition": "lava", "duration": "6.49", "extra": {"label": "rootfs", "md5sum": "d79e7942f4ce877526b21c85a038bad5", "sha256sum": "3a39414ae4b795e26d08593901dead00cd3a3f781e418f58e3cdeba848bd7b66", "sha512sum": "d3242bca8ffd32132227a3257c528b556c12151196289ae0a2faaff94e8c3983a05118c98921d49316b4d8cc1cb16a0df5fd75825988543dfeb72635e926c978", "size": ! "209993468"}, "level": "1.2.1", "namespace": "common", "result": "pass"}} - {"dt": "2022-09-06T11:16:21.638998", "lvl": "debug", "msg": "start: 1.2.2 download-retry (timeout 00:04:53) [common]"} - {"dt": "2022-09-06T11:16:21.639069", "lvl": "debug", "msg": "start: 1.2.2.1 file-download (timeout 00:04:53) [common]"} - {"dt": "2022-09-06T11:16:21.639337", "lvl": "info", "msg": "downloading file:///home/anders/.cache/tuxmake/builds/1002/modules.tar.xz"} - {"dt": "2022-09-06T11:16:21.639409", "lvl": "debug", "msg": "saving as /var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/rootfs.modules/modules.tar"} - {"dt": "2022-09-06T11:16:21.639464", "lvl": "debug", "msg": "total size: 6046068 (5MB)"} - {"dt": "2022-09-06T11:16:21.639516", "lvl": "info", "msg": "Using unxz to decompress xz"} - {"dt": "2022-09-06T11:16:21.641438", "lvl": "debug", "msg": "progress 0% (0MB)"} - {"dt": "2022-09-06T11:16:21.652665", "lvl": "debug", "msg": "progress 5% (0MB)"} - {"dt": "2022-09-06T11:16:21.666626", "lvl": "debug", "msg": "progress 10% (0MB)"} - {"dt": "2022-09-06T11:16:21.680481", "lvl": "debug", "msg": "progress 15% (0MB)"} - {"dt": "2022-09-06T11:16:21.693223", "lvl": "debug", "msg": "progress 20% (1MB)"} - {"dt": "2022-09-06T11:16:21.710019", "lvl": "debug", "msg": "progress 25% (1MB)"} - {"dt": "2022-09-06T11:16:21.723939", "lvl": "debug", "msg": "progress 30% (1MB)"} - {"dt": "2022-09-06T11:16:21.738001", "lvl": "debug", "msg": "progress 35% (2MB)"} - {"dt": "2022-09-06T11:16:21.752106", "lvl": "debug", "msg": "progress 40% (2MB)"} - {"dt": "2022-09-06T11:16:21.767583", "lvl": "debug", "msg": "progress 45% (2MB)"} - {"dt": "2022-09-06T11:16:21.781576", "lvl": "debug", "msg": "progress 50% (2MB)"} - {"dt": "2022-09-06T11:16:21.795563", "lvl": "debug", "msg": "progress 55% (3MB)"} - {"dt": "2022-09-06T11:16:21.808953", "lvl": "debug", "msg": "progress 60% (3MB)"} - {"dt": "2022-09-06T11:16:21.823408", "lvl": "debug", "msg": "progress 65% (3MB)"} - {"dt": "2022-09-06T11:16:21.839082", "lvl": "debug", "msg": "progress 70% (4MB)"} - {"dt": "2022-09-06T11:16:21.852851", "lvl": "debug", "msg": "progress 75% (4MB)"} - {"dt": "2022-09-06T11:16:21.866377", "lvl": "debug", "msg": "progress 80% (4MB)"} - {"dt": "2022-09-06T11:16:21.879779", "lvl": "debug", "msg": "progress 85% (4MB)"} - {"dt": "2022-09-06T11:16:21.895375", "lvl": "debug", "msg": "progress 90% (5MB)"} - {"dt": "2022-09-06T11:16:21.910378", "lvl": "debug", "msg": "progress 95% (5MB)"} - {"dt": "2022-09-06T11:16:21.925669", "lvl": "debug", "msg": "progress 100% (5MB)"} - {"dt": "2022-09-06T11:16:21.953417", "lvl": "info", "msg": "5MB downloaded in 0.31s (18.37MB/s)"} - {"dt": "2022-09-06T11:16:21.953853", "lvl": "debug", "msg": "end: 1.2.2.1 file-download (duration 00:00:00) [common]"} - {"dt": "2022-09-06T11:16:21.954004", "lvl": "results", "msg": {"case": "file-download", "definition": "lava", "duration": "0.31", "extra": {"label": "rootfs.modules", "md5sum": "3dcfe3fc2e655065041322104c8e24d7", "sha256sum": "cafecb0a4b689b0dbb93836c9e68747de515300296c39e23fcc9a9bb65a7e389", "sha512sum": "26297cd053f1fc2067707a80615763d0d99f2f8dc12e9b7c72c88ae3fc24cd0315889cb306f852cc2d9ec974f41f1dc220786956bf30c55eccefb54cf733cb1a", "size": ! "6046068"}, "level": "1.2.2.1", "namespace": "common", "result": "pass"}} - {"dt": "2022-09-06T11:16:21.954318", "lvl": "debug", "msg": "end: 1.2.2 download-retry (duration 00:00:00) [common]"} - {"dt": "2022-09-06T11:16:21.954475", "lvl": "debug", "msg": "start: 1.2.3 append-overlays (timeout 00:04:53) [common]"} - {"dt": "2022-09-06T11:16:21.954621", "lvl": "info", "msg": "Modifying '/var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/rootfs/https___storage.lkft.org_rootfs_oe-kirkstone_20220824-114729_am57xx-evm_lkft-tux-image-am57xx-evm-20220824120704.rootfs.ext4'"} - {"dt": "2022-09-06T11:16:27.240853", "lvl": "debug", "msg": "Overlays:"} - {"dt": "2022-09-06T11:16:27.241615", "lvl": "debug", "msg": "- rootfs.modules: '/var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/rootfs.modules/modules.tar' to '/'"} - {"dt": "2022-09-06T11:16:27.532160", "lvl": "debug", "msg": "end: 1.2.3 append-overlays (duration 00:00:06) [common]"} - {"dt": "2022-09-06T11:16:27.532544", "lvl": "debug", "msg": "end: 1.2 download-retry (duration 00:00:12) [common]"} - {"dt": "2022-09-06T11:16:27.532708", "lvl": "info", "msg": "end: 1 deployimages (duration 00:00:12) [common]"} - {"dt": "2022-09-06T11:16:27.532891", "lvl": "info", "msg": "start: 2 boot-image-retry (timeout 00:40:00) [common]"} - {"dt": "2022-09-06T11:16:27.533048", "lvl": "debug", "msg": "start: 2.1 boot-qemu-image (timeout 00:40:00) [common]"} - {"dt": "2022-09-06T11:16:27.533200", "lvl": "debug", "msg": "start: 2.1.1 execute-qemu (timeout 00:40:00) [common]"} - {"dt": "2022-09-06T11:16:27.533593", "lvl": "info", "msg": "Boot command: /usr/bin/qemu-system-arm -cpu cortex-a15 -machine virt,gic-version=3 -nographic -nic none -m 1024 -monitor none -no-reboot -smp 2 -kernel /var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/kernel/zImage -append \"console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog\" -drive file=/var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/rootfs/https___storage.lkft.org_rootfs_oe-kirkstone_20220824-114729_am57xx-evm_lkft-tux-image-am57xx-evm-20220824120704.rootfs.ext4,if=none,format=raw,id=hd0 -device virtio-blk-device,drive=hd0"} - {"dt": "2022-09-06T11:16:27.608886", "lvl": "debug", "msg": "started a shell command"} - {"dt": "2022-09-06T11:16:27.609304", "lvl": "debug", "msg": "end: 2.1.1 execute-qemu (duration 00:00:00) [common]"} - {"dt": "2022-09-06T11:16:27.609450", "lvl": "results", "msg": {"case": "execute-qemu", "definition": "lava", "duration": "0.08", "extra": {"host_arch": "amd64", "job_arch": "arm", "qemu_version": "1:7.0+dfsg-2~bpo11+2"}, "level": "2.1.1", "namespace": "common", "result": "pass"}} - {"dt": "2022-09-06T11:16:27.609716", "lvl": "debug", "msg": "end: 2.1 boot-qemu-image (duration 00:00:00) [common]"} - {"dt": "2022-09-06T11:16:27.609876", "lvl": "debug", "msg": "start: 2.2 auto-login-action (timeout 00:40:00) [common]"} - {"dt": "2022-09-06T11:16:27.610038", "lvl": "debug", "msg": "Setting prompt string to ['Linux version [0-9]']"} - {"dt": "2022-09-06T11:16:27.610166", "lvl": "debug", "msg": "auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:40:00)"} - {"dt": "2022-09-06T11:16:28.093419", "lvl": "target", "msg": "<6>[ 0.000000] Booting Linux on physical CPU 0x0"} - {"dt": "2022-09-06T11:16:28.093889", "lvl": "target", "msg": "<5>[ 0.000000] Linux version 6.0.0-rc3-next-20220901 (tuxmake@tuxmake) (arm-linux-gnueabihf-gcc (Debian 10.2.1-6) 10.2.1 20210110, GNU ld (GNU Binutils for Debian) 2.35.2) #1 SMP @1662100063"} - {"dt": "2022-09-06T11:16:28.094036", "lvl": "target", "msg": "<6>[ 0.000000] CPU: ARMv7 Processor [412fc0f1] revision 1 (ARMv7), cr=10c5387d"} - {"dt": "2022-09-06T11:16:28.094172", "lvl": "target", "msg": "<6>[ 0.000000] CPU: div instructions available: patching division code"} - {"dt": "2022-09-06T11:16:28.094282", "lvl": "target", "msg": "<6>[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache"} - {"dt": "2022-09-06T11:16:28.094464", "lvl": "target", "msg": "<6>[ 0.000000] OF: fdt: Machine model: linux,dummy-virt"} - {"dt": "2022-09-06T11:16:28.094622", "lvl": "target", "msg": "<6>[ 0.000000] Memory policy: Data cache writealloc"} - {"dt": "2022-09-06T11:16:28.094751", "lvl": "target", "msg": "<6>[ 0.000000] efi: UEFI not found."} - {"dt": "2022-09-06T11:16:28.094895", "lvl": "target", "msg": "<6>[ 0.000000] cma: Reserved 64 MiB at 0x7c000000"} - {"dt": "2022-09-06T11:16:28.095036", "lvl": "target", "msg": "<6>[ 0.000000] Zone ranges:"} - {"dt": "2022-09-06T11:16:28.095176", "lvl": "target", "msg": "<6>[ 0.000000] DMA [mem 0x0000000040000000-0x000000006fffffff]"} - {"dt": "2022-09-06T11:16:28.095306", "lvl": "target", "msg": "<6>[ 0.000000] Normal empty"} - {"dt": "2022-09-06T11:16:28.095456", "lvl": "target", "msg": "<6>[ 0.000000] HighMem [mem 0x0000000070000000-0x000000007fffffff]"} - {"dt": "2022-09-06T11:16:28.095583", "lvl": "target", "msg": "<6>[ 0.000000] Movable zone start for each node"} - {"dt": "2022-09-06T11:16:28.095719", "lvl": "target", "msg": "<6>[ 0.000000] Early memory node ranges"} - {"dt": "2022-09-06T11:16:28.095880", "lvl": "target", "msg": "<6>[ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]"} - {"dt": "2022-09-06T11:16:28.096020", "lvl": "target", "msg": "<6>[ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]"} - {"dt": "2022-09-06T11:16:28.096144", "lvl": "target", "msg": "<6>[ 0.000000] psci: probing for conduit method from DT."} - {"dt": "2022-09-06T11:16:28.096273", "lvl": "target", "msg": "<6>[ 0.000000] psci: PSCIv1.1 detected in firmware."} - {"dt": "2022-09-06T11:16:28.096392", "lvl": "target", "msg": "<6>[ 0.000000] psci: Using standard PSCI v0.2 function IDs"} - {"dt": "2022-09-06T11:16:28.096506", "lvl": "target", "msg": "<6>[ 0.000000] psci: Trusted OS migration not required"} - {"dt": "2022-09-06T11:16:28.096636", "lvl": "target", "msg": "<6>[ 0.000000] psci: SMC Calling Convention v1.0"} - {"dt": "2022-09-06T11:16:28.096759", "lvl": "target", "msg": "<6>[ 0.000000] percpu: Embedded 12 pages/cpu s18196 r8192 d22764 u49152"} - {"dt": "2022-09-06T11:16:28.096885", "lvl": "target", "msg": "<7>[ 0.000000] pcpu-alloc: s18196 r8192 d22764 u49152 alloc=12*4096"} - {"dt": "2022-09-06T11:16:28.097007", "lvl": "target", "msg": "<7>[ 0.000000] pcpu-alloc: [0] 0 [0] 1 "} - {"dt": "2022-09-06T11:16:28.097136", "lvl": "target", "msg": "<6>[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 260416"} - {"dt": "2022-09-06T11:16:28.097256", "lvl": "target", "msg": "<5>[ 0.000000] Kernel command line: console=ttyAMA0,115200 rootwait root=/dev/vda debug verbose console_msg_format=syslog"} - {"dt": "2022-09-06T11:16:28.097371", "lvl": "target", "msg": "<5>[ 0.000000] Unknown kernel command line parameters \\\"verbose\\\", will be passed to user space."} - {"dt": "2022-09-06T11:16:28.097769", "lvl": "debug", "msg": "start: 2.2.1 login-action (timeout 00:39:59) [common]"} - {"dt": "2022-09-06T11:16:28.097969", "lvl": "debug", "msg": "Setting prompt string to ['-+\\\\[ cut here \\\\]-+\\\\s+(.*\\\\s+-+\\\\[ end trace (\\\\w*) \\\\]-+)', '(Unhandled fault.*)\\\\r\\\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\\\s+(.*\\\\s+-+\\\\[ end trace (\\\\w*) \\\\]-+)', '^[^\\\\n]+WARNING:.*?$', '^[^\\\\n]+Oops(?: -|:).*?$', '^[^\\\\n]+BUG:.*?$']"} - {"dt": "2022-09-06T11:16:28.098112", "lvl": "debug", "msg": "Using line separator: #'\\n'#"} - {"dt": "2022-09-06T11:16:28.098241", "lvl": "info", "msg": "Waiting for the login prompt"} - {"dt": "2022-09-06T11:16:28.098373", "lvl": "info", "msg": "Parsing kernel messages"} - {"dt": "2022-09-06T11:16:28.098504", "lvl": "debug", "msg": ["-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)", "(Unhandled fault.*)\\r\\n", "Kernel panic - (.*) end Kernel panic", "Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)", "^[^\\n]+WARNING:.*?$", "^[^\\n]+Oops(?: -|:).*?$", "^[^\\n]+BUG:.*?$", "root@(.*):[/~]#", "(.*)login:", "Login incorrect"]} - {"dt": "2022-09-06T11:16:28.098725", "lvl": "debug", "msg": "[login-action] Waiting for messages, (timeout 00:39:59)"} - {"dt": "2022-09-06T11:16:28.125157", "lvl": "target", "msg": "<6>[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.125295", "lvl": "target", "msg": "<6>[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.125400", "lvl": "target", "msg": "<6>[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off"} - {"dt": "2022-09-06T11:16:28.125492", "lvl": "target", "msg": "<6>[ 0.000000] Memory: 942740K/1048576K available (15360K kernel code, 2018K rwdata, 8304K rodata, 2048K init, 484K bss, 40300K reserved, 65536K cma-reserved, 196608K highmem)"} - {"dt": "2022-09-06T11:16:28.125583", "lvl": "target", "msg": "<6>[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1"} - {"dt": "2022-09-06T11:16:28.125670", "lvl": "target", "msg": "<6>[ 0.000000] rcu: Hierarchical RCU implementation."} - {"dt": "2022-09-06T11:16:28.125753", "lvl": "target", "msg": "<6>[ 0.000000] rcu: \tRCU event tracing is enabled."} - {"dt": "2022-09-06T11:16:28.125837", "lvl": "target", "msg": "<6>[ 0.000000] rcu: \tRCU restricting CPUs from NR_CPUS=16 to nr_cpu_ids=2."} - {"dt": "2022-09-06T11:16:28.125920", "lvl": "target", "msg": "<6>[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies."} - {"dt": "2022-09-06T11:16:28.126004", "lvl": "target", "msg": "<6>[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2"} - {"dt": "2022-09-06T11:16:28.126088", "lvl": "target", "msg": "<6>[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16"} - {"dt": "2022-09-06T11:16:28.126171", "lvl": "target", "msg": "<6>[ 0.000000] GICv3: 224 SPIs implemented"} - {"dt": "2022-09-06T11:16:28.126253", "lvl": "target", "msg": "<6>[ 0.000000] GICv3: 0 Extended SPIs implemented"} - {"dt": "2022-09-06T11:16:28.126337", "lvl": "target", "msg": "<6>[ 0.000000] GICv3: GICv3 features: 16 PPIs"} - {"dt": "2022-09-06T11:16:28.126420", "lvl": "target", "msg": "<6>[ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x080a0000"} - {"dt": "2022-09-06T11:16:28.126500", "lvl": "target", "msg": "<6>[ 0.000000] ITS [mem 0x08080000-0x0809ffff]"} - {"dt": "2022-09-06T11:16:28.126581", "lvl": "target", "msg": "<6>[ 0.000000] ITS@0x08080000: allocated 8192 Devices @42030000 (indirect, esz 8, psz 64K, shr 1)"} - {"dt": "2022-09-06T11:16:28.126674", "lvl": "target", "msg": "<6>[ 0.000000] ITS@0x08080000: allocated 8192 Interrupt Collections @42040000 (flat, esz 8, psz 64K, shr 1)"} - {"dt": "2022-09-06T11:16:28.126763", "lvl": "target", "msg": "<6>[ 0.000000] GICv3: using LPI property table @0x42050000"} - {"dt": "2022-09-06T11:16:28.126847", "lvl": "target", "msg": "<6>[ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x42060000"} - {"dt": "2022-09-06T11:16:28.126931", "lvl": "target", "msg": "<6>[ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention."} - {"dt": "2022-09-06T11:16:28.127015", "lvl": "target", "msg": "<6>[ 0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt)."} - {"dt": "2022-09-06T11:16:28.127096", "lvl": "target", "msg": "<6>[ 0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns"} - {"dt": "2022-09-06T11:16:28.127180", "lvl": "target", "msg": "<6>[ 0.000077] sched_clock: 57 bits at 63MHz, resolution 16ns, wraps every 4398046511096ns"} - {"dt": "2022-09-06T11:16:28.127264", "lvl": "target", "msg": "<6>[ 0.000160] Switching to timer-based delay loop, resolution 16ns"} - {"dt": "2022-09-06T11:16:28.188558", "lvl": "target", "msg": "<6>[ 0.003543] Console: colour dummy device 80x30"} - {"dt": "2022-09-06T11:16:28.188623", "lvl": "target", "msg": "<6>[ 0.004316] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=625000)"} - {"dt": "2022-09-06T11:16:28.188671", "lvl": "target", "msg": "<6>[ 0.004386] pid_max: default: 32768 minimum: 301"} - {"dt": "2022-09-06T11:16:28.188714", "lvl": "target", "msg": "<6>[ 0.005898] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.188756", "lvl": "target", "msg": "<6>[ 0.005924] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.188796", "lvl": "target", "msg": "<6>[ 0.014863] CPU: Testing write buffer coherency: ok"} - {"dt": "2022-09-06T11:16:28.188837", "lvl": "target", "msg": "<3>[ 0.015308] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"} - {"dt": "2022-09-06T11:16:28.188877", "lvl": "target", "msg": "<6>[ 0.015389] CPU0: Spectre BHB: enabling loop workaround for all CPUs"} - {"dt": "2022-09-06T11:16:28.188917", "lvl": "target", "msg": "<3>[ 0.020685] /cpus/cpu@0 missing clock-frequency property"} - {"dt": "2022-09-06T11:16:28.188959", "lvl": "target", "msg": "<3>[ 0.020834] /cpus/cpu@1 missing clock-frequency property"} - {"dt": "2022-09-06T11:16:28.189011", "lvl": "target", "msg": "<6>[ 0.020913] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000"} - {"dt": "2022-09-06T11:16:28.189051", "lvl": "target", "msg": "<6>[ 0.021258] Early cacheinfo failed, ret = -2"} - {"dt": "2022-09-06T11:16:28.189091", "lvl": "target", "msg": "<6>[ 0.024591] Setting up static identity map for 0x40300000 - 0x403000ac"} - {"dt": "2022-09-06T11:16:28.189130", "lvl": "target", "msg": "<6>[ 0.025625] rcu: Hierarchical SRCU implementation."} - {"dt": "2022-09-06T11:16:28.189177", "lvl": "target", "msg": "<6>[ 0.025647] rcu: \tMax phase no-delay instances is 1000."} - {"dt": "2022-09-06T11:16:28.189216", "lvl": "target", "msg": "<6>[ 0.027497] Platform MSI: its@8080000 domain created"} - {"dt": "2022-09-06T11:16:28.189256", "lvl": "target", "msg": "<6>[ 0.027648] PCI/MSI: /intc@8000000/its@8080000 domain created"} - {"dt": "2022-09-06T11:16:28.189296", "lvl": "target", "msg": "<6>[ 0.028596] EFI services will not be available."} - {"dt": "2022-09-06T11:16:28.189339", "lvl": "target", "msg": "<6>[ 0.029380] smp: Bringing up secondary CPUs ..."} - {"dt": "2022-09-06T11:16:28.189394", "lvl": "target", "msg": "<6>[ 0.031913] GICv3: CPU1: found redistributor 1 region 0:0x080c0000"} - {"dt": "2022-09-06T11:16:28.189437", "lvl": "target", "msg": "<6>[ 0.032207] GICv3: CPU1: using allocated LPI pending table @0x42070000"} - {"dt": "2022-09-06T11:16:28.189477", "lvl": "target", "msg": "<6>[ 0.032775] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001"} - {"dt": "2022-09-06T11:16:28.189516", "lvl": "target", "msg": "<6>[ 0.032802] Early cacheinfo failed, ret = -2"} - {"dt": "2022-09-06T11:16:28.189555", "lvl": "target", "msg": "<3>[ 0.032856] CPU1: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable"} - {"dt": "2022-09-06T11:16:28.189594", "lvl": "target", "msg": "<6>[ 0.035435] smp: Brought up 1 node, 2 CPUs"} - {"dt": "2022-09-06T11:16:28.189633", "lvl": "target", "msg": "<6>[ 0.035501] SMP: Total of 2 processors activated (250.00 BogoMIPS)."} - {"dt": "2022-09-06T11:16:28.189671", "lvl": "target", "msg": "<6>[ 0.035553] CPU: All CPU(s) started in SVC mode."} - {"dt": "2022-09-06T11:16:28.189710", "lvl": "target", "msg": "<6>[ 0.045783] devtmpfs: initialized"} - {"dt": "2022-09-06T11:16:28.293094", "lvl": "target", "msg": "<6>[ 0.050644] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0"} - {"dt": "2022-09-06T11:16:28.293335", "lvl": "target", "msg": "<6>[ 0.060346] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns"} - {"dt": "2022-09-06T11:16:28.293456", "lvl": "target", "msg": "<6>[ 0.060727] futex hash table entries: 512 (order: 3, 32768 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.293571", "lvl": "target", "msg": "<6>[ 0.064110] pinctrl core: initialized pinctrl subsystem"} - {"dt": "2022-09-06T11:16:28.293666", "lvl": "target", "msg": "<6>[ 0.073163] DMI not present or invalid."} - {"dt": "2022-09-06T11:16:28.293757", "lvl": "target", "msg": "<6>[ 0.078447] NET: Registered PF_NETLINK/PF_ROUTE protocol family"} - {"dt": "2022-09-06T11:16:28.293844", "lvl": "target", "msg": "<6>[ 0.089945] DMA: preallocated 256 KiB pool for atomic coherent allocations"} - {"dt": "2022-09-06T11:16:28.293933", "lvl": "target", "msg": "<6>[ 0.093025] thermal_sys: Registered thermal governor 'step_wise'"} - {"dt": "2022-09-06T11:16:28.294022", "lvl": "target", "msg": "<6>[ 0.093203] cpuidle: using governor menu"} - {"dt": "2022-09-06T11:16:28.294107", "lvl": "target", "msg": "<6>[ 0.093444] No ATAGs?"} - {"dt": "2022-09-06T11:16:28.294196", "lvl": "target", "msg": "<4>[ 0.093760] hw-breakpoint: CPU 0 debug is powered down!"} - {"dt": "2022-09-06T11:16:28.294289", "lvl": "target", "msg": "<6>[ 0.096627] Serial: AMBA PL011 UART driver"} - {"dt": "2022-09-06T11:16:28.294376", "lvl": "target", "msg": "<6>[ 0.115573] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 28, base_baud = 0) is a PL011 rev1"} - {"dt": "2022-09-06T11:16:28.294465", "lvl": "target", "msg": "<6>[ 0.124542] printk: console [ttyAMA0] enabled"} - {"dt": "2022-09-06T11:16:28.294553", "lvl": "target", "msg": "<6>[ 0.146606] cryptd: max_cpu_qlen set to 1000"} - {"dt": "2022-09-06T11:16:28.294654", "lvl": "target", "msg": "<6>[ 0.153067] iommu: Default domain type: Translated "} - {"dt": "2022-09-06T11:16:28.294742", "lvl": "target", "msg": "<6>[ 0.153170] iommu: DMA domain TLB invalidation policy: strict mode "} - {"dt": "2022-09-06T11:16:28.294828", "lvl": "target", "msg": "<5>[ 0.154373] SCSI subsystem initialized"} - {"dt": "2022-09-06T11:16:28.294914", "lvl": "target", "msg": "<7>[ 0.155021] libata version 3.00 loaded."} - {"dt": "2022-09-06T11:16:28.295000", "lvl": "target", "msg": "<6>[ 0.155807] usbcore: registered new interface driver usbfs"} - {"dt": "2022-09-06T11:16:28.295085", "lvl": "target", "msg": "<6>[ 0.156015] usbcore: registered new interface driver hub"} - {"dt": "2022-09-06T11:16:28.295168", "lvl": "target", "msg": "<6>[ 0.156183] usbcore: registered new device driver usb"} - {"dt": "2022-09-06T11:16:28.295252", "lvl": "target", "msg": "<6>[ 0.157291] mc: Linux media interface: v0.10"} - {"dt": "2022-09-06T11:16:28.295334", "lvl": "target", "msg": "<6>[ 0.157411] videodev: Linux video capture interface: v2.00"} - {"dt": "2022-09-06T11:16:28.295418", "lvl": "target", "msg": "<6>[ 0.157676] pps_core: LinuxPPS API ver. 1 registered"} - {"dt": "2022-09-06T11:16:28.295465", "lvl": "target", "msg": "<6>[ 0.157756] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti "} - {"dt": "2022-09-06T11:16:28.295504", "lvl": "target", "msg": "<6>[ 0.157923] PTP clock support registered"} - {"dt": "2022-09-06T11:16:28.295543", "lvl": "target", "msg": "<6>[ 0.158212] EDAC MC: Ver: 3.0.0"} - {"dt": "2022-09-06T11:16:28.295582", "lvl": "target", "msg": "<6>[ 0.166307] vgaarb: loaded"} - {"dt": "2022-09-06T11:16:28.295620", "lvl": "target", "msg": "<6>[ 0.169491] clocksource: Switched to clocksource arch_sys_counter"} - {"dt": "2022-09-06T11:16:28.295660", "lvl": "target", "msg": "<6>[ 0.201804] NET: Registered PF_INET protocol family"} - {"dt": "2022-09-06T11:16:28.458840", "lvl": "target", "msg": "<6>[ 0.202674] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.458907", "lvl": "target", "msg": "<6>[ 0.205877] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.458958", "lvl": "target", "msg": "<6>[ 0.206032] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.459002", "lvl": "target", "msg": "<6>[ 0.206152] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.459047", "lvl": "target", "msg": "<6>[ 0.206349] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.459090", "lvl": "target", "msg": "<6>[ 0.206588] TCP: Hash tables configured (established 8192 bind 8192)"} - {"dt": "2022-09-06T11:16:28.459133", "lvl": "target", "msg": "<6>[ 0.207667] MPTCP token hash table entries: 1024 (order: 2, 16384 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.459175", "lvl": "target", "msg": "<6>[ 0.207892] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.459216", "lvl": "target", "msg": "<6>[ 0.208079] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)"} - {"dt": "2022-09-06T11:16:28.459257", "lvl": "target", "msg": "<6>[ 0.208731] NET: Registered PF_UNIX/PF_LOCAL protocol family"} - {"dt": "2022-09-06T11:16:28.459299", "lvl": "target", "msg": "<6>[ 0.210982] RPC: Registered named UNIX socket transport module."} - {"dt": "2022-09-06T11:16:28.459340", "lvl": "target", "msg": "<6>[ 0.211086] RPC: Registered udp transport module."} - {"dt": "2022-09-06T11:16:28.459382", "lvl": "target", "msg": "<6>[ 0.211148] RPC: Registered tcp transport module."} - {"dt": "2022-09-06T11:16:28.459424", "lvl": "target", "msg": "<6>[ 0.211205] RPC: Registered tcp NFSv4.1 backchannel transport module."} - {"dt": "2022-09-06T11:16:28.459465", "lvl": "target", "msg": "<6>[ 0.211316] PCI: CLS 0 bytes, default 64"} - {"dt": "2022-09-06T11:16:28.459506", "lvl": "target", "msg": "<5>[ 0.212917] Initialise system trusted keyrings"} - {"dt": "2022-09-06T11:16:28.459547", "lvl": "target", "msg": "<6>[ 0.214058] workingset: timestamp_bits=14 max_order=18 bucket_order=4"} - {"dt": "2022-09-06T11:16:28.459588", "lvl": "target", "msg": "<6>[ 0.220671] squashfs: version 4.0 (2009/01/31) Phillip Lougher"} - {"dt": "2022-09-06T11:16:28.459628", "lvl": "target", "msg": "<5>[ 0.222297] NFS: Registering the id_resolver key type"} - {"dt": "2022-09-06T11:16:28.459669", "lvl": "target", "msg": "<5>[ 0.222551] Key type id_resolver registered"} - {"dt": "2022-09-06T11:16:28.459710", "lvl": "target", "msg": "<5>[ 0.222635] Key type id_legacy registered"} - {"dt": "2022-09-06T11:16:28.459751", "lvl": "target", "msg": "<6>[ 0.222875] nfs4filelayout_init: NFSv4 File Layout Driver Registering..."} - {"dt": "2022-09-06T11:16:28.459792", "lvl": "target", "msg": "<6>[ 0.223001] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering..."} - {"dt": "2022-09-06T11:16:28.459834", "lvl": "target", "msg": "<6>[ 0.223255] ntfs: driver 2.1.32 [Flags: R/O]."} - {"dt": "2022-09-06T11:16:28.459876", "lvl": "target", "msg": "<6>[ 0.268558] NET: Registered PF_ALG protocol family"} - {"dt": "2022-09-06T11:16:28.459917", "lvl": "target", "msg": "<5>[ 0.268719] Key type asymmetric registered"} - {"dt": "2022-09-06T11:16:28.459957", "lvl": "target", "msg": "<5>[ 0.268793] Asymmetric key parser 'x509' registered"} - {"dt": "2022-09-06T11:16:28.459998", "lvl": "target", "msg": "<6>[ 0.269247] bounce: pool size: 64 pages"} - {"dt": "2022-09-06T11:16:28.722863", "lvl": "target", "msg": "<6>[ 0.269506] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)"} - {"dt": "2022-09-06T11:16:28.722930", "lvl": "target", "msg": "<6>[ 0.269673] io scheduler mq-deadline registered"} - {"dt": "2022-09-06T11:16:28.722979", "lvl": "target", "msg": "<6>[ 0.269767] io scheduler kyber registered"} - {"dt": "2022-09-06T11:16:28.723024", "lvl": "target", "msg": "<6>[ 0.282737] pl061_gpio 9030000.pl061: PL061 GPIO chip registered"} - {"dt": "2022-09-06T11:16:28.723069", "lvl": "target", "msg": "<6>[ 0.286171] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:"} - {"dt": "2022-09-06T11:16:28.723112", "lvl": "target", "msg": "<6>[ 0.286653] pci-host-generic 3f000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000"} - {"dt": "2022-09-06T11:16:28.723154", "lvl": "target", "msg": "<6>[ 0.287037] pci-host-generic 3f000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000"} - {"dt": "2022-09-06T11:16:28.723196", "lvl": "target", "msg": "<6>[ 0.287579] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f]"} - {"dt": "2022-09-06T11:16:28.723237", "lvl": "target", "msg": "<6>[ 0.288328] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00"} - {"dt": "2022-09-06T11:16:28.723279", "lvl": "target", "msg": "<6>[ 0.288525] pci_bus 0000:00: root bus resource [bus 00-0f]"} - {"dt": "2022-09-06T11:16:28.723320", "lvl": "target", "msg": "<6>[ 0.288627] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]"} - {"dt": "2022-09-06T11:16:28.723360", "lvl": "target", "msg": "<6>[ 0.288737] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]"} - {"dt": "2022-09-06T11:16:28.723400", "lvl": "target", "msg": "<6>[ 0.289568] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000"} - {"dt": "2022-09-06T11:16:28.723440", "lvl": "target", "msg": "<6>[ 0.291775] PCI: bus0: Fast back to back transfers disabled"} - {"dt": "2022-09-06T11:16:28.723480", "lvl": "target", "msg": "<6>[ 0.489721] Serial: 8250/16550 driver, 5 ports, IRQ sharing enabled"} - {"dt": "2022-09-06T11:16:28.723521", "lvl": "target", "msg": "<6>[ 0.494459] SuperH (H)SCI(F) driver initialized"} - {"dt": "2022-09-06T11:16:28.723561", "lvl": "target", "msg": "<6>[ 0.495219] msm_serial: driver initialized"} - {"dt": "2022-09-06T11:16:28.723601", "lvl": "target", "msg": "<6>[ 0.495290] STMicroelectronics ASC driver initialized"} - {"dt": "2022-09-06T11:16:28.723642", "lvl": "target", "msg": "<6>[ 0.496029] STM32 USART driver initialized"} - {"dt": "2022-09-06T11:16:28.723682", "lvl": "target", "msg": "<6>[ 0.521847] brd: module loaded"} - {"dt": "2022-09-06T11:16:28.723721", "lvl": "target", "msg": "<6>[ 0.531034] loop: module loaded"} - {"dt": "2022-09-06T11:16:28.723762", "lvl": "target", "msg": "<6>[ 0.531576] virtio_blk virtio0: 1/0/0 default/read/poll queues"} - {"dt": "2022-09-06T11:16:28.723804", "lvl": "target", "msg": "<5>[ 0.533583] virtio_blk virtio0: [vda] 2371280 512-byte logical blocks (1.21 GB/1.13 GiB)"} - {"dt": "2022-09-06T11:16:28.723845", "lvl": "target", "msg": "<5>[ 0.551066] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]"} - {"dt": "2022-09-06T11:16:28.723887", "lvl": "target", "msg": "<6>[ 0.552778] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"} - {"dt": "2022-09-06T11:16:28.723927", "lvl": "target", "msg": "<6>[ 0.553542] Intel/Sharp Extended Query Table at 0x0031"} - {"dt": "2022-09-06T11:16:28.723966", "lvl": "target", "msg": "<6>[ 0.554410] Using buffer write method"} - {"dt": "2022-09-06T11:16:29.094505", "lvl": "target", "msg": "<7>[ 0.554954] erase region 0: offset=0x0,size=0x40000,blocks=256"} - {"dt": "2022-09-06T11:16:29.094577", "lvl": "target", "msg": "<5>[ 0.556857] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]"} - {"dt": "2022-09-06T11:16:29.094634", "lvl": "target", "msg": "<6>[ 0.557545] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000"} - {"dt": "2022-09-06T11:16:29.094683", "lvl": "target", "msg": "<6>[ 0.557866] Intel/Sharp Extended Query Table at 0x0031"} - {"dt": "2022-09-06T11:16:29.094729", "lvl": "target", "msg": "<6>[ 0.558434] Using buffer write method"} - {"dt": "2022-09-06T11:16:29.094773", "lvl": "target", "msg": "<7>[ 0.558590] erase region 0: offset=0x0,size=0x40000,blocks=256"} - {"dt": "2022-09-06T11:16:29.094818", "lvl": "target", "msg": "<5>[ 0.558837] Concatenating MTD devices:"} - {"dt": "2022-09-06T11:16:29.094861", "lvl": "target", "msg": "<5>[ 0.558982] (0): \\\"0.flash\\\""} - {"dt": "2022-09-06T11:16:29.094904", "lvl": "target", "msg": "<5>[ 0.559232] (1): \\\"0.flash\\\""} - {"dt": "2022-09-06T11:16:29.094947", "lvl": "target", "msg": "<5>[ 0.559365] into device \\\"0.flash\\\""} - {"dt": "2022-09-06T11:16:29.094989", "lvl": "target", "msg": "<6>[ 0.591771] CAN device driver interface"} - {"dt": "2022-09-06T11:16:29.095030", "lvl": "target", "msg": "<6>[ 0.593437] bgmac_bcma: Broadcom 47xx GBit MAC driver loaded"} - {"dt": "2022-09-06T11:16:29.095071", "lvl": "target", "msg": "<6>[ 0.594808] e1000e: Intel(R) PRO/1000 Network Driver"} - {"dt": "2022-09-06T11:16:29.095112", "lvl": "target", "msg": "<6>[ 0.594941] e1000e: Copyright(c) 1999 - 2015 Intel Corporation."} - {"dt": "2022-09-06T11:16:29.095153", "lvl": "target", "msg": "<6>[ 0.595205] igb: Intel(R) Gigabit Ethernet Network Driver"} - {"dt": "2022-09-06T11:16:29.095194", "lvl": "target", "msg": "<6>[ 0.595345] igb: Copyright (c) 2007-2014 Intel Corporation."} - {"dt": "2022-09-06T11:16:29.095235", "lvl": "target", "msg": "<6>[ 0.596302] QLogic FastLinQ 4xxxx Core Module qed"} - {"dt": "2022-09-06T11:16:29.095277", "lvl": "target", "msg": "<6>[ 0.596524] qede init: QLogic FastLinQ 4xxxx Ethernet Driver qede"} - {"dt": "2022-09-06T11:16:29.095317", "lvl": "target", "msg": "<6>[ 0.601492] pegasus: Pegasus/Pegasus II USB Ethernet driver"} - {"dt": "2022-09-06T11:16:29.095358", "lvl": "target", "msg": "<6>[ 0.602059] usbcore: registered new interface driver pegasus"} - {"dt": "2022-09-06T11:16:29.095399", "lvl": "target", "msg": "<6>[ 0.602297] usbcore: registered new interface driver asix"} - {"dt": "2022-09-06T11:16:29.095440", "lvl": "target", "msg": "<6>[ 0.602487] usbcore: registered new interface driver ax88179_178a"} - {"dt": "2022-09-06T11:16:29.095480", "lvl": "target", "msg": "<6>[ 0.602694] usbcore: registered new interface driver cdc_ether"} - {"dt": "2022-09-06T11:16:29.095521", "lvl": "target", "msg": "<6>[ 0.602903] usbcore: registered new interface driver smsc75xx"} - {"dt": "2022-09-06T11:16:29.095560", "lvl": "target", "msg": "<6>[ 0.603107] usbcore: registered new interface driver smsc95xx"} - {"dt": "2022-09-06T11:16:29.095600", "lvl": "target", "msg": "<6>[ 0.603301] usbcore: registered new interface driver net1080"} - {"dt": "2022-09-06T11:16:29.095640", "lvl": "target", "msg": "<6>[ 0.603489] usbcore: registered new interface driver cdc_subset"} - {"dt": "2022-09-06T11:16:29.095681", "lvl": "target", "msg": "<6>[ 0.603677] usbcore: registered new interface driver zaurus"} - {"dt": "2022-09-06T11:16:29.095722", "lvl": "target", "msg": "<6>[ 0.603938] usbcore: registered new interface driver cdc_ncm"} - {"dt": "2022-09-06T11:16:29.095763", "lvl": "target", "msg": "<6>[ 0.607183] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver"} - {"dt": "2022-09-06T11:16:29.095803", "lvl": "target", "msg": "<6>[ 0.607460] ehci-pci: EHCI PCI platform driver"} - {"dt": "2022-09-06T11:16:29.599015", "lvl": "target", "msg": "<6>[ 0.607791] ehci-platform: EHCI generic platform driver"} - {"dt": "2022-09-06T11:16:29.599120", "lvl": "target", "msg": "<6>[ 0.608178] ehci-omap: OMAP-EHCI Host Controller driver"} - {"dt": "2022-09-06T11:16:29.599172", "lvl": "target", "msg": "<6>[ 0.608496] ehci-orion: EHCI orion driver"} - {"dt": "2022-09-06T11:16:29.599216", "lvl": "target", "msg": "<6>[ 0.608805] SPEAr-ehci: EHCI SPEAr driver"} - {"dt": "2022-09-06T11:16:29.599261", "lvl": "target", "msg": "<6>[ 0.609200] ehci-st: EHCI STMicroelectronics driver"} - {"dt": "2022-09-06T11:16:29.599306", "lvl": "target", "msg": "<6>[ 0.609643] ehci-atmel: EHCI Atmel driver"} - {"dt": "2022-09-06T11:16:29.599349", "lvl": "target", "msg": "<6>[ 0.609946] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver"} - {"dt": "2022-09-06T11:16:29.599391", "lvl": "target", "msg": "<6>[ 0.610183] ohci-pci: OHCI PCI platform driver"} - {"dt": "2022-09-06T11:16:29.599433", "lvl": "target", "msg": "<6>[ 0.610509] ohci-platform: OHCI generic platform driver"} - {"dt": "2022-09-06T11:16:29.599476", "lvl": "target", "msg": "<6>[ 0.610870] SPEAr-ohci: OHCI SPEAr driver"} - {"dt": "2022-09-06T11:16:29.599518", "lvl": "target", "msg": "<6>[ 0.611158] ohci-st: OHCI STMicroelectronics driver"} - {"dt": "2022-09-06T11:16:29.599559", "lvl": "target", "msg": "<6>[ 0.611480] ohci-atmel: OHCI Atmel driver"} - {"dt": "2022-09-06T11:16:29.599601", "lvl": "target", "msg": "<6>[ 0.612587] usbcore: registered new interface driver usb-storage"} - {"dt": "2022-09-06T11:16:29.599642", "lvl": "target", "msg": "<6>[ 0.620821] rtc-pl031 9010000.pl031: registered as rtc0"} - {"dt": "2022-09-06T11:16:29.599683", "lvl": "target", "msg": "<6>[ 0.621291] rtc-pl031 9010000.pl031: setting system clock to 2022-09-06T11:16:28 UTC (1662462988)"} - {"dt": "2022-09-06T11:16:29.599723", "lvl": "target", "msg": "<6>[ 0.622684] i2c_dev: i2c /dev entries driver"} - {"dt": "2022-09-06T11:16:29.599764", "lvl": "target", "msg": "<6>[ 0.633484] sdhci: Secure Digital Host Controller Interface driver"} - {"dt": "2022-09-06T11:16:29.599806", "lvl": "target", "msg": "<6>[ 0.633574] sdhci: Copyright(c) Pierre Ossman"} - {"dt": "2022-09-06T11:16:29.599850", "lvl": "target", "msg": "<6>[ 0.634798] Synopsys Designware Multimedia Card Interface Driver"} - {"dt": "2022-09-06T11:16:29.599891", "lvl": "target", "msg": "<6>[ 0.635926] sdhci-pltfm: SDHCI platform and OF driver helper"} - {"dt": "2022-09-06T11:16:29.599932", "lvl": "target", "msg": "<6>[ 0.638066] ledtrig-cpu: registered to indicate activity on CPUs"} - {"dt": "2022-09-06T11:16:29.599980", "lvl": "target", "msg": "<6>[ 0.645551] usbcore: registered new interface driver usbhid"} - {"dt": "2022-09-06T11:16:29.600021", "lvl": "target", "msg": "<6>[ 0.645645] usbhid: USB HID core driver"} - {"dt": "2022-09-06T11:16:29.600062", "lvl": "target", "msg": "<6>[ 0.648953] cs_system_cfg: CoreSight Configuration manager initialised"} - {"dt": "2022-09-06T11:16:29.600102", "lvl": "target", "msg": "<6>[ 0.651523] NET: Registered PF_INET6 protocol family"} - {"dt": "2022-09-06T11:16:29.600143", "lvl": "target", "msg": "<6>[ 0.655876] Segment Routing with IPv6"} - {"dt": "2022-09-06T11:16:29.600183", "lvl": "target", "msg": "<6>[ 0.656035] In-situ OAM (IOAM) with IPv6"} - {"dt": "2022-09-06T11:16:29.600223", "lvl": "target", "msg": "<6>[ 0.656321] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver"} - {"dt": "2022-09-06T11:16:29.600265", "lvl": "target", "msg": "<6>[ 0.657859] NET: Registered PF_PACKET protocol family"} - {"dt": "2022-09-06T11:16:29.600306", "lvl": "target", "msg": "<6>[ 0.658017] can: controller area network core"} - {"dt": "2022-09-06T11:16:29.600348", "lvl": "target", "msg": "<6>[ 0.658346] NET: Registered PF_CAN protocol family"} - {"dt": "2022-09-06T11:16:29.600389", "lvl": "target", "msg": "<6>[ 0.658434] can: raw protocol"} - {"dt": "2022-09-06T11:16:30.253391", "lvl": "target", "msg": "<6>[ 0.658543] can: broadcast manager protocol"} - {"dt": "2022-09-06T11:16:30.253465", "lvl": "target", "msg": "<6>[ 0.658670] can: netlink gateway - max_hops=1"} - {"dt": "2022-09-06T11:16:30.253516", "lvl": "target", "msg": "<5>[ 0.659682] Key type dns_resolver registered"} - {"dt": "2022-09-06T11:16:30.253560", "lvl": "target", "msg": "<6>[ 0.659925] ThumbEE CPU extension supported."} - {"dt": "2022-09-06T11:16:30.253603", "lvl": "target", "msg": "<5>[ 0.660027] Registering SWP/SWPB emulation handler"} - {"dt": "2022-09-06T11:16:30.253643", "lvl": "target", "msg": "<6>[ 0.661297] registered taskstats version 1"} - {"dt": "2022-09-06T11:16:30.253683", "lvl": "target", "msg": "<5>[ 0.661382] Loading compiled-in X.509 certificates"} - {"dt": "2022-09-06T11:16:30.253723", "lvl": "target", "msg": "<6>[ 0.701685] input: gpio-keys as /devices/platform/gpio-keys/input/input0"} - {"dt": "2022-09-06T11:16:30.253764", "lvl": "target", "msg": "<6>[ 0.707296] uart-pl011 9000000.pl011: no DMA platform data"} - {"dt": "2022-09-06T11:16:30.253806", "lvl": "target", "msg": "<6>[ 0.724743] EXT4-fs (vda): mounted filesystem with ordered data mode. Quota mode: disabled."} - {"dt": "2022-09-06T11:16:30.253847", "lvl": "target", "msg": "<6>[ 0.725629] VFS: Mounted root (ext4 filesystem) readonly on device 254:0."} - {"dt": "2022-09-06T11:16:30.253888", "lvl": "target", "msg": "<6>[ 0.728218] devtmpfs: mounted"} - {"dt": "2022-09-06T11:16:30.253931", "lvl": "target", "msg": "<6>[ 0.797945] Freeing unused kernel image (initmem) memory: 2048K"} - {"dt": "2022-09-06T11:16:30.253974", "lvl": "target", "msg": "<6>[ 0.849199] Run /sbin/init as init process"} - {"dt": "2022-09-06T11:16:30.254015", "lvl": "target", "msg": "<7>[ 0.849303] with arguments:"} - {"dt": "2022-09-06T11:16:30.254056", "lvl": "target", "msg": "<7>[ 0.849373] /sbin/init"} - {"dt": "2022-09-06T11:16:30.254098", "lvl": "target", "msg": "<7>[ 0.849426] verbose"} - {"dt": "2022-09-06T11:16:30.254139", "lvl": "target", "msg": "<7>[ 0.849467] with environment:"} - {"dt": "2022-09-06T11:16:30.254179", "lvl": "target", "msg": "<7>[ 0.849518] HOME=/"} - {"dt": "2022-09-06T11:16:30.254221", "lvl": "target", "msg": "<7>[ 0.849558] TERM=linux"} - {"dt": "2022-09-06T11:16:30.254262", "lvl": "target", "msg": "<30>[ 1.056775] 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": "2022-09-06T11:16:30.254307", "lvl": "target", "msg": "<31>[ 1.059234] systemd[1]: No virtualization found in DMI vendor table."} - {"dt": "2022-09-06T11:16:30.254349", "lvl": "target", "msg": "<31>[ 1.060102] systemd[1]: Unable to read /sys/firmware/dmi/entries/0-0/raw, using the virtualization information found in DMI vendor table, ignoring: No such file or directory"} - {"dt": "2022-09-06T11:16:30.254393", "lvl": "target", "msg": "<31>[ 1.061393] systemd[1]: UML virtualization not found in /proc/cpuinfo."} - {"dt": "2022-09-06T11:16:30.254434", "lvl": "target", "msg": "<31>[ 1.061738] systemd[1]: Virtualization XEN not found, /proc/xen does not exist"} - {"dt": "2022-09-06T11:16:30.254475", "lvl": "target", "msg": "<31>[ 1.061915] systemd[1]: No virtualization found in CPUID"} - {"dt": "2022-09-06T11:16:31.076191", "lvl": "target", "msg": "<31>[ 1.064436] systemd[1]: Virtualization QEMU: \\\"fw-cfg\\\" present in /proc/device-tree/fw-cfg@9020000"} - {"dt": "2022-09-06T11:16:31.076266", "lvl": "target", "msg": "<31>[ 1.064747] systemd[1]: Found VM virtualization qemu"} - {"dt": "2022-09-06T11:16:31.076316", "lvl": "target", "msg": "<30>[ 1.064934] systemd[1]: Detected virtualization qemu."} - {"dt": "2022-09-06T11:16:31.076359", "lvl": "target", "msg": "<30>[ 1.065387] systemd[1]: Detected architecture arm."} - {"dt": "2022-09-06T11:16:31.076400", "lvl": "target", "msg": "<31>[ 1.065816] systemd[1]: Detected initialized system, this is not the first boot."} - {"dt": "2022-09-06T11:16:31.076440", "lvl": "target", "msg": "<31>[ 1.068675] systemd[1]: Mounting cgroup to /sys/fs/cgroup/net_prio of type cgroup with options net_prio."} - {"dt": "2022-09-06T11:16:31.076481", "lvl": "target", "msg": "<31>[ 1.071200] systemd[1]: Mounting cgroup to /sys/fs/cgroup/freezer of type cgroup with options freezer."} - {"dt": "2022-09-06T11:16:31.076521", "lvl": "target", "msg": "<31>[ 1.072518] systemd[1]: Mounting cgroup to /sys/fs/cgroup/cpu of type cgroup with options cpu."} - {"dt": "2022-09-06T11:16:31.076561", "lvl": "target", "msg": "<31>[ 1.073908] systemd[1]: Mounting cgroup to /sys/fs/cgroup/memory of type cgroup with options memory."} - {"dt": "2022-09-06T11:16:31.076601", "lvl": "target", "msg": "<31>[ 1.111786] systemd[1]: Mounting cgroup to /sys/fs/cgroup/blkio of type cgroup with options blkio."} - {"dt": "2022-09-06T11:16:31.076641", "lvl": "target", "msg": ""} - {"dt": "2022-09-06T11:16:31.076684", "lvl": "target", "msg": "Welcome to [1mLinux-Kernel-Functional-Testing nodistro.0[0m!"} - {"dt": "2022-09-06T11:16:31.076725", "lvl": "target", "msg": ""} - {"dt": "2022-09-06T11:16:31.076767", "lvl": "target", "msg": "<30>[ 1.161397] systemd[1]: Hostname set to ."} - {"dt": "2022-09-06T11:16:31.076810", "lvl": "target", "msg": "<30>[ 1.167155] systemd[1]: Initializing machine ID from random generator."} - {"dt": "2022-09-06T11:16:31.076851", "lvl": "target", "msg": "<31>[ 1.170814] systemd[1]: Bind-mounting /run/machine-id on /etc/machine-id (MS_BIND \\\"\\\")..."} - {"dt": "2022-09-06T11:16:31.076893", "lvl": "target", "msg": "<30>[ 1.171789] systemd[1]: Installed transient /etc/machine-id file."} - {"dt": "2022-09-06T11:16:31.076936", "lvl": "target", "msg": "<31>[ 1.172178] systemd[1]: Remounting /etc/machine-id (MS_RDONLY|MS_REMOUNT|MS_BIND \\\"\\\")..."} - {"dt": "2022-09-06T11:16:31.076977", "lvl": "target", "msg": "<31>[ 1.195255] systemd[1]: Successfully added address 127.0.0.1 to loopback interface"} - {"dt": "2022-09-06T11:16:31.077017", "lvl": "target", "msg": "<31>[ 1.195806] systemd[1]: Successfully added address ::1 to loopback interface"} - {"dt": "2022-09-06T11:16:31.077059", "lvl": "target", "msg": "<31>[ 1.196256] systemd[1]: Successfully brought loopback interface up"} - {"dt": "2022-09-06T11:16:31.077099", "lvl": "target", "msg": "<31>[ 1.199027] systemd[1]: Setting '/proc/sys/fs/file-max' to '2147483647"} - {"dt": "2022-09-06T11:16:31.077139", "lvl": "target", "msg": "<31>[ 1.199335] systemd[1]: '"} - {"dt": "2022-09-06T11:16:31.077180", "lvl": "target", "msg": "<31>[ 1.200383] systemd[1]: Setting '/proc/sys/fs/nr_open' to '2147483644"} - {"dt": "2022-09-06T11:16:31.077221", "lvl": "target", "msg": "<31>[ 1.200543] systemd[1]: '"} - {"dt": "2022-09-06T11:16:31.077261", "lvl": "target", "msg": "<31>[ 1.201360] systemd[1]: Couldn't write fs.nr_open as 2147483644, halving it."} - {"dt": "2022-09-06T11:16:31.077303", "lvl": "target", "msg": "<31>[ 1.201787] systemd[1]: Setting '/proc/sys/fs/nr_open' to '1073741820"} - {"dt": "2022-09-06T11:16:31.077345", "lvl": "target", "msg": "<31>[ 1.201937] systemd[1]: '"} - {"dt": "2022-09-06T11:16:32.093630", "lvl": "target", "msg": "<31>[ 1.202642] systemd[1]: Couldn't write fs.nr_open as 1073741820, halving it."} - {"dt": "2022-09-06T11:16:32.093758", "lvl": "target", "msg": "<31>[ 1.203038] systemd[1]: Setting '/proc/sys/fs/nr_open' to '536870908"} - {"dt": "2022-09-06T11:16:32.093813", "lvl": "target", "msg": "<31>[ 1.203181] systemd[1]: '"} - {"dt": "2022-09-06T11:16:32.093859", "lvl": "target", "msg": "<31>[ 1.203572] systemd[1]: Successfully bumped fs.nr_open to 536870908"} - {"dt": "2022-09-06T11:16:32.093901", "lvl": "target", "msg": "<31>[ 1.206246] systemd[1]: Found cgroup2 on /sys/fs/cgroup/unified, unified hierarchy for systemd controller"} - {"dt": "2022-09-06T11:16:32.093943", "lvl": "target", "msg": "<31>[ 1.217192] systemd[1]: Found cgroup2 on /sys/fs/cgroup/unified, unified hierarchy for systemd controller"} - {"dt": "2022-09-06T11:16:32.093984", "lvl": "target", "msg": "<31>[ 1.217460] systemd[1]: Unified cgroup hierarchy is located at /sys/fs/cgroup/unified. Controllers are on legacy hierarchies."} - {"dt": "2022-09-06T11:16:32.094026", "lvl": "target", "msg": "<31>[ 1.292656] systemd[1]: Can't load kernel CGROUP SKB BPF program, BPF firewalling is not supported: Function not implemented"} - {"dt": "2022-09-06T11:16:32.094067", "lvl": "target", "msg": "<31>[ 1.293074] systemd[1]: Can't load kernel CGROUP DEVICE BPF program, BPF device control is not supported: Function not implemented"} - {"dt": "2022-09-06T11:16:32.094111", "lvl": "target", "msg": "<31>[ 1.293393] systemd[1]: Controller 'cpu' supported: yes"} - {"dt": "2022-09-06T11:16:32.094155", "lvl": "target", "msg": "<31>[ 1.293550] systemd[1]: Controller 'cpuacct' supported: yes"} - {"dt": "2022-09-06T11:16:32.094198", "lvl": "target", "msg": "<31>[ 1.293704] systemd[1]: Controller 'cpuset' supported: no"} - {"dt": "2022-09-06T11:16:32.094241", "lvl": "target", "msg": "<31>[ 1.293845] systemd[1]: Controller 'io' supported: no"} - {"dt": "2022-09-06T11:16:32.094283", "lvl": "target", "msg": "<31>[ 1.293975] systemd[1]: Controller 'blkio' supported: yes"} - {"dt": "2022-09-06T11:16:32.094325", "lvl": "target", "msg": "<31>[ 1.294108] systemd[1]: Controller 'memory' supported: yes"} - {"dt": "2022-09-06T11:16:32.094366", "lvl": "target", "msg": "<31>[ 1.294245] systemd[1]: Controller 'devices' supported: no"} - {"dt": "2022-09-06T11:16:32.094407", "lvl": "target", "msg": "<31>[ 1.294382] systemd[1]: Controller 'pids' supported: no"} - {"dt": "2022-09-06T11:16:32.094448", "lvl": "target", "msg": "<31>[ 1.294513] systemd[1]: Controller 'bpf-firewall' supported: no"} - {"dt": "2022-09-06T11:16:32.094493", "lvl": "target", "msg": "<31>[ 1.294651] systemd[1]: Controller 'bpf-devices' supported: no"} - {"dt": "2022-09-06T11:16:32.094537", "lvl": "target", "msg": "<31>[ 1.294801] systemd[1]: Controller 'bpf-foreign' supported: no"} - {"dt": "2022-09-06T11:16:32.094581", "lvl": "target", "msg": "<31>[ 1.294943] systemd[1]: Controller 'bpf-socket-bind' supported: no"} - {"dt": "2022-09-06T11:16:32.094636", "lvl": "target", "msg": "<31>[ 1.295085] systemd[1]: Controller 'bpf-restrict-network-interfaces' supported: no"} - {"dt": "2022-09-06T11:16:32.094682", "lvl": "target", "msg": "<31>[ 1.296094] systemd[1]: Set up TFD_TIMER_CANCEL_ON_SET timerfd."} - {"dt": "2022-09-06T11:16:32.094725", "lvl": "target", "msg": "<31>[ 1.296388] systemd[1]: Failed to stat /etc/localtime, ignoring: No such file or directory"} - {"dt": "2022-09-06T11:16:33.328124", "lvl": "target", "msg": "<31>[ 1.296749] systemd[1]: /etc/localtime doesn't exist yet, watching /etc instead."} - {"dt": "2022-09-06T11:16:33.328197", "lvl": "target", "msg": "<31>[ 1.302431] systemd[1]: Enabling (yes) showing of status (commandline)."} - {"dt": "2022-09-06T11:16:33.328251", "lvl": "target", "msg": "<31>[ 1.311496] systemd[1]: Successfully forked off '(sd-executor)' as PID 150."} - {"dt": "2022-09-06T11:16:33.328297", "lvl": "target", "msg": "<31>[ 1.339684] systemd[150]: Successfully forked off '(direxec)' as PID 151."} - {"dt": "2022-09-06T11:16:33.328342", "lvl": "target", "msg": "<31>[ 1.344548] systemd[150]: Successfully forked off '(direxec)' as PID 152."} - {"dt": "2022-09-06T11:16:33.328384", "lvl": "target", "msg": "<31>[ 1.351507] systemd[150]: Successfully forked off '(direxec)' as PID 153."} - {"dt": "2022-09-06T11:16:33.328427", "lvl": "target", "msg": "<31>[ 1.356088] systemd[150]: Successfully forked off '(direxec)' as PID 154."} - {"dt": "2022-09-06T11:16:33.328469", "lvl": "target", "msg": "<31>[ 1.381421] systemd[150]: Successfully forked off '(direxec)' as PID 155."} - {"dt": "2022-09-06T11:16:33.328511", "lvl": "target", "msg": "<31>[ 1.384999] systemd[150]: Successfully forked off '(direxec)' as PID 156."} - {"dt": "2022-09-06T11:16:33.328552", "lvl": "target", "msg": "<31>[ 1.388610] systemd[150]: Successfully forked off '(direxec)' as PID 157."} - {"dt": "2022-09-06T11:16:33.328594", "lvl": "target", "msg": "<31>[ 1.406270] systemd[150]: Successfully forked off '(direxec)' as PID 158."} - {"dt": "2022-09-06T11:16:33.328634", "lvl": "target", "msg": "<31>[ 1.408231] systemd[150]: Successfully forked off '(direxec)' as PID 159."} - {"dt": "2022-09-06T11:16:33.328676", "lvl": "target", "msg": "<31>[ 1.548132] systemd-hibernate-resume-generator[155]: Not running in an initrd, quitting."} - {"dt": "2022-09-06T11:16:33.328718", "lvl": "target", "msg": "<31>[ 1.554700] systemd-fstab-generator[152]: Parsing /etc/fstab..."} - {"dt": "2022-09-06T11:16:33.328759", "lvl": "target", "msg": "<31>[ 1.571389] systemd-fstab-generator[152]: Found entry what=/dev/root where=/ type=auto makefs=no growfs=no noauto=no nofail=no"} - {"dt": "2022-09-06T11:16:33.328802", "lvl": "target", "msg": "<31>[ 1.578115] systemd-fstab-generator[152]: Found entry what=proc where=/proc type=proc makefs=no growfs=no noauto=no nofail=no"} - {"dt": "2022-09-06T11:16:33.328844", "lvl": "target", "msg": "<31>[ 1.578846] systemd-fstab-generator[152]: Found entry what=devpts where=/dev/pts type=devpts makefs=no growfs=no noauto=no nofail=no"} - {"dt": "2022-09-06T11:16:33.328885", "lvl": "target", "msg": "<31>[ 1.589125] systemd-gpt-auto-generator[154]: Disabling root partition auto-detection, root= is defined."} - {"dt": "2022-09-06T11:16:33.328928", "lvl": "target", "msg": "<31>[ 1.596180] systemd-rc-local-generator[156]: /etc/rc.local does not exist, skipping."} - {"dt": "2022-09-06T11:16:33.328970", "lvl": "target", "msg": "<31>[ 1.596658] systemd-getty-generator[153]: Automatically adding serial getty for /dev/ttyAMA0."} - {"dt": "2022-09-06T11:16:34.798102", "lvl": "target", "msg": "<31>[ 1.599709] systemd-fstab-generator[152]: Found entry what=tmpfs where=/run type=tmpfs makefs=no growfs=no noauto=no nofail=no"} - {"dt": "2022-09-06T11:16:34.798248", "lvl": "target", "msg": "<31>[ 1.600439] systemd-fstab-generator[152]: Found entry what=tmpfs where=/var/volatile type=tmpfs makefs=no growfs=no noauto=no nofail=no"} - {"dt": "2022-09-06T11:16:34.798302", "lvl": "target", "msg": "<31>[ 1.607064] systemd[150]: /lib/systemd/system-generators/systemd-system-update-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798350", "lvl": "target", "msg": "<31>[ 1.609758] systemd-gpt-auto-generator[154]: Failed to chase block device, ignoring: No such file or directory"} - {"dt": "2022-09-06T11:16:34.798394", "lvl": "target", "msg": "<31>[ 1.612944] systemd[150]: /lib/systemd/system-generators/systemd-sysv-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798436", "lvl": "target", "msg": "<31>[ 1.619506] systemd-gpt-auto-generator[154]: virtio0: Parent doesn't have a device type, ignoring: No such file or directory"} - {"dt": "2022-09-06T11:16:34.798478", "lvl": "target", "msg": "<31>[ 1.623491] systemd[150]: /lib/systemd/system-generators/systemd-gpt-auto-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798520", "lvl": "target", "msg": "<31>[ 1.625349] systemd[150]: /lib/systemd/system-generators/systemd-fstab-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798562", "lvl": "target", "msg": "<31>[ 1.625712] systemd[150]: /lib/systemd/system-generators/systemd-run-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798607", "lvl": "target", "msg": "<31>[ 1.625989] systemd[150]: /lib/systemd/system-generators/systemd-getty-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798653", "lvl": "target", "msg": "<31>[ 1.626305] systemd[150]: /lib/systemd/system-generators/systemd-debug-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798696", "lvl": "target", "msg": "<31>[ 1.626581] systemd[150]: /lib/systemd/system-generators/systemd-rc-local-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798743", "lvl": "target", "msg": "<31>[ 1.626852] systemd[150]: /lib/systemd/system-generators/systemd-hibernate-resume-generator succeeded."} - {"dt": "2022-09-06T11:16:34.798785", "lvl": "target", "msg": "<31>[ 1.628965] systemd[1]: (sd-executor) succeeded."} - {"dt": "2022-09-06T11:16:34.798828", "lvl": "target", "msg": "<31>[ 1.630537] systemd[1]: Looking for unit files in (higher priority first):"} - {"dt": "2022-09-06T11:16:34.798870", "lvl": "target", "msg": "<31>[ 1.630695] systemd[1]: \t/etc/systemd/system.control"} - {"dt": "2022-09-06T11:16:34.798912", "lvl": "target", "msg": "<31>[ 1.630830] systemd[1]: \t/run/systemd/system.control"} - {"dt": "2022-09-06T11:16:34.798953", "lvl": "target", "msg": "<31>[ 1.630962] systemd[1]: \t/run/systemd/transient"} - {"dt": "2022-09-06T11:16:34.798994", "lvl": "target", "msg": "<31>[ 1.631083] systemd[1]: \t/run/systemd/generator.early"} - {"dt": "2022-09-06T11:16:34.799035", "lvl": "target", "msg": "<31>[ 1.631212] systemd[1]: \t/etc/systemd/system"} - {"dt": "2022-09-06T11:16:34.799077", "lvl": "target", "msg": "<31>[ 1.631354] systemd[1]: \t/etc/systemd/system.attached"} - {"dt": "2022-09-06T11:16:34.799118", "lvl": "target", "msg": "<31>[ 1.631479] systemd[1]: \t/run/systemd/system"} - {"dt": "2022-09-06T11:16:34.799159", "lvl": "target", "msg": "<31>[ 1.631596] systemd[1]: \t/run/systemd/system.attached"} - {"dt": "2022-09-06T11:16:36.507128", "lvl": "target", "msg": "<31>[ 1.631719] systemd[1]: \t/run/systemd/generator"} - {"dt": "2022-09-06T11:16:36.507286", "lvl": "target", "msg": "<31>[ 1.631839] systemd[1]: \t/usr/local/lib/systemd/system"} - {"dt": "2022-09-06T11:16:36.507340", "lvl": "target", "msg": "<31>[ 1.631967] systemd[1]: \t/lib/systemd/system"} - {"dt": "2022-09-06T11:16:36.507387", "lvl": "target", "msg": "<31>[ 1.632085] systemd[1]: \t/usr/lib/systemd/system"} - {"dt": "2022-09-06T11:16:36.507431", "lvl": "target", "msg": "<31>[ 1.632213] systemd[1]: \t/run/systemd/generator.late"} - {"dt": "2022-09-06T11:16:36.507475", "lvl": "target", "msg": "<31>[ 1.658775] systemd[1]: Modification times have changed, need to update cache."} - {"dt": "2022-09-06T11:16:36.507519", "lvl": "target", "msg": "<31>[ 1.663448] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/dbus-org.freedesktop.timesync1.service \u2192 systemd-timesyncd.service"} - {"dt": "2022-09-06T11:16:36.507566", "lvl": "target", "msg": "<31>[ 1.664798] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/ctrl-alt-del.target \u2192 reboot.target"} - {"dt": "2022-09-06T11:16:36.507611", "lvl": "target", "msg": "<31>[ 1.665849] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/dbus-org.bluez.service \u2192 bluetooth.service"} - {"dt": "2022-09-06T11:16:36.507656", "lvl": "target", "msg": "<31>[ 1.666921] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/syslog.service \u2192 busybox-syslog.service"} - {"dt": "2022-09-06T11:16:36.507700", "lvl": "target", "msg": "<31>[ 1.667879] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/dbus-org.neard.service \u2192 neard.service"} - {"dt": "2022-09-06T11:16:36.507744", "lvl": "target", "msg": "<31>[ 1.668856] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/dbus-org.freedesktop.Avahi.service \u2192 avahi-daemon.service"} - {"dt": "2022-09-06T11:16:36.507788", "lvl": "target", "msg": "<31>[ 1.669958] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/default.target \u2192 multi-user.target"} - {"dt": "2022-09-06T11:16:36.507830", "lvl": "target", "msg": "<31>[ 1.671276] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/dbus-org.freedesktop.network1.service \u2192 systemd-networkd.service"} - {"dt": "2022-09-06T11:16:36.507873", "lvl": "target", "msg": "<31>[ 1.672330] systemd[1]: unit_file_build_name_map: alias: /etc/systemd/system/dbus-org.freedesktop.resolve1.service \u2192 systemd-resolved.service"} - {"dt": "2022-09-06T11:16:36.507916", "lvl": "target", "msg": "<31>[ 1.673619] systemd[1]: unit_file_build_name_map: normal unit file: /run/systemd/generator/var-volatile.mount"} - {"dt": "2022-09-06T11:16:36.507959", "lvl": "target", "msg": "<31>[ 1.674039] systemd[1]: unit_file_build_name_map: normal unit file: /run/systemd/generator/-.mount"} - {"dt": "2022-09-06T11:16:36.508001", "lvl": "target", "msg": "<31>[ 1.679750] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/paths.target"} - {"dt": "2022-09-06T11:16:38.514094", "lvl": "target", "msg": "<31>[ 1.680041] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/ofono.service"} - {"dt": "2022-09-06T11:16:38.514281", "lvl": "target", "msg": "<31>[ 1.680255] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/logrotate.timer"} - {"dt": "2022-09-06T11:16:38.514338", "lvl": "target", "msg": "<31>[ 1.680470] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-journal-catalog-update.service"} - {"dt": "2022-09-06T11:16:38.514390", "lvl": "target", "msg": "<31>[ 1.680726] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/kexec.target"} - {"dt": "2022-09-06T11:16:38.514440", "lvl": "target", "msg": "<31>[ 1.680930] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-journald.socket"} - {"dt": "2022-09-06T11:16:38.514485", "lvl": "target", "msg": "<31>[ 1.681169] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-journald@.service"} - {"dt": "2022-09-06T11:16:38.514530", "lvl": "target", "msg": "<31>[ 1.681393] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/sys-kernel-debug.mount"} - {"dt": "2022-09-06T11:16:38.514576", "lvl": "target", "msg": "<31>[ 1.681599] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/sockets.target"} - {"dt": "2022-09-06T11:16:38.514630", "lvl": "target", "msg": "<31>[ 1.681802] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/swap.target"} - {"dt": "2022-09-06T11:16:38.514678", "lvl": "target", "msg": "<31>[ 1.682002] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/sys-fs-fuse-connections.mount"} - {"dt": "2022-09-06T11:16:38.514723", "lvl": "target", "msg": "<31>[ 1.682231] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-journald-audit.socket"} - {"dt": "2022-09-06T11:16:38.514768", "lvl": "target", "msg": "<31>[ 1.682455] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/emergency.target"} - {"dt": "2022-09-06T11:16:38.514813", "lvl": "target", "msg": "<31>[ 1.682745] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/umount.target"} - {"dt": "2022-09-06T11:16:38.514856", "lvl": "target", "msg": "<31>[ 1.682951] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/busybox-syslog.service"} - {"dt": "2022-09-06T11:16:38.514900", "lvl": "target", "msg": "<31>[ 1.683179] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/getty@.service"} - {"dt": "2022-09-06T11:16:38.514951", "lvl": "target", "msg": "<31>[ 1.683395] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/halt.target"} - {"dt": "2022-09-06T11:16:38.514996", "lvl": "target", "msg": "<31>[ 1.683873] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/wpa_supplicant.service"} - {"dt": "2022-09-06T11:16:40.823954", "lvl": "target", "msg": "<31>[ 1.684094] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-udevd-control.socket"} - {"dt": "2022-09-06T11:16:40.824126", "lvl": "target", "msg": "<31>[ 1.684325] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/rescue.target"} - {"dt": "2022-09-06T11:16:40.824196", "lvl": "target", "msg": "<31>[ 1.684536] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-volatile-root.service"} - {"dt": "2022-09-06T11:16:40.824245", "lvl": "target", "msg": "<31>[ 1.684752] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-initctl.service"} - {"dt": "2022-09-06T11:16:40.824291", "lvl": "target", "msg": "<31>[ 1.684960] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/reboot.target"} - {"dt": "2022-09-06T11:16:40.824342", "lvl": "target", "msg": "<31>[ 1.685163] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-journald-varlink@.socket"} - {"dt": "2022-09-06T11:16:40.824388", "lvl": "target", "msg": "<31>[ 1.685378] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/ldconfig.service"} - {"dt": "2022-09-06T11:16:40.824433", "lvl": "target", "msg": "<31>[ 1.685581] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/initrd-root-fs.target"} - {"dt": "2022-09-06T11:16:40.824477", "lvl": "target", "msg": "<31>[ 1.685798] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/user-runtime-dir@.service"} - {"dt": "2022-09-06T11:16:40.824521", "lvl": "target", "msg": "<31>[ 1.686005] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/shutdown.target"} - {"dt": "2022-09-06T11:16:40.824565", "lvl": "target", "msg": "<31>[ 1.686256] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/system-update-cleanup.service"} - {"dt": "2022-09-06T11:16:40.824608", "lvl": "target", "msg": "<31>[ 1.686489] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-rfkill.socket"} - {"dt": "2022-09-06T11:16:40.824650", "lvl": "target", "msg": "<31>[ 1.686706] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/emergency.service"} - {"dt": "2022-09-06T11:16:40.824692", "lvl": "target", "msg": "<31>[ 1.686915] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/rescue.service"} - {"dt": "2022-09-06T11:16:40.824735", "lvl": "target", "msg": "<31>[ 1.687125] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/initrd-switch-root.target"} - {"dt": "2022-09-06T11:16:40.824777", "lvl": "target", "msg": "<31>[ 1.687345] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/time-sync.target"} - {"dt": "2022-09-06T11:16:40.824819", "lvl": "target", "msg": "<31>[ 1.687558] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-tmpfiles-setup-dev.service"} - {"dt": "2022-09-06T11:16:43.465255", "lvl": "target", "msg": "<31>[ 1.687945] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/usb-gadget.target"} - {"dt": "2022-09-06T11:16:43.465422", "lvl": "target", "msg": "<31>[ 1.688169] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/initrd-root-device.target"} - {"dt": "2022-09-06T11:16:43.465479", "lvl": "target", "msg": "<31>[ 1.688387] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/initrd-cleanup.service"} - {"dt": "2022-09-06T11:16:43.465529", "lvl": "target", "msg": "<31>[ 1.688599] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-udev-settle.service"} - {"dt": "2022-09-06T11:16:43.465575", "lvl": "target", "msg": "<31>[ 1.688808] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/first-boot-complete.target"} - {"dt": "2022-09-06T11:16:43.465619", "lvl": "target", "msg": "<31>[ 1.689016] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-hibernate.service"} - {"dt": "2022-09-06T11:16:43.465664", "lvl": "target", "msg": "<31>[ 1.689355] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-remount-fs.service"} - {"dt": "2022-09-06T11:16:43.465708", "lvl": "target", "msg": "<31>[ 1.689577] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-ask-password-console.service"} - {"dt": "2022-09-06T11:16:43.465765", "lvl": "target", "msg": "<31>[ 1.689805] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/network-pre.target"} - {"dt": "2022-09-06T11:16:43.465808", "lvl": "target", "msg": "<31>[ 1.690010] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-tmpfiles-setup.service"} - {"dt": "2022-09-06T11:16:43.465862", "lvl": "target", "msg": "<31>[ 1.690236] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-hybrid-sleep.service"} - {"dt": "2022-09-06T11:16:43.465906", "lvl": "target", "msg": "<31>[ 1.690455] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/initrd-parse-etc.service"} - {"dt": "2022-09-06T11:16:43.465948", "lvl": "target", "msg": "<31>[ 1.691542] systemd[1]: unit_file_build_name_map: alias: /lib/systemd/system/dbus-org.freedesktop.timedate1.service \u2192 systemd-timedated.service"} - {"dt": "2022-09-06T11:16:43.465993", "lvl": "target", "msg": "<31>[ 1.692535] systemd[1]: unit_file_build_name_map: alias: /lib/systemd/system/runlevel4.target \u2192 multi-user.target"} - {"dt": "2022-09-06T11:16:43.466037", "lvl": "target", "msg": "<31>[ 1.692761] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/system-update-pre.target"} - {"dt": "2022-09-06T11:16:43.466080", "lvl": "target", "msg": "<31>[ 1.692974] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/nss-user-lookup.target"} - {"dt": "2022-09-06T11:16:46.457592", "lvl": "target", "msg": "<31>[ 1.693196] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/wpa_supplicant-nl80211@.service"} - {"dt": "2022-09-06T11:16:46.457783", "lvl": "target", "msg": "<31>[ 1.694142] systemd[1]: unit_file_build_name_map: alias: /lib/systemd/system/runlevel5.target \u2192 graphical.target"} - {"dt": "2022-09-06T11:16:46.457849", "lvl": "target", "msg": "<31>[ 1.694387] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/user@.service"} - {"dt": "2022-09-06T11:16:46.457906", "lvl": "target", "msg": "<31>[ 1.694594] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/bluetooth.service"} - {"dt": "2022-09-06T11:16:46.457958", "lvl": "target", "msg": "<31>[ 1.694811] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/dev-mqueue.mount"} - {"dt": "2022-09-06T11:16:46.458010", "lvl": "target", "msg": "<31>[ 1.695430] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-userdbd.service"} - {"dt": "2022-09-06T11:16:46.458060", "lvl": "target", "msg": "<31>[ 1.695651] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/network-online.target"} - {"dt": "2022-09-06T11:16:46.458110", "lvl": "target", "msg": "<31>[ 1.695858] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/sound.target"} - {"dt": "2022-09-06T11:16:46.458161", "lvl": "target", "msg": "<31>[ 1.696847] systemd[1]: unit_file_build_name_map: alias: /lib/systemd/system/dbus-org.freedesktop.locale1.service \u2192 systemd-localed.service"} - {"dt": "2022-09-06T11:16:46.458213", "lvl": "target", "msg": "<31>[ 1.697112] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/multi-user.target"} - {"dt": "2022-09-06T11:16:46.458266", "lvl": "target", "msg": "<31>[ 1.697322] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-suspend-then-hibernate.service"} - {"dt": "2022-09-06T11:16:46.458331", "lvl": "target", "msg": "<31>[ 1.697541] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/tmp.mount"} - {"dt": "2022-09-06T11:16:46.458381", "lvl": "target", "msg": "<31>[ 1.697738] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-halt.service"} - {"dt": "2022-09-06T11:16:46.458434", "lvl": "target", "msg": "<31>[ 1.697953] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-ask-password-console.path"} - {"dt": "2022-09-06T11:16:46.458488", "lvl": "target", "msg": "<31>[ 1.698950] systemd[1]: unit_file_build_name_map: alias: /lib/systemd/system/autovt@.service \u2192 getty@.service"} - {"dt": "2022-09-06T11:16:46.458540", "lvl": "target", "msg": "<31>[ 2.277318] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-resolved.service"} - {"dt": "2022-09-06T11:16:49.811940", "lvl": "target", "msg": "<31>[ 2.278761] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/hibernate.target"} - {"dt": "2022-09-06T11:16:49.812127", "lvl": "target", "msg": "<31>[ 2.278995] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/boot-complete.target"} - {"dt": "2022-09-06T11:16:49.812186", "lvl": "target", "msg": "<31>[ 2.279344] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/rpcbind.service"} - {"dt": "2022-09-06T11:16:49.812234", "lvl": "target", "msg": "<31>[ 2.279552] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/syslog.socket"} - {"dt": "2022-09-06T11:16:49.812278", "lvl": "target", "msg": "<31>[ 2.280463] systemd[1]: unit_file_build_name_map: alias: /lib/systemd/system/runlevel6.target \u2192 reboot.target"} - {"dt": "2022-09-06T11:16:49.812326", "lvl": "target", "msg": "<31>[ 2.280696] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/exit.target"} - {"dt": "2022-09-06T11:16:49.812373", "lvl": "target", "msg": "<31>[ 2.280899] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-hostnamed.service"} - {"dt": "2022-09-06T11:16:49.812419", "lvl": "target", "msg": "<31>[ 2.281115] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-vconsole-setup.service"} - {"dt": "2022-09-06T11:16:49.812464", "lvl": "target", "msg": "<31>[ 2.281329] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/sigpwr.target"} - {"dt": "2022-09-06T11:16:49.812508", "lvl": "target", "msg": "<31>[ 2.281531] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-journald.service"} - {"dt": "2022-09-06T11:16:49.812553", "lvl": "target", "msg": "<31>[ 2.281738] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/smartcard.target"} - {"dt": "2022-09-06T11:16:49.812600", "lvl": "target", "msg": "<31>[ 2.281942] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-udevd-kernel.socket"} - {"dt": "2022-09-06T11:16:49.812646", "lvl": "target", "msg": "<31>[ 2.282168] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/alsa-restore.service"} - {"dt": "2022-09-06T11:16:49.812691", "lvl": "target", "msg": "<31>[ 2.282385] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-userdbd.socket"} - {"dt": "2022-09-06T11:16:49.812736", "lvl": "target", "msg": "<31>[ 2.282893] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/initrd-switch-root.service"} - {"dt": "2022-09-06T11:16:49.812782", "lvl": "target", "msg": "<31>[ 2.283112] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-journal-flush.service"} - {"dt": "2022-09-06T11:16:49.812826", "lvl": "target", "msg": "<31>[ 2.283337] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/var-volatile-srv.service"} - {"dt": "2022-09-06T11:16:53.562509", "lvl": "target", "msg": "<31>[ 3.101141] systemd[1]: unit_file_build_name_map: alias: /lib/systemd/system/runlevel3.target \u2192 multi-user.target"} - {"dt": "2022-09-06T11:16:53.562691", "lvl": "target", "msg": "<31>[ 3.101379] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/nss-lookup.target"} - {"dt": "2022-09-06T11:16:53.562749", "lvl": "target", "msg": "<31>[ 3.101596] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/printer.target"} - {"dt": "2022-09-06T11:16:53.562800", "lvl": "target", "msg": "<31>[ 3.101808] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-journald@.socket"} - {"dt": "2022-09-06T11:16:53.562847", "lvl": "target", "msg": "<31>[ 3.102031] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-timesyncd.service"} - {"dt": "2022-09-06T11:16:53.562892", "lvl": "target", "msg": "<31>[ 3.102251] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/rpcbind.socket"} - {"dt": "2022-09-06T11:16:53.562935", "lvl": "target", "msg": "<31>[ 3.102478] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/getty.target"} - {"dt": "2022-09-06T11:16:53.562977", "lvl": "target", "msg": "<31>[ 3.102680] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/busybox-klogd.service"} - {"dt": "2022-09-06T11:16:53.563029", "lvl": "target", "msg": "<31>[ 3.102890] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/avahi-daemon.service"} - {"dt": "2022-09-06T11:16:53.563072", "lvl": "target", "msg": "<31>[ 3.103093] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/debug-shell.service"} - {"dt": "2022-09-06T11:16:53.563115", "lvl": "target", "msg": "<31>[ 3.103296] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-localed.service"} - {"dt": "2022-09-06T11:16:53.563158", "lvl": "target", "msg": "<31>[ 3.103508] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/sysinit.target"} - {"dt": "2022-09-06T11:16:53.563200", "lvl": "target", "msg": "<31>[ 3.103712] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/neard.service"} - {"dt": "2022-09-06T11:16:53.563242", "lvl": "target", "msg": "<31>[ 3.103913] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-time-wait-sync.service"} - {"dt": "2022-09-06T11:16:53.563286", "lvl": "target", "msg": "<31>[ 3.104132] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-boot-check-no-failures.service"} - {"dt": "2022-09-06T11:16:53.563329", "lvl": "target", "msg": "<31>[ 3.104361] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/var-volatile-spool.service"} - {"dt": "2022-09-06T11:16:53.563375", "lvl": "target", "msg": "<31>[ 3.104580] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-exit.service"} - {"dt": "2022-09-06T11:16:57.611135", "lvl": "target", "msg": "<31>[ 4.117895] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/console-getty.service"} - {"dt": "2022-09-06T11:16:57.611303", "lvl": "target", "msg": "<31>[ 4.118356] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-networkd.socket"} - {"dt": "2022-09-06T11:16:57.611358", "lvl": "target", "msg": "<31>[ 4.118613] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-kexec.service"} - {"dt": "2022-09-06T11:16:57.611407", "lvl": "target", "msg": "<31>[ 4.118840] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/logrotate.service"} - {"dt": "2022-09-06T11:16:57.611451", "lvl": "target", "msg": "<31>[ 4.119180] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/rc-local.service"} - {"dt": "2022-09-06T11:16:57.611493", "lvl": "target", "msg": "<31>[ 4.119446] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/initrd-usr-fs.target"} - {"dt": "2022-09-06T11:16:57.611535", "lvl": "target", "msg": "<31>[ 4.119674] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/wpa_supplicant-wired@.service"} - {"dt": "2022-09-06T11:16:57.611579", "lvl": "target", "msg": "<31>[ 4.119892] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-backlight@.service"} - {"dt": "2022-09-06T11:16:57.611631", "lvl": "target", "msg": "<31>[ 4.120114] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-hwdb-update.service"} - {"dt": "2022-09-06T11:16:57.611677", "lvl": "target", "msg": "<31>[ 4.120324] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/rpcbind.target"} - {"dt": "2022-09-06T11:16:57.611720", "lvl": "target", "msg": "<31>[ 4.120535] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-hibernate-resume@.service"} - {"dt": "2022-09-06T11:16:57.611764", "lvl": "target", "msg": "<31>[ 4.120769] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/quotaon.service"} - {"dt": "2022-09-06T11:16:57.611809", "lvl": "target", "msg": "<31>[ 4.120970] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/initrd-fs.target"} - {"dt": "2022-09-06T11:16:57.611853", "lvl": "target", "msg": "<31>[ 4.121180] systemd[1]: unit_file_build_name_map: normal unit file: /lib/systemd/system/systemd-fsck-root.service"} - {"dt": "2022-09-06T11:16:57.611896", "lvl": "target", "msg": "[[0;32m OK [0m] Created slice [0;1;39mSlice /system/getty[0m."} - {"dt": "2022-09-06T11:16:57.611940", "lvl": "target", "msg": "[[0;32m OK [0m] Created slice [0;1;39mSlice /system/modprobe[0m."} - {"dt": "2022-09-06T11:16:57.612000", "lvl": "target", "msg": "[[0;32m OK [0m] Created slice [0;1;39mSlice /system/serial-getty[0m."} - {"dt": "2022-09-06T11:16:57.612043", "lvl": "target", "msg": "[[0;32m OK [0m] Created slice [0;1;39mUser and Session Slice[0m."} - {"dt": "2022-09-06T11:16:57.612085", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mDispatch Password \u2026ts to Console Directory Watch[0m."} - {"dt": "2022-09-06T11:17:02.210177", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mForward Password R\u2026uests to Wall Directory Watch[0m."} - {"dt": "2022-09-06T11:17:02.210343", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mPath Units[0m."} - {"dt": "2022-09-06T11:17:02.210395", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mRemote File Systems[0m."} - {"dt": "2022-09-06T11:17:02.210441", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mSlice Units[0m."} - {"dt": "2022-09-06T11:17:02.210485", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mSwaps[0m."} - {"dt": "2022-09-06T11:17:02.210527", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mRPCbind Server Activation Socket[0m."} - {"dt": "2022-09-06T11:17:02.210569", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mRPC Port Mapper[0m."} - {"dt": "2022-09-06T11:17:02.210617", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mSyslog Socket[0m."} - {"dt": "2022-09-06T11:17:02.210661", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39minitctl Compatibility Named Pipe[0m."} - {"dt": "2022-09-06T11:17:02.210704", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mJournal Socket (/dev/log)[0m."} - {"dt": "2022-09-06T11:17:02.210747", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mJournal Socket[0m."} - {"dt": "2022-09-06T11:17:02.210789", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mNetwork Service Netlink Socket[0m."} - {"dt": "2022-09-06T11:17:02.210832", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mudev Control Socket[0m."} - {"dt": "2022-09-06T11:17:02.210875", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mudev Kernel Socket[0m."} - {"dt": "2022-09-06T11:17:02.210916", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mUser Database Manager Socket[0m."} - {"dt": "2022-09-06T11:17:02.210957", "lvl": "target", "msg": " Mounting [0;1;39mPOSIX Message Queue File System[0m..."} - {"dt": "2022-09-06T11:17:02.211000", "lvl": "target", "msg": " Mounting [0;1;39mKernel Debug File System[0m..."} - {"dt": "2022-09-06T11:17:02.211040", "lvl": "target", "msg": " Mounting [0;1;39mTemporary Directory /tmp[0m..."} - {"dt": "2022-09-06T11:17:02.211082", "lvl": "target", "msg": " Starting [0;1;39mCreate List of Static Device Nodes[0m..."} - {"dt": "2022-09-06T11:17:02.211125", "lvl": "target", "msg": " Starting [0;1;39mLoad Kernel Module configfs[0m..."} - {"dt": "2022-09-06T11:17:02.211167", "lvl": "target", "msg": " Starting [0;1;39mLoad Kernel Module drm[0m..."} - {"dt": "2022-09-06T11:17:02.211207", "lvl": "target", "msg": "<31>[ 6.843595] systemd[1]: modprobe@fuse.service: ConditionCapability=CAP_SYS_MODULE succeeded."} - {"dt": "2022-09-06T11:17:02.211249", "lvl": "target", "msg": "<31>[ 6.866988] systemd[1]: modprobe@fuse.service: Passing 0 fds to service"} - {"dt": "2022-09-06T11:17:02.211290", "lvl": "target", "msg": "<31>[ 6.867431] systemd[1]: modprobe@fuse.service: About to execute /sbin/modprobe -abq fuse"} - {"dt": "2022-09-06T11:17:02.211331", "lvl": "target", "msg": "<31>[ 6.891457] systemd[1]: modprobe@fuse.service: Forked /sbin/modprobe as 167"} - {"dt": "2022-09-06T11:17:02.211371", "lvl": "target", "msg": "<31>[ 6.951407] systemd[1]: modprobe@fuse.service: Changed dead -> start"} - {"dt": "2022-09-06T11:17:02.211412", "lvl": "target", "msg": "<30>[ 6.951835] systemd[1]: Starting Load Kernel Module fuse..."} - {"dt": "2022-09-06T11:17:07.278511", "lvl": "target", "msg": " Starting [0;1;39mLoad Kernel Module fuse[0m..."} - {"dt": "2022-09-06T11:17:07.278685", "lvl": "target", "msg": "<31>[ 6.953830] systemd[1]: ofono.service: starting held back, waiting for: dbus.socket"} - {"dt": "2022-09-06T11:17:07.278744", "lvl": "target", "msg": "<31>[ 6.966111] systemd[1]: rpcbind.service: Passing 5 fds to service"} - {"dt": "2022-09-06T11:17:07.278794", "lvl": "target", "msg": "<31>[ 6.969681] systemd[1]: rpcbind.service: About to execute /usr/sbin/rpcbind \\\"\\$RPCBIND_OPTIONS\\\" -w -f"} - {"dt": "2022-09-06T11:17:07.278842", "lvl": "target", "msg": "<31>[ 6.972746] systemd[1]: rpcbind.service: Forked /usr/sbin/rpcbind as 168"} - {"dt": "2022-09-06T11:17:07.278889", "lvl": "target", "msg": "<31>[ 6.977445] systemd[1]: rpcbind.service: Changed dead -> start"} - {"dt": "2022-09-06T11:17:07.278935", "lvl": "target", "msg": "<30>[ 6.977841] systemd[1]: Starting RPC Bind..."} - {"dt": "2022-09-06T11:17:07.278978", "lvl": "target", "msg": " Starting [0;1;39mRPC Bind[0m..."} - {"dt": "2022-09-06T11:17:07.279021", "lvl": "target", "msg": "<31>[ 6.989490] systemd[1]: serial-getty@ttyAMA0.service: starting held back, waiting for: sysinit.target"} - {"dt": "2022-09-06T11:17:07.279064", "lvl": "target", "msg": "<31>[ 6.989742] systemd[1]: serial-getty@ttyS2.service: starting held back, waiting for: basic.target"} - {"dt": "2022-09-06T11:17:07.279108", "lvl": "target", "msg": "<31>[ 6.990311] systemd[1]: systemd-fsck-root.service: ConditionPathIsReadWrite=!/ succeeded."} - {"dt": "2022-09-06T11:17:07.279152", "lvl": "target", "msg": "<31>[ 6.996777] systemd[1]: systemd-fsck-root.service: Passing 0 fds to service"} - {"dt": "2022-09-06T11:17:07.279193", "lvl": "target", "msg": "<31>[ 6.996976] systemd[1]: systemd-fsck-root.service: About to execute /lib/systemd/systemd-fsck"} - {"dt": "2022-09-06T11:17:07.279236", "lvl": "target", "msg": "<31>[ 6.998686] systemd[1]: systemd-fsck-root.service: Forked /lib/systemd/systemd-fsck as 169"} - {"dt": "2022-09-06T11:17:07.279279", "lvl": "target", "msg": "<31>[ 7.001614] systemd[1]: systemd-fsck-root.service: Changed dead -> start"} - {"dt": "2022-09-06T11:17:07.279324", "lvl": "target", "msg": "<30>[ 7.001906] systemd[1]: Starting File System Check on Root Device..."} - {"dt": "2022-09-06T11:17:07.279368", "lvl": "target", "msg": " Starting [0;1;39mFile System Check on Root Device[0m..."} - {"dt": "2022-09-06T11:17:07.279411", "lvl": "target", "msg": "<31>[ 7.002809] systemd[1]: systemd-hwdb-update.service: starting held back, waiting for: systemd-remount-fs.service"} - {"dt": "2022-09-06T11:17:07.279453", "lvl": "target", "msg": "<31>[ 8.531328] systemd[1]: systemd-journal-catalog-update.service: starting held back, waiting for: local-fs.target"} - {"dt": "2022-09-06T11:17:07.279496", "lvl": "target", "msg": "<31>[ 8.537528] systemd[1]: systemd-journal-flush.service: starting held back, waiting for: systemd-remount-fs.service"} - {"dt": "2022-09-06T11:17:07.279538", "lvl": "target", "msg": "<28>[ 8.542519] systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling."} - {"dt": "2022-09-06T11:17:12.851706", "lvl": "target", "msg": "<28>[ 8.543208] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)"} - {"dt": "2022-09-06T11:17:12.851882", "lvl": "target", "msg": "<31>[ 8.572573] systemd[1]: systemd-journald.service: Passing 3 fds to service"} - {"dt": "2022-09-06T11:17:12.851939", "lvl": "target", "msg": "<31>[ 8.573461] systemd[1]: systemd-journald.service: About to execute /lib/systemd/systemd-journald"} - {"dt": "2022-09-06T11:17:12.851988", "lvl": "target", "msg": "<31>[ 8.577318] systemd[1]: systemd-journald.service: Forked /lib/systemd/systemd-journald as 170"} - {"dt": "2022-09-06T11:17:12.852036", "lvl": "target", "msg": "<31>[ 8.593359] systemd[1]: systemd-journald.service: Changed dead -> start"} - {"dt": "2022-09-06T11:17:12.852083", "lvl": "target", "msg": "<30>[ 8.593962] systemd[1]: Starting Journal Service..."} - {"dt": "2022-09-06T11:17:12.852128", "lvl": "target", "msg": " Starting [0;1;39mJournal Service[0m..."} - {"dt": "2022-09-06T11:17:12.852173", "lvl": "target", "msg": "<31>[ 8.601529] systemd[170]: Operating on architecture: arm"} - {"dt": "2022-09-06T11:17:12.852217", "lvl": "target", "msg": "<31>[ 8.610412] systemd[1]: systemd-logind.service: starting held back, waiting for: tmp.mount"} - {"dt": "2022-09-06T11:17:12.852261", "lvl": "target", "msg": "<31>[ 8.610954] systemd[1]: systemd-machine-id-commit.service: starting held back, waiting for: first-boot-complete.target"} - {"dt": "2022-09-06T11:17:12.852306", "lvl": "target", "msg": "<31>[ 8.612630] systemd[1]: systemd-modules-load.service: ConditionKernelCommandLine=|rd.modules-load failed."} - {"dt": "2022-09-06T11:17:12.852352", "lvl": "target", "msg": "<31>[ 8.613525] systemd[1]: systemd-modules-load.service: ConditionKernelCommandLine=|modules-load failed."} - {"dt": "2022-09-06T11:17:12.852407", "lvl": "target", "msg": "<31>[ 8.614117] systemd[1]: systemd-modules-load.service: ConditionDirectoryNotEmpty=|/run/modules-load.d failed."} - {"dt": "2022-09-06T11:17:12.852454", "lvl": "target", "msg": "<31>[ 8.620431] systemd[1]: systemd-modules-load.service: ConditionDirectoryNotEmpty=|/etc/modules-load.d failed."} - {"dt": "2022-09-06T11:17:12.852499", "lvl": "target", "msg": "<31>[ 8.620709] systemd[1]: systemd-modules-load.service: ConditionDirectoryNotEmpty=|/usr/local/lib/modules-load.d failed."} - {"dt": "2022-09-06T11:17:12.852543", "lvl": "target", "msg": "<31>[ 8.622492] systemd[170]: Operating on architecture: arm"} - {"dt": "2022-09-06T11:17:12.852587", "lvl": "target", "msg": "<31>[ 8.623649] systemd[170]: Operating on architecture: arm"} - {"dt": "2022-09-06T11:17:12.852630", "lvl": "target", "msg": "<31>[ 8.625076] systemd[1]: systemd-modules-load.service: ConditionDirectoryNotEmpty=|/usr/lib/modules-load.d failed."} - {"dt": "2022-09-06T11:17:12.852675", "lvl": "target", "msg": "<31>[ 10.549379] systemd[1]: systemd-modules-load.service: ConditionDirectoryNotEmpty=|/lib/modules-load.d failed."} - {"dt": "2022-09-06T11:17:12.852718", "lvl": "target", "msg": "<31>[ 10.550804] systemd[1]: systemd-modules-load.service: ConditionCapability=CAP_SYS_MODULE succeeded."} - {"dt": "2022-09-06T11:17:12.852762", "lvl": "target", "msg": "<31>[ 10.551021] systemd[1]: systemd-modules-load.service: Starting requested but condition failed. Not starting unit."} - {"dt": "2022-09-06T11:17:19.504649", "lvl": "target", "msg": "<31>[ 10.551257] systemd[1]: systemd-modules-load.service: Job 52 systemd-modules-load.service/start finished, result=done"} - {"dt": "2022-09-06T11:17:19.504817", "lvl": "target", "msg": "<30>[ 10.551546] systemd[1]: Load Kernel Modules was skipped because all trigger condition checks failed."} - {"dt": "2022-09-06T11:17:19.504872", "lvl": "target", "msg": "<31>[ 10.552002] systemd[1]: sysinit.target: starting held back, waiting for: systemd-journald.service"} - {"dt": "2022-09-06T11:17:19.504919", "lvl": "target", "msg": "<31>[ 10.558451] systemd[1]: systemd-network-generator.service: Passing 0 fds to service"} - {"dt": "2022-09-06T11:17:19.504964", "lvl": "target", "msg": "<31>[ 10.558702] systemd[1]: systemd-network-generator.service: About to execute /lib/systemd/systemd-network-generator"} - {"dt": "2022-09-06T11:17:19.505010", "lvl": "target", "msg": "<31>[ 10.567464] systemd[1]: systemd-network-generator.service: Forked /lib/systemd/systemd-network-generator as 172"} - {"dt": "2022-09-06T11:17:19.505054", "lvl": "target", "msg": "<31>[ 10.581016] systemd[170]: Restricting namespace to: n/a."} - {"dt": "2022-09-06T11:17:19.505098", "lvl": "target", "msg": "<31>[ 10.581747] systemd[170]: Operating on architecture: arm"} - {"dt": "2022-09-06T11:17:19.505141", "lvl": "target", "msg": "<31>[ 10.582655] systemd[170]: Blocking cgroup."} - {"dt": "2022-09-06T11:17:19.505184", "lvl": "target", "msg": "<31>[ 10.583395] systemd[170]: Blocking ipc."} - {"dt": "2022-09-06T11:17:19.505226", "lvl": "target", "msg": "<31>[ 10.583987] systemd[170]: Blocking net."} - {"dt": "2022-09-06T11:17:19.505267", "lvl": "target", "msg": "<31>[ 10.584528] systemd[170]: Blocking mnt."} - {"dt": "2022-09-06T11:17:19.505309", "lvl": "target", "msg": "<31>[ 10.585064] systemd[170]: Blocking pid."} - {"dt": "2022-09-06T11:17:19.505350", "lvl": "target", "msg": "<31>[ 10.585664] systemd[170]: Blocking user."} - {"dt": "2022-09-06T11:17:19.505392", "lvl": "target", "msg": "<31>[ 10.586221] systemd[170]: Blocking uts."} - {"dt": "2022-09-06T11:17:19.505434", "lvl": "target", "msg": "<31>[ 10.587737] systemd[170]: Operating on architecture: arm"} - {"dt": "2022-09-06T11:17:19.505489", "lvl": "target", "msg": "<31>[ 10.590530] systemd[170]: Operating on architecture: arm"} - {"dt": "2022-09-06T11:17:19.505531", "lvl": "target", "msg": "<31>[ 10.611435] systemd[1]: systemd-network-generator.service: Changed dead -> start"} - {"dt": "2022-09-06T11:17:19.505574", "lvl": "target", "msg": "<30>[ 10.611718] systemd[1]: Starting Generate network units from Kernel command line..."} - {"dt": "2022-09-06T11:17:19.505616", "lvl": "target", "msg": " Starting [0;1;39mGenerate network \u2026ts from Kernel command line[0m..."} - {"dt": "2022-09-06T11:17:19.505659", "lvl": "target", "msg": "<31>[ 10.612740] systemd[1]: systemd-networkd.service: starting held back, waiting for: systemd-sysusers.service"} - {"dt": "2022-09-06T11:17:19.505702", "lvl": "target", "msg": "<31>[ 12.849910] systemd[1]: systemd-pstore.service: starting held back, waiting for: systemd-remount-fs.service"} - {"dt": "2022-09-06T11:17:19.505744", "lvl": "target", "msg": "<31>[ 12.850737] systemd[1]: systemd-random-seed.service: starting held back, waiting for: systemd-remount-fs.service"} - {"dt": "2022-09-06T11:17:25.984783", "lvl": "target", "msg": "<31>[ 12.851342] systemd[1]: systemd-remount-fs.service: starting held back, waiting for: systemd-fsck-root.service"} - {"dt": "2022-09-06T11:17:25.984946", "lvl": "target", "msg": "<31>[ 12.852079] systemd[1]: systemd-resolved.service: starting held back, waiting for: tmp.mount"} - {"dt": "2022-09-06T11:17:25.985000", "lvl": "target", "msg": "<31>[ 12.853485] systemd[1]: systemd-sysctl.service: ConditionPathIsReadWrite=/proc/sys/net succeeded."} - {"dt": "2022-09-06T11:17:25.985047", "lvl": "target", "msg": "<31>[ 12.866560] systemd[1]: systemd-sysctl.service: Passing 0 fds to service"} - {"dt": "2022-09-06T11:17:25.985092", "lvl": "target", "msg": "<31>[ 12.867192] systemd[1]: systemd-sysctl.service: About to execute /lib/systemd/systemd-sysctl"} - {"dt": "2022-09-06T11:17:25.985137", "lvl": "target", "msg": "<31>[ 12.870917] systemd[1]: systemd-sysctl.service: Forked /lib/systemd/systemd-sysctl as 173"} - {"dt": "2022-09-06T11:17:25.985183", "lvl": "target", "msg": "<31>[ 12.877140] systemd[1]: systemd-sysctl.service: Changed dead -> start"} - {"dt": "2022-09-06T11:17:25.985229", "lvl": "target", "msg": "<30>[ 12.877565] systemd[1]: Starting Apply Kernel Variables..."} - {"dt": "2022-09-06T11:17:25.985276", "lvl": "target", "msg": " Starting [0;1;39mApply Kernel Variables[0m..."} - {"dt": "2022-09-06T11:17:25.985325", "lvl": "target", "msg": "<31>[ 12.879035] systemd[1]: systemd-sysusers.service: starting held back, waiting for: systemd-remount-fs.service"} - {"dt": "2022-09-06T11:17:25.985370", "lvl": "target", "msg": "<31>[ 12.899832] systemd[1]: systemd-timesyncd.service: starting held back, waiting for: systemd-tmpfiles-setup.service"} - {"dt": "2022-09-06T11:17:25.985422", "lvl": "target", "msg": "<31>[ 12.900457] systemd[1]: systemd-tmpfiles-setup-dev.service: starting held back, waiting for: systemd-sysusers.service"} - {"dt": "2022-09-06T11:17:25.985470", "lvl": "target", "msg": "<31>[ 12.901170] systemd[1]: systemd-tmpfiles-setup.service: starting held back, waiting for: systemd-journal-flush.service"} - {"dt": "2022-09-06T11:17:25.985514", "lvl": "target", "msg": "<31>[ 12.902648] systemd[1]: systemd-udev-trigger.service: ConditionPathIsReadWrite=/sys succeeded."} - {"dt": "2022-09-06T11:17:25.985557", "lvl": "target", "msg": "<31>[ 12.918518] systemd[1]: systemd-udev-trigger.service: Passing 0 fds to service"} - {"dt": "2022-09-06T11:17:25.985602", "lvl": "target", "msg": "<31>[ 12.918949] systemd[1]: systemd-udev-trigger.service: About to execute udevadm trigger --type=subsystems --action=add"} - {"dt": "2022-09-06T11:17:25.985662", "lvl": "target", "msg": "<31>[ 12.931035] systemd[1]: systemd-udev-trigger.service: Forked udevadm as 174"} - {"dt": "2022-09-06T11:17:25.985706", "lvl": "target", "msg": "<31>[ 15.656404] systemd[1]: systemd-udev-trigger.service: Changed dead -> start"} - {"dt": "2022-09-06T11:17:25.985749", "lvl": "target", "msg": "<30>[ 15.656711] systemd[1]: Starting Coldplug All udev Devices..."} - {"dt": "2022-09-06T11:17:25.985793", "lvl": "target", "msg": " Starting [0;1;39mColdplug All udev Devices[0m..."} - {"dt": "2022-09-06T11:17:25.985836", "lvl": "target", "msg": "<31>[ 15.657680] systemd[1]: systemd-udevd.service: starting held back, waiting for: systemd-sysusers.service"} - {"dt": "2022-09-06T11:17:32.985295", "lvl": "target", "msg": "<31>[ 15.657915] systemd[1]: systemd-update-done.service: starting held back, waiting for: ldconfig.service"} - {"dt": "2022-09-06T11:17:32.985459", "lvl": "target", "msg": "<31>[ 15.658211] systemd[1]: systemd-update-utmp-runlevel.service: starting held back, waiting for: multi-user.target"} - {"dt": "2022-09-06T11:17:32.985513", "lvl": "target", "msg": "<31>[ 15.658438] systemd[1]: systemd-update-utmp.service: starting held back, waiting for: systemd-remount-fs.service"} - {"dt": "2022-09-06T11:17:32.985559", "lvl": "target", "msg": "<31>[ 15.658730] systemd[1]: var-volatile-cache.service: starting held back, waiting for: var-volatile.mount"} - {"dt": "2022-09-06T11:17:32.985604", "lvl": "target", "msg": "<31>[ 15.658934] systemd[1]: var-volatile-lib.service: starting held back, waiting for: var-volatile.mount"} - {"dt": "2022-09-06T11:17:32.985647", "lvl": "target", "msg": "<31>[ 15.659266] systemd[1]: var-volatile-spool.service: starting held back, waiting for: var-volatile.mount"} - {"dt": "2022-09-06T11:17:32.985689", "lvl": "target", "msg": "<31>[ 15.659543] systemd[1]: var-volatile-srv.service: starting held back, waiting for: var-volatile.mount"} - {"dt": "2022-09-06T11:17:32.985730", "lvl": "target", "msg": "<31>[ 15.674032] systemd[1]: dev-vda.device: Changed dead -> tentative"} - {"dt": "2022-09-06T11:17:32.985772", "lvl": "target", "msg": "<31>[ 15.676922] systemd[1]: sys-kernel-debug.mount: Changed mounting -> mounting-done"} - {"dt": "2022-09-06T11:17:32.985814", "lvl": "target", "msg": "<31>[ 15.677252] systemd[1]: dev-mqueue.mount: Changed mounting -> mounting-done"} - {"dt": "2022-09-06T11:17:32.985858", "lvl": "target", "msg": "<31>[ 15.677506] systemd[1]: tmp.mount: Changed mounting -> mounting-done"} - {"dt": "2022-09-06T11:17:32.985901", "lvl": "target", "msg": "<31>[ 15.694755] systemd[1]: rpcbind.service: Got notification message from PID 168 (READY=1)"} - {"dt": "2022-09-06T11:17:32.985945", "lvl": "target", "msg": "<31>[ 15.695300] systemd[1]: rpcbind.service: Changed start -> running"} - {"dt": "2022-09-06T11:17:32.986001", "lvl": "target", "msg": "<31>[ 15.695473] systemd[1]: rpcbind.service: Job 79 rpcbind.service/start finished, result=done"} - {"dt": "2022-09-06T11:17:32.986045", "lvl": "target", "msg": "<30>[ 15.695696] systemd[1]: Started RPC Bind."} - {"dt": "2022-09-06T11:17:32.986088", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mRPC Bind[0m."} - {"dt": "2022-09-06T11:17:32.986131", "lvl": "target", "msg": "<31>[ 15.696767] systemd[1]: rpcbind.socket: Changed listening -> running"} - {"dt": "2022-09-06T11:17:32.986181", "lvl": "target", "msg": "<31>[ 15.698159] systemd[1]: Received SIGCHLD from PID 161 (mount)."} - {"dt": "2022-09-06T11:17:32.986224", "lvl": "target", "msg": "<31>[ 15.698866] systemd[1]: Child 161 (mount) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:17:32.986267", "lvl": "target", "msg": "<31>[ 18.491239] systemd[1]: dev-mqueue.mount: Child 161 belongs to dev-mqueue.mount."} - {"dt": "2022-09-06T11:17:32.986309", "lvl": "target", "msg": "<31>[ 18.492279] systemd[1]: dev-mqueue.mount: Mount process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:17:32.986351", "lvl": "target", "msg": "<31>[ 18.493120] systemd[1]: dev-mqueue.mount: Changed mounting-done -> mounted"} - {"dt": "2022-09-06T11:17:41.409582", "lvl": "target", "msg": "<31>[ 18.493540] systemd[1]: dev-mqueue.mount: Job 16 dev-mqueue.mount/start finished, result=done"} - {"dt": "2022-09-06T11:17:41.409746", "lvl": "target", "msg": "<30>[ 18.493986] systemd[1]: Mounted POSIX Message Queue File System."} - {"dt": "2022-09-06T11:17:41.409798", "lvl": "target", "msg": "[[0;32m OK [0m] Mounted [0;1;39mPOSIX Message Queue File System[0m."} - {"dt": "2022-09-06T11:17:41.409844", "lvl": "target", "msg": "<31>[ 18.512114] systemd[1]: Child 162 (mount) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:17:41.409888", "lvl": "target", "msg": "<31>[ 18.513726] systemd[1]: sys-kernel-debug.mount: Child 162 belongs to sys-kernel-debug.mount."} - {"dt": "2022-09-06T11:17:41.409931", "lvl": "target", "msg": "<31>[ 18.514234] systemd[1]: sys-kernel-debug.mount: Mount process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:17:41.409973", "lvl": "target", "msg": "<31>[ 18.514695] systemd[1]: sys-kernel-debug.mount: Changed mounting-done -> mounted"} - {"dt": "2022-09-06T11:17:41.410015", "lvl": "target", "msg": "<31>[ 18.515073] systemd[1]: sys-kernel-debug.mount: Job 49 sys-kernel-debug.mount/start finished, result=done"} - {"dt": "2022-09-06T11:17:41.410057", "lvl": "target", "msg": "<30>[ 18.515486] systemd[1]: Mounted Kernel Debug File System."} - {"dt": "2022-09-06T11:17:41.410098", "lvl": "target", "msg": "[[0;32m OK [0m] Mounted [0;1;39mKernel Debug File System[0m."} - {"dt": "2022-09-06T11:17:41.410139", "lvl": "target", "msg": "<31>[ 18.518474] systemd[1]: Child 163 (mount) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:17:41.410181", "lvl": "target", "msg": "<31>[ 18.520229] systemd[1]: tmp.mount: Child 163 belongs to tmp.mount."} - {"dt": "2022-09-06T11:17:41.410222", "lvl": "target", "msg": "<31>[ 18.520655] systemd[1]: tmp.mount: Mount process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:17:41.410265", "lvl": "target", "msg": "<31>[ 18.521077] systemd[1]: tmp.mount: Changed mounting-done -> mounted"} - {"dt": "2022-09-06T11:17:41.410308", "lvl": "target", "msg": "<31>[ 18.521416] systemd[1]: tmp.mount: Job 20 tmp.mount/start finished, result=done"} - {"dt": "2022-09-06T11:17:41.410351", "lvl": "target", "msg": "<30>[ 18.521760] systemd[1]: Mounted Temporary Directory /tmp."} - {"dt": "2022-09-06T11:17:41.410395", "lvl": "target", "msg": "[[0;32m OK [0m] Mounted [0;1;39mTemporary Directory /tmp[0m."} - {"dt": "2022-09-06T11:17:41.410439", "lvl": "target", "msg": "<31>[ 18.524614] systemd[1]: Child 164 (kmod) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:17:41.410488", "lvl": "target", "msg": "<31>[ 18.526269] systemd[1]: kmod-static-nodes.service: Child 164 belongs to kmod-static-nodes.service."} - {"dt": "2022-09-06T11:17:41.410540", "lvl": "target", "msg": "<31>[ 18.527195] systemd[1]: kmod-static-nodes.service: Main process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:17:41.410583", "lvl": "target", "msg": "<31>[ 21.841117] systemd[1]: kmod-static-nodes.service: Changed start -> exited"} - {"dt": "2022-09-06T11:17:41.410631", "lvl": "target", "msg": "<31>[ 21.841455] systemd[1]: kmod-static-nodes.service: Job 4 kmod-static-nodes.service/start finished, result=done"} - {"dt": "2022-09-06T11:17:49.192065", "lvl": "target", "msg": "<30>[ 21.842317] systemd[1]: Finished Create List of Static Device Nodes."} - {"dt": "2022-09-06T11:17:49.192234", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mCreate List of Static Device Nodes[0m."} - {"dt": "2022-09-06T11:17:49.192288", "lvl": "target", "msg": "<31>[ 21.844526] systemd[1]: Child 165 (modprobe) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:17:49.192333", "lvl": "target", "msg": "<31>[ 21.845523] systemd[1]: modprobe@configfs.service: Child 165 belongs to modprobe@configfs.service."} - {"dt": "2022-09-06T11:17:49.192375", "lvl": "target", "msg": "<31>[ 21.845860] systemd[1]: modprobe@configfs.service: Main process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:17:49.192417", "lvl": "target", "msg": "<30>[ 21.851531] systemd[1]: modprobe@configfs.service: Deactivated successfully."} - {"dt": "2022-09-06T11:17:49.192457", "lvl": "target", "msg": "<31>[ 21.852711] systemd[1]: modprobe@configfs.service: Service will not restart (restart setting)"} - {"dt": "2022-09-06T11:17:49.192497", "lvl": "target", "msg": "<31>[ 21.853044] systemd[1]: modprobe@configfs.service: Changed start -> dead"} - {"dt": "2022-09-06T11:17:49.192536", "lvl": "target", "msg": "<31>[ 21.857642] systemd[1]: modprobe@configfs.service: Job 58 modprobe@configfs.service/start finished, result=done"} - {"dt": "2022-09-06T11:17:49.192579", "lvl": "target", "msg": "<30>[ 21.857898] systemd[1]: Finished Load Kernel Module configfs."} - {"dt": "2022-09-06T11:17:49.192622", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module configfs[0m."} - {"dt": "2022-09-06T11:17:49.192663", "lvl": "target", "msg": "<31>[ 21.861364] systemd[1]: Child 166 (modprobe) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:17:49.192704", "lvl": "target", "msg": "<31>[ 21.862450] systemd[1]: modprobe@drm.service: Child 166 belongs to modprobe@drm.service."} - {"dt": "2022-09-06T11:17:49.192746", "lvl": "target", "msg": "<31>[ 21.862776] systemd[1]: modprobe@drm.service: Main process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:17:49.192787", "lvl": "target", "msg": "<30>[ 21.867702] systemd[1]: modprobe@drm.service: Deactivated successfully."} - {"dt": "2022-09-06T11:17:49.192826", "lvl": "target", "msg": "<31>[ 21.868743] systemd[1]: modprobe@drm.service: Service will not restart (restart setting)"} - {"dt": "2022-09-06T11:17:49.192866", "lvl": "target", "msg": "<31>[ 21.869032] systemd[1]: modprobe@drm.service: Changed start -> dead"} - {"dt": "2022-09-06T11:17:49.192909", "lvl": "target", "msg": "<31>[ 21.874357] systemd[1]: modprobe@drm.service: Job 93 modprobe@drm.service/start finished, result=done"} - {"dt": "2022-09-06T11:17:49.192961", "lvl": "target", "msg": "<30>[ 21.874837] systemd[1]: Finished Load Kernel Module drm."} - {"dt": "2022-09-06T11:17:49.193002", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module drm[0m."} - {"dt": "2022-09-06T11:17:49.193042", "lvl": "target", "msg": "<47>[ 25.594899] systemd-journald[170]: Journal effective settings seal=no keyed_hash=yes compress=yes compress_threshold_bytes=512B"} - {"dt": "2022-09-06T11:17:57.814068", "lvl": "target", "msg": "<47>[ 25.600739] systemd-journald[170]: Fixed min_use=3.9M max_use=64.0M max_size=8.0M min_size=512.0K keep_free=9.8M n_max_files=100"} - {"dt": "2022-09-06T11:17:57.814234", "lvl": "target", "msg": "<47>[ 25.604652] systemd-journald[170]: Reserving 333 entries in field hash table."} - {"dt": "2022-09-06T11:17:57.814288", "lvl": "target", "msg": "<31>[ 25.605222] systemd[1]: Child 167 (modprobe) died (code=exited, status=1/FAILURE)"} - {"dt": "2022-09-06T11:17:57.814333", "lvl": "target", "msg": "<31>[ 25.607017] systemd[1]: modprobe@fuse.service: Child 167 belongs to modprobe@fuse.service."} - {"dt": "2022-09-06T11:17:57.814378", "lvl": "target", "msg": "<31>[ 25.607544] systemd[1]: modprobe@fuse.service: Main process exited, code=exited, status=1/FAILURE (success)"} - {"dt": "2022-09-06T11:17:57.814422", "lvl": "target", "msg": "<47>[ 25.626207] systemd-journald[170]: Reserving 14563 entries in data hash table."} - {"dt": "2022-09-06T11:17:57.814465", "lvl": "target", "msg": "<30>[ 25.627983] systemd[1]: modprobe@fuse.service: Deactivated successfully."} - {"dt": "2022-09-06T11:17:57.814508", "lvl": "target", "msg": "<31>[ 25.639625] systemd[1]: modprobe@fuse.service: Service will not restart (restart setting)"} - {"dt": "2022-09-06T11:17:57.814550", "lvl": "target", "msg": "<31>[ 25.640068] systemd[1]: modprobe@fuse.service: Changed start -> dead"} - {"dt": "2022-09-06T11:17:57.814591", "lvl": "target", "msg": "<47>[ 25.651140] systemd-journald[170]: Vacuuming..."} - {"dt": "2022-09-06T11:17:57.814652", "lvl": "target", "msg": "<47>[ 25.652865] systemd-journald[170]: Vacuuming done, freed 0B of archived journals from /run/log/journal/65e6c7da10444f3f831c7273e1a36d30."} - {"dt": "2022-09-06T11:17:57.814701", "lvl": "target", "msg": "<47>[ 25.653884] systemd-journald[170]: Flushing /dev/kmsg..."} - {"dt": "2022-09-06T11:17:57.814742", "lvl": "target", "msg": "<31>[ 25.659880] systemd[1]: modprobe@fuse.service: Job 61 modprobe@fuse.service/start finished, result=done"} - {"dt": "2022-09-06T11:17:57.814784", "lvl": "target", "msg": "<30>[ 25.660309] systemd[1]: Finished Load Kernel Module fuse."} - {"dt": "2022-09-06T11:17:57.814825", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module fuse[0m."} - {"dt": "2022-09-06T11:17:57.814865", "lvl": "target", "msg": "<31>[ 25.663964] systemd[1]: Child 169 (systemd-fsck) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:17:57.814906", "lvl": "target", "msg": "<31>[ 25.665465] systemd[1]: systemd-fsck-root.service: Child 169 belongs to systemd-fsck-root.service."} - {"dt": "2022-09-06T11:17:57.814946", "lvl": "target", "msg": "<31>[ 25.665893] systemd[1]: systemd-fsck-root.service: Main process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:17:57.814986", "lvl": "target", "msg": "<31>[ 25.672593] systemd[1]: systemd-fsck-root.service: Changed start -> exited"} - {"dt": "2022-09-06T11:17:57.815028", "lvl": "target", "msg": "<31>[ 25.673602] systemd[1]: systemd-fsck-root.service: Job 21 systemd-fsck-root.service/start finished, result=done"} - {"dt": "2022-09-06T11:17:57.815069", "lvl": "target", "msg": "<30>[ 29.635583] systemd[1]: Finished File System Check on Root Device."} - {"dt": "2022-09-06T11:18:06.659938", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mFile System Check on Root Device[0m."} - {"dt": "2022-09-06T11:18:06.660111", "lvl": "target", "msg": "<31>[ 29.641262] systemd[1]: Child 172 (systemd-network) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:18:06.660169", "lvl": "target", "msg": "<31>[ 29.643092] systemd[1]: systemd-network-generator.service: Child 172 belongs to systemd-network-generator.service."} - {"dt": "2022-09-06T11:18:06.660217", "lvl": "target", "msg": "<31>[ 29.643630] systemd[1]: systemd-network-generator.service: Main process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:18:06.660271", "lvl": "target", "msg": "<31>[ 29.653146] systemd[1]: systemd-network-generator.service: Changed start -> exited"} - {"dt": "2022-09-06T11:18:06.660315", "lvl": "target", "msg": "<31>[ 29.653842] systemd[1]: systemd-network-generator.service: Job 30 systemd-network-generator.service/start finished, result=done"} - {"dt": "2022-09-06T11:18:06.660360", "lvl": "target", "msg": "<30>[ 29.654390] systemd[1]: Finished Generate network units from Kernel command line."} - {"dt": "2022-09-06T11:18:06.660412", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mGenerate network units from Kernel command line[0m."} - {"dt": "2022-09-06T11:18:06.660457", "lvl": "target", "msg": "<31>[ 29.657875] systemd[1]: Child 173 (systemd-sysctl) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:18:06.660498", "lvl": "target", "msg": "<31>[ 29.660854] systemd[1]: systemd-sysctl.service: Child 173 belongs to systemd-sysctl.service."} - {"dt": "2022-09-06T11:18:06.660539", "lvl": "target", "msg": "<31>[ 29.661498] systemd[1]: systemd-sysctl.service: Main process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:18:06.660581", "lvl": "target", "msg": "<31>[ 29.671131] systemd[1]: systemd-sysctl.service: Changed start -> exited"} - {"dt": "2022-09-06T11:18:06.660622", "lvl": "target", "msg": "<31>[ 29.671550] systemd[1]: systemd-sysctl.service: Job 53 systemd-sysctl.service/start finished, result=done"} - {"dt": "2022-09-06T11:18:06.660662", "lvl": "target", "msg": "<30>[ 29.672023] systemd[1]: Finished Apply Kernel Variables."} - {"dt": "2022-09-06T11:18:06.660703", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mApply Kernel Variables[0m."} - {"dt": "2022-09-06T11:18:06.660744", "lvl": "target", "msg": "<31>[ 29.675132] systemd[1]: systemd-sysctl.service: Control group is empty."} - {"dt": "2022-09-06T11:18:06.660787", "lvl": "target", "msg": "<31>[ 29.675768] systemd[1]: systemd-network-generator.service: Control group is empty."} - {"dt": "2022-09-06T11:18:06.660828", "lvl": "target", "msg": "<31>[ 29.676359] systemd[1]: systemd-fsck-root.service: Control group is empty."} - {"dt": "2022-09-06T11:18:06.660870", "lvl": "target", "msg": "<31>[ 29.676920] systemd[1]: modprobe@fuse.service: Control group is empty."} - {"dt": "2022-09-06T11:18:06.660912", "lvl": "target", "msg": "<31>[ 29.677542] systemd[1]: modprobe@drm.service: Control group is empty."} - {"dt": "2022-09-06T11:18:06.660952", "lvl": "target", "msg": "<31>[ 29.678138] systemd[1]: modprobe@configfs.service: Control group is empty."} - {"dt": "2022-09-06T11:18:16.215932", "lvl": "target", "msg": "<31>[ 34.235357] systemd[1]: kmod-static-nodes.service: Control group is empty."} - {"dt": "2022-09-06T11:18:16.216101", "lvl": "target", "msg": "<31>[ 34.235912] systemd[1]: systemd-journald.socket: Incoming traffic"} - {"dt": "2022-09-06T11:18:16.216158", "lvl": "target", "msg": "<31>[ 34.236361] systemd[1]: systemd-journald.socket: Changed listening -> running"} - {"dt": "2022-09-06T11:18:16.216208", "lvl": "target", "msg": "[[0m[0;31m* [0m] (1 of 2) A start job is running for\u2026 All udev Devices (29s / no limit)"} - {"dt": "2022-09-06T11:18:16.216255", "lvl": "target", "msg": "<31>[ 34.238290] systemd[1]: basic.target: starting held back, waiting for: sockets.target"} - {"dt": "2022-09-06T11:18:16.216300", "lvl": "target", "msg": "<31>[ 34.238510] systemd[1]: local-fs.target: starting held back, waiting for: local-fs-pre.target"} - {"dt": "2022-09-06T11:18:16.216343", "lvl": "target", "msg": "<31>[ 34.238906] systemd[1]: network-pre.target changed dead -> active"} - {"dt": "2022-09-06T11:18:16.216385", "lvl": "target", "msg": "<31>[ 34.240148] systemd[1]: network-pre.target: Job 31 network-pre.target/start finished, result=done"} - {"dt": "2022-09-06T11:18:16.216428", "lvl": "target", "msg": "<30>[ 34.240620] systemd[1]: Reached target Preparation for Network."} - {"dt": "2022-09-06T11:18:16.216472", "lvl": "target", "msg": "M[K[[0;32m OK [0m] Reached target [0;1;39mPreparation for Network[0m."} - {"dt": "2022-09-06T11:18:16.216516", "lvl": "target", "msg": "[K<31>[ 34.241528] systemd[1]: network.target: starting held back, waiting for: systemd-networkd.service"} - {"dt": "2022-09-06T11:18:16.216560", "lvl": "target", "msg": "<31>[ 34.241775] systemd[1]: sysinit.target: starting held back, waiting for: systemd-journald.service"} - {"dt": "2022-09-06T11:18:16.216604", "lvl": "target", "msg": "<31>[ 34.242250] systemd[1]: /proc/self/uid_map has a full 1:1 mapping"} - {"dt": "2022-09-06T11:18:16.216646", "lvl": "target", "msg": "<31>[ 34.242670] systemd[1]: /proc/self/gid_map has a full 1:1 mapping"} - {"dt": "2022-09-06T11:18:16.216688", "lvl": "target", "msg": "<31>[ 34.243151] systemd[1]: /proc/self/setgroups contains \\\"allow\\\", not in user namespace"} - {"dt": "2022-09-06T11:18:16.216731", "lvl": "target", "msg": "<31>[ 34.243358] systemd[1]: sys-fs-fuse-connections.mount: ConditionVirtualization=!private-users succeeded."} - {"dt": "2022-09-06T11:18:16.216775", "lvl": "target", "msg": "<31>[ 34.244750] systemd[1]: sys-fs-fuse-connections.mount: ConditionCapability=CAP_SYS_ADMIN succeeded."} - {"dt": "2022-09-06T11:18:16.216818", "lvl": "target", "msg": "<31>[ 34.245044] systemd[1]: sys-fs-fuse-connections.mount: ConditionPathExists=/sys/fs/fuse/connections failed."} - {"dt": "2022-09-06T11:18:16.216860", "lvl": "target", "msg": "<31>[ 34.245268] systemd[1]: sys-fs-fuse-connections.mount: Starting requested but condition failed. Not starting unit."} - {"dt": "2022-09-06T11:18:16.216903", "lvl": "target", "msg": "<31>[ 34.245495] systemd[1]: sys-fs-fuse-connections.mount: Job 60 sys-fs-fuse-connections.mount/start finished, result=done"} - {"dt": "2022-09-06T11:18:26.740895", "lvl": "target", "msg": "<30>[ 34.245730] systemd[1]: FUSE Control File System was skipped because of a failed condition check (ConditionPathExists=/sys/fs/fuse/connections)."} - {"dt": "2022-09-06T11:18:26.741060", "lvl": "target", "msg": "<31>[ 39.320437] systemd[1]: sysinit.target: starting held back, waiting for: systemd-journald.service"} - {"dt": "2022-09-06T11:18:26.741117", "lvl": "target", "msg": "<31>[ 39.326579] systemd[1]: sys-kernel-config.mount: ConditionCapability=CAP_SYS_RAWIO succeeded."} - {"dt": "2022-09-06T11:18:26.741165", "lvl": "target", "msg": "<31>[ 39.327654] systemd[1]: sys-kernel-config.mount: ConditionPathExists=/sys/kernel/config succeeded."} - {"dt": "2022-09-06T11:18:26.741212", "lvl": "target", "msg": "<31>[ 39.365615] systemd[1]: sys-kernel-config.mount: About to execute /bin/mount configfs /sys/kernel/config -t configfs -o nosuid,nodev,noexec"} - {"dt": "2022-09-06T11:18:26.741258", "lvl": "target", "msg": "<31>[ 39.371550] systemd[1]: sys-kernel-config.mount: Forked /bin/mount as 178"} - {"dt": "2022-09-06T11:18:26.741303", "lvl": "target", "msg": "<31>[ 39.430537] systemd[1]: sys-kernel-config.mount: Changed dead -> mounting"} - {"dt": "2022-09-06T11:18:26.741347", "lvl": "target", "msg": "<30>[ 39.430859] systemd[1]: Mounting Kernel Configuration File System..."} - {"dt": "2022-09-06T11:18:26.741390", "lvl": "target", "msg": " Mounting [0;1;39mKernel Configuration File System[0m..."} - {"dt": "2022-09-06T11:18:26.741434", "lvl": "target", "msg": "<31>[ 39.431853] systemd[1]: systemd-logind.service: starting held back, waiting for: systemd-remount-fs.service"} - {"dt": "2022-09-06T11:18:26.741478", "lvl": "target", "msg": "<31>[ 39.432217] systemd[1]: systemd-networkd.service: starting held back, waiting for: systemd-sysusers.service"} - {"dt": "2022-09-06T11:18:26.741523", "lvl": "target", "msg": "<31>[ 39.459618] systemd[1]: systemd-remount-fs.service: Passing 0 fds to service"} - {"dt": "2022-09-06T11:18:26.741566", "lvl": "target", "msg": "<31>[ 39.459937] systemd[1]: systemd-remount-fs.service: About to execute /lib/systemd/systemd-remount-fs"} - {"dt": "2022-09-06T11:18:26.741609", "lvl": "target", "msg": "<31>[ 39.461728] systemd[1]: systemd-remount-fs.service: Forked /lib/systemd/systemd-remount-fs as 179"} - {"dt": "2022-09-06T11:18:26.741652", "lvl": "target", "msg": "<31>[ 39.464728] systemd[1]: systemd-remount-fs.service: Changed dead -> start"} - {"dt": "2022-09-06T11:18:26.741694", "lvl": "target", "msg": "<30>[ 39.465284] systemd[1]: Starting Remount Root and Kernel File Systems..."} - {"dt": "2022-09-06T11:18:26.741738", "lvl": "target", "msg": " Starting [0;1;39mRemount Root and Kernel File Systems[0m..."} - {"dt": "2022-09-06T11:18:26.741783", "lvl": "target", "msg": "<31>[ 39.470183] systemd[1]: systemd-resolved.service: starting held back, waiting for: systemd-tmpfiles-setup.service"} - {"dt": "2022-09-06T11:18:26.741827", "lvl": "target", "msg": "<31>[ 39.470708] systemd[1]: systemd-timesyncd.service: starting held back, waiting for: systemd-tmpfiles-setup.service"} - {"dt": "2022-09-06T11:18:26.741869", "lvl": "target", "msg": "<31>[ 39.471187] systemd[1]: systemd-tmpfiles-setup-dev.service: starting held back, waiting for: systemd-sysusers.service"} - {"dt": "2022-09-06T11:18:37.564564", "lvl": "target", "msg": "<31>[ 39.471758] systemd[1]: systemd-udevd.service: starting held back, waiting for: systemd-sysusers.service"} - {"dt": "2022-09-06T11:18:37.564735", "lvl": "target", "msg": "<6>[ 45.169719] EXT4-fs (vda): re-mounted. Quota mode: disabled."} - {"dt": "2022-09-06T11:18:37.564791", "lvl": "target", "msg": "<31>[ 45.327454] systemd[1]: sys-kernel-config.mount: Changed mounting -> mounting-done"} - {"dt": "2022-09-06T11:18:37.564840", "lvl": "target", "msg": "<31>[ 45.340460] systemd[1]: Received SIGCHLD from PID 178 (mount)."} - {"dt": "2022-09-06T11:18:37.564888", "lvl": "target", "msg": "<31>[ 45.340975] systemd[1]: Child 178 (mount) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:18:37.564933", "lvl": "target", "msg": "<31>[ 45.341737] systemd[1]: sys-kernel-config.mount: Child 178 belongs to sys-kernel-config.mount."} - {"dt": "2022-09-06T11:18:37.564978", "lvl": "target", "msg": "<31>[ 45.341958] systemd[1]: sys-kernel-config.mount: Mount process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:18:37.565023", "lvl": "target", "msg": "<31>[ 45.342191] systemd[1]: sys-kernel-config.mount: Changed mounting-done -> mounted"} - {"dt": "2022-09-06T11:18:37.565068", "lvl": "target", "msg": "<31>[ 45.342394] systemd[1]: sys-kernel-config.mount: Job 57 sys-kernel-config.mount/start finished, result=done"} - {"dt": "2022-09-06T11:18:37.565111", "lvl": "target", "msg": "<30>[ 45.342612] systemd[1]: Mounted Kernel Configuration File System."} - {"dt": "2022-09-06T11:18:37.565152", "lvl": "target", "msg": "[[0;32m OK [0m] Mounted [0;1;39mKernel Configuration File System[0m."} - {"dt": "2022-09-06T11:18:37.565194", "lvl": "target", "msg": "<31>[ 45.344159] systemd[1]: Child 179 (systemd-remount) died (code=exited, status=0/SUCCESS)"} - {"dt": "2022-09-06T11:18:37.565257", "lvl": "target", "msg": "<31>[ 45.344931] systemd[1]: systemd-remount-fs.service: Child 179 belongs to systemd-remount-fs.service."} - {"dt": "2022-09-06T11:18:37.565299", "lvl": "target", "msg": "<31>[ 45.345170] systemd[1]: systemd-remount-fs.service: Main process exited, code=exited, status=0/SUCCESS (success)"} - {"dt": "2022-09-06T11:18:37.565342", "lvl": "target", "msg": "<31>[ 45.348617] systemd[1]: systemd-remount-fs.service: Changed start -> exited"} - {"dt": "2022-09-06T11:18:37.565385", "lvl": "target", "msg": "<31>[ 45.359461] systemd[1]: systemd-remount-fs.service: Job 24 systemd-remount-fs.service/start finished, result=done"} - {"dt": "2022-09-06T11:18:37.565428", "lvl": "target", "msg": "<30>[ 51.528644] systemd[1]: Finished Remount Root and Kernel File Systems."} - {"dt": "2022-09-06T11:18:37.565469", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mRemount Root and Kernel File Systems[0m."} - {"dt": "2022-09-06T11:18:37.565512", "lvl": "target", "msg": "<31>[ 51.532871] systemd[1]: systemd-remount-fs.service: Control group is empty."} - {"dt": "2022-09-06T11:18:37.565554", "lvl": "target", "msg": "<31>[ 51.533551] systemd[1]: local-fs-pre.target: starting held back, waiting for: systemd-tmpfiles-setup-dev.service"} - {"dt": "2022-09-06T11:18:37.565595", "lvl": "target", "msg": "<31>[ 51.534072] systemd[1]: local-fs.target: starting held back, waiting for: local-fs-pre.target"} - {"dt": "2022-09-06T11:18:49.237205", "lvl": "target", "msg": "<31>[ 51.534625] systemd[1]: sysinit.target: starting held back, waiting for: systemd-journald.service"} - {"dt": "2022-09-06T11:18:49.237373", "lvl": "target", "msg": "<31>[ 51.554380] systemd[1]: systemd-hwdb-update.service: ConditionDirectoryNotEmpty=|/etc/udev/hwdb.d failed."} - {"dt": "2022-09-06T11:18:49.237429", "lvl": "target", "msg": "<31>[ 51.561996] systemd[1]: systemd-hwdb-update.service: ConditionPathExists=|/etc/udev/hwdb.bin failed."} - {"dt": "2022-09-06T11:18:49.237479", "lvl": "target", "msg": "<31>[ 51.569669] systemd[1]: systemd-hwdb-update.service: ConditionPathExists=|!/lib/udev/hwdb.bin failed."} - {"dt": "2022-09-06T11:18:49.237526", "lvl": "target", "msg": "<31>[ 51.571352] systemd[1]: systemd-hwdb-update.service: ConditionNeedsUpdate=/etc succeeded."} - {"dt": "2022-09-06T11:18:49.237571", "lvl": "target", "msg": "<31>[ 51.571864] systemd[1]: systemd-hwdb-update.service: Starting requested but condition failed. Not starting unit."} - {"dt": "2022-09-06T11:18:49.237614", "lvl": "target", "msg": "<31>[ 51.572354] systemd[1]: systemd-hwdb-update.service: Job 55 systemd-hwdb-update.service/start finished, result=done"} - {"dt": "2022-09-06T11:18:49.237661", "lvl": "target", "msg": "<30>[ 51.572821] systemd[1]: Rebuild Hardware Database was skipped because all trigger condition checks failed."} - {"dt": "2022-09-06T11:18:49.237711", "lvl": "target", "msg": "<31>[ 51.573487] systemd[1]: sysinit.target: starting held back, waiting for: systemd-journald.service"} - {"dt": "2022-09-06T11:18:49.237754", "lvl": "target", "msg": "<31>[ 51.574118] systemd[1]: systemd-journal-flush.service: starting held back, waiting for: systemd-journald.service"} - {"dt": "2022-09-06T11:18:49.237797", "lvl": "target", "msg": "<31>[ 51.574784] systemd[1]: systemd-logind.service: starting held back, waiting for: sysinit.target"} - {"dt": "2022-09-06T11:18:49.237838", "lvl": "target", "msg": "<31>[ 51.575663] systemd[1]: systemd-pstore.service: ConditionVirtualization=!container succeeded."} - {"dt": "2022-09-06T11:18:49.237880", "lvl": "target", "msg": "<31>[ 51.576480] systemd[1]: systemd-pstore.service: ConditionDirectoryNotEmpty=/sys/fs/pstore failed."} - {"dt": "2022-09-06T11:18:49.237922", "lvl": "target", "msg": "<31>[ 51.576915] systemd[1]: systemd-pstore.service: Starting requested but condition failed. Not starting unit."} - {"dt": "2022-09-06T11:18:49.237964", "lvl": "target", "msg": "<31>[ 58.008698] systemd[1]: systemd-pstore.service: Job 41 systemd-pstore.service/start finished, result=done"} - {"dt": "2022-09-06T11:18:49.238010", "lvl": "target", "msg": "<30>[ 58.040068] systemd[1]: Platform Persistent Storage Archival was skipped because of a failed condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore)."} - {"dt": "2022-09-06T11:18:49.238066", "lvl": "target", "msg": "<31>[ 58.040895] systemd[1]: sysinit.target: starting held back, waiting for: systemd-journald.service"} - {"dt": "2022-09-06T11:19:01.275461", "lvl": "target", "msg": "<31>[ 58.041405] systemd[1]: systemd-random-seed.service: starting held back, waiting for: var-volatile-lib.service"} - {"dt": "2022-09-06T11:19:01.275627", "lvl": "target", "msg": "<31>[ 58.043064] systemd[1]: systemd-sysusers.service: ConditionNeedsUpdate=/etc succeeded."} - {"dt": "2022-09-06T11:19:01.275682", "lvl": "target", "msg": "<31>[ 58.065368] systemd[1]: systemd-sysusers.service: Passing 0 fds to service"} - {"dt": "2022-09-06T11:19:01.275728", "lvl": "target", "msg": "<31>[ 58.065821] systemd[1]: systemd-sysusers.service: About to execute systemd-sysusers"} - {"dt": "2022-09-06T11:19:01.275771", "lvl": "target", "msg": "<31>[ 58.079603] systemd[1]: systemd-sysusers.service: Forked systemd-sysusers as 185"} - {"dt": "2022-09-06T11:19:01.275826", "lvl": "target", "msg": "<47>[ 58.108452] systemd-journald[170]: systemd-journald running as PID 170 for the system."} - {"dt": "2022-09-06T11:19:01.275870", "lvl": "target", "msg": "<47>[ 58.115811] systemd-journald[170]: Sent READY=1 notification."} - {"dt": "2022-09-06T11:19:01.275912", "lvl": "target", "msg": "<47>[ 58.116223] systemd-journald[170]: Sent WATCHDOG=1 notification."} - {"dt": "2022-09-06T11:19:01.275961", "lvl": "target", "msg": "<47>[ 58.128882] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:01.276003", "lvl": "target", "msg": "<47>[ 58.137412] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:01.276044", "lvl": "target", "msg": "<47>[ 58.143660] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:01.276086", "lvl": "target", "msg": "<47>[ 58.148058] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:01.276128", "lvl": "target", "msg": "<31>[ 58.151323] systemd[1]: systemd-sysusers.service: Changed dead -> start"} - {"dt": "2022-09-06T11:19:01.276169", "lvl": "target", "msg": "<30>[ 58.151646] systemd[1]: Starting Create System Users..."} - {"dt": "2022-09-06T11:19:01.276209", "lvl": "target", "msg": " Starting [0;1;39mCreate System Users[0m..."} - {"dt": "2022-09-06T11:19:01.276250", "lvl": "target", "msg": "<31>[ 58.152492] systemd[1]: systemd-timesyncd.service: starting held back, waiting for: systemd-tmpfiles-setup.service"} - {"dt": "2022-09-06T11:19:01.276295", "lvl": "target", "msg": "<31>[ 58.152826] systemd[1]: systemd-udevd.service: starting held back, waiting for: systemd-sysusers.service"} - {"dt": "2022-09-06T11:19:01.276337", "lvl": "target", "msg": "<31>[ 58.153048] systemd[1]: systemd-update-done.service: starting held back, waiting for: ldconfig.service"} - {"dt": "2022-09-06T11:19:01.276379", "lvl": "target", "msg": "<31>[ 65.009249] systemd[1]: systemd-update-utmp.service: starting held back, waiting for: systemd-tmpfiles-setup.service"} - {"dt": "2022-09-06T11:19:01.276421", "lvl": "target", "msg": "<31>[ 65.014440] systemd[1]: systemd-journald.service: Got notification message from PID 170 (READY=1, STATUS=Processing requests...)"} - {"dt": "2022-09-06T11:19:01.276461", "lvl": "target", "msg": "<31>[ 65.014891] systemd[1]: systemd-journald.service: Changed start -> running"} - {"dt": "2022-09-06T11:19:14.188891", "lvl": "target", "msg": "<31>[ 65.015084] systemd[1]: systemd-journald.service: Job 12 systemd-journald.service/start finished, result=done"} - {"dt": "2022-09-06T11:19:14.189055", "lvl": "target", "msg": "<30>[ 65.015309] systemd[1]: Started Journal Service."} - {"dt": "2022-09-06T11:19:14.189111", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mJournal Service[0m."} - {"dt": "2022-09-06T11:19:14.189159", "lvl": "target", "msg": "<31>[ 65.026864] systemd[185]: Successfully forked off '(sd-mkdcreds)' as PID 186."} - {"dt": "2022-09-06T11:19:14.189204", "lvl": "target", "msg": "<31>[ 65.044272] systemd[186]: Mounting /dev (MS_REC|MS_SLAVE \\\"\\\")..."} - {"dt": "2022-09-06T11:19:14.189248", "lvl": "target", "msg": "<31>[ 65.048188] systemd[186]: Mounting ramfs (ramfs) on /dev/shm (MS_NOSUID|MS_NODEV|MS_NOEXEC \\\"mode=0700\\\")..."} - {"dt": "2022-09-06T11:19:14.189291", "lvl": "target", "msg": " Starting [0;1;39mFlush Journal to Persistent Storage[0m..."} - {"dt": "2022-09-06T11:19:14.189336", "lvl": "target", "msg": "<47>[ 65.057558] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:14.189394", "lvl": "target", "msg": "<31>[ 65.071353] systemd[186]: Couldn't read inherited credential 'passwd.plaintext-password.root', skipping: No such file or directory"} - {"dt": "2022-09-06T11:19:14.189440", "lvl": "target", "msg": "<31>[ 65.072136] systemd[186]: Couldn't read inherited credential 'passwd.hashed-password.root', skipping: No such file or directory"} - {"dt": "2022-09-06T11:19:14.189483", "lvl": "target", "msg": "<31>[ 65.072713] systemd[186]: Couldn't read inherited credential 'passwd.shell.root', skipping: No such file or directory"} - {"dt": "2022-09-06T11:19:14.189528", "lvl": "target", "msg": "<31>[ 65.074169] systemd[186]: Remounting /dev/shm (MS_RDONLY|MS_NOSUID|MS_NODEV|MS_NOEXEC|MS_REMOUNT|MS_BIND \\\"\\\")..."} - {"dt": "2022-09-06T11:19:14.189581", "lvl": "target", "msg": "<31>[ 65.075088] systemd[186]: Moving mount /dev/shm \u2192 /run/credentials/systemd-sysusers.service (MS_MOVE \\\"\\\")..."} - {"dt": "2022-09-06T11:19:14.189627", "lvl": "target", "msg": "<47>[ 65.087305] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:14.189671", "lvl": "target", "msg": "<31>[ 65.090234] systemd[185]: (sd-mkdcreds) succeeded."} - {"dt": "2022-09-06T11:19:14.189714", "lvl": "target", "msg": "<47>[ 65.095314] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:14.189755", "lvl": "target", "msg": "<47>[ 65.113775] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:14.189796", "lvl": "target", "msg": "[[0;1;31m*[0m[0;31m* [0m] (1 of 3) A start job is running for\u2026 System Users (1min 8s / 2min 23s)"} - {"dt": "2022-09-06T11:19:14.189837", "lvl": "target", "msg": "<47>[ 73.514585] systemd-journald[170]: varlink: New incoming connection."} - {"dt": "2022-09-06T11:19:14.189879", "lvl": "target", "msg": "<47>[ 73.515183] systemd-journald[170]: varlink-17: Setting state idle-server"} - {"dt": "2022-09-06T11:19:27.747917", "lvl": "target", "msg": "<47>[ 73.517739] systemd-journald[170]: varlink-17: New incoming message: {\\\"method\\\":\\\"io.systemd.Journal.FlushToVar\\\",\\\"parameters\\\":{}}"} - {"dt": "2022-09-06T11:19:27.748088", "lvl": "target", "msg": "<47>[ 73.528542] systemd-journald[170]: varlink-17: Changing state idle-server \u2192 processing-method"} - {"dt": "2022-09-06T11:19:27.748144", "lvl": "target", "msg": "<46>[ 73.529000] systemd-journald[170]: Received client request to flush runtime journal."} - {"dt": "2022-09-06T11:19:27.748190", "lvl": "target", "msg": "<47>[ 73.539942] systemd-journald[170]: Vacuuming..."} - {"dt": "2022-09-06T11:19:27.748234", "lvl": "target", "msg": "<47>[ 73.541045] systemd-journald[170]: Vacuuming done, freed 0B of archived journals from /run/log/journal/65e6c7da10444f3f831c7273e1a36d30."} - {"dt": "2022-09-06T11:19:27.748277", "lvl": "target", "msg": "<47>[ 73.544775] systemd-journald[170]: varlink-17: Sending message: {\\\"parameters\\\":{}}"} - {"dt": "2022-09-06T11:19:27.748320", "lvl": "target", "msg": "<47>[ 73.545133] systemd-journald[170]: varlink-17: Changing state processing-method \u2192 processed-method"} - {"dt": "2022-09-06T11:19:27.748361", "lvl": "target", "msg": "<47>[ 73.545566] systemd-journald[170]: varlink-17: Changing state processed-method \u2192 idle-server"} - {"dt": "2022-09-06T11:19:27.748405", "lvl": "target", "msg": "<47>[ 73.562050] systemd-journald[170]: varlink-17: Got POLLHUP from socket."} - {"dt": "2022-09-06T11:19:27.748448", "lvl": "target", "msg": "<47>[ 73.562651] systemd-journald[170]: varlink-17: Changing state idle-server \u2192 pending-disconnect"} - {"dt": "2022-09-06T11:19:27.748491", "lvl": "target", "msg": "<47>[ 73.563160] systemd-journald[170]: varlink-17: Changing state pending-disconnect \u2192 processing-disconnect"} - {"dt": "2022-09-06T11:19:27.748533", "lvl": "target", "msg": "<47>[ 73.563557] systemd-journald[170]: varlink-17: Changing state processing-disconnect \u2192 disconnected"} - {"dt": "2022-09-06T11:19:27.748576", "lvl": "target", "msg": "<47>[ 73.565978] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:27.748616", "lvl": "target", "msg": "M[K[[0;32m OK [0m] Finished [0;1;39mFlush Journal to Persistent Storage[0m."} - {"dt": "2022-09-06T11:19:27.748656", "lvl": "target", "msg": "[K<47>[ 73.597872] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:27.748699", "lvl": "target", "msg": "<47>[ 73.610914] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:27.748741", "lvl": "target", "msg": "<47>[ 73.620857] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:27.748782", "lvl": "target", "msg": "[[0;31m*[0;1;31m*[0m[0;31m* [0m] (1 of 2) A start job is running for\u2026System Users (1min 16s / 2min 23s)"} - {"dt": "2022-09-06T11:19:27.748823", "lvl": "target", "msg": "<47>[ 81.246634] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.764222", "lvl": "target", "msg": "<47>[ 81.262526] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.764389", "lvl": "target", "msg": "<47>[ 81.273426] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.764452", "lvl": "target", "msg": "<47>[ 81.278029] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.764501", "lvl": "target", "msg": "M[K[[0;32m OK [0m] Finished [0;1;39mCreate System Users[0m."} - {"dt": "2022-09-06T11:19:41.764545", "lvl": "target", "msg": "[K Starting [0;1;39mCreate Static Device Nodes in /dev[0m..."} - {"dt": "2022-09-06T11:19:41.764588", "lvl": "target", "msg": "<47>[ 81.402858] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.764629", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mCreate Static Device Nodes in /dev[0m."} - {"dt": "2022-09-06T11:19:41.764671", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mPreparation for Local File Systems[0m."} - {"dt": "2022-09-06T11:19:41.764713", "lvl": "target", "msg": " Mounting [0;1;39m/var/volatile[0m..."} - {"dt": "2022-09-06T11:19:41.764752", "lvl": "target", "msg": "<47>[ 81.724703] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.764792", "lvl": "target", "msg": " Starting [0;1;39mRule-based Manage\u2026for Device Events and Files[0m..."} - {"dt": "2022-09-06T11:19:41.764832", "lvl": "target", "msg": "<47>[ 81.770884] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.764874", "lvl": "target", "msg": "[[0;32m OK [0m] Mounted [0;1;39m/var/volatile[0m."} - {"dt": "2022-09-06T11:19:41.764916", "lvl": "target", "msg": " Starting [0;1;39mLoad/Save Random Seed[0m..."} - {"dt": "2022-09-06T11:19:41.764956", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m."} - {"dt": "2022-09-06T11:19:41.764997", "lvl": "target", "msg": "<47>[ 81.894092] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.765049", "lvl": "target", "msg": " Starting [0;1;39mRebuild Dynamic Linker Cache[0m..."} - {"dt": "2022-09-06T11:19:41.765089", "lvl": "target", "msg": "<47>[ 81.920197] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.765129", "lvl": "target", "msg": " Starting [0;1;39mCreate Volatile Files and Directories[0m..."} - {"dt": "2022-09-06T11:19:41.765168", "lvl": "target", "msg": "<47>[ 81.977154] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:41.765208", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mRule-based Manager for Device Events and Files[0m."} - {"dt": "2022-09-06T11:19:41.765248", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m."} - {"dt": "2022-09-06T11:19:41.765287", "lvl": "target", "msg": " Starting [0;1;39mRebuild Journal Catalog[0m..."} - {"dt": "2022-09-06T11:19:41.765328", "lvl": "target", "msg": "<47>[ 90.265640] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:56.928997", "lvl": "target", "msg": " Starting [0;1;39mNetwork Configuration[0m..."} - {"dt": "2022-09-06T11:19:56.929177", "lvl": "target", "msg": "<47>[ 90.343905] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:56.929234", "lvl": "target", "msg": " Starting [0;1;39mNetwork Time Synchronization[0m..."} - {"dt": "2022-09-06T11:19:56.929282", "lvl": "target", "msg": " Starting [0;1;39mRecord System Boot/Shutdown in UTMP[0m..."} - {"dt": "2022-09-06T11:19:56.929328", "lvl": "target", "msg": "<47>[ 90.433382] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:56.929371", "lvl": "target", "msg": "<47>[ 90.436659] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:56.929414", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mRebuild Dynamic Linker Cache[0m."} - {"dt": "2022-09-06T11:19:56.929457", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mRecord System Boot/Shutdown in UTMP[0m."} - {"dt": "2022-09-06T11:19:56.929498", "lvl": "target", "msg": "<47>[ 91.422709] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:56.929541", "lvl": "target", "msg": "<31>[ 91.439474] systemd[1]: systemd-journald.service: Got notification message from PID 170 (FDSTORE=1)"} - {"dt": "2022-09-06T11:19:56.929585", "lvl": "target", "msg": "<31>[ 91.458448] systemd[1]: systemd-journald.service: Added fd 45 (n/a) to fd store."} - {"dt": "2022-09-06T11:19:56.929628", "lvl": "target", "msg": "<5>[ 92.022837] cfg80211: Loading compiled-in X.509 certificates for regulatory database"} - {"dt": "2022-09-06T11:19:56.929672", "lvl": "target", "msg": "<5>[ 92.183879] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'"} - {"dt": "2022-09-06T11:19:56.929716", "lvl": "target", "msg": "[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 5) A start job is running for\u2026chronization (1min 28s / 2min 55s)"} - {"dt": "2022-09-06T11:19:56.929761", "lvl": "target", "msg": "<31>[ 93.362019] systemd[1]: Received SIGCHLD from PID 194 (journalctl)."} - {"dt": "2022-09-06T11:19:56.929805", "lvl": "target", "msg": "M[K[[0;32m OK [0m] Finished [0;1;39mRebuild Journal Catalog[0m."} - {"dt": "2022-09-06T11:19:56.929847", "lvl": "target", "msg": "[K<31>[ 93.439345] systemd[1]: sysinit.target: starting held back, waiting for: systemd-update-done.service"} - {"dt": "2022-09-06T11:19:56.929902", "lvl": "target", "msg": "<31>[ 93.459460] systemd[1]: systemd-update-done.service: ConditionNeedsUpdate=|/etc succeeded."} - {"dt": "2022-09-06T11:19:56.929944", "lvl": "target", "msg": "<31>[ 93.489415] systemd[1]: systemd-update-done.service: Forked /lib/systemd/systemd-update-done as 216"} - {"dt": "2022-09-06T11:19:56.929987", "lvl": "target", "msg": "<47>[ 93.505979] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:19:56.930030", "lvl": "target", "msg": "<31>[ 98.684580] systemd[1]: systemd-update-done.service: Changed dead -> start"} - {"dt": "2022-09-06T11:19:56.930072", "lvl": "target", "msg": " Starting [0;1;39mUpdate is Completed[0m..."} - {"dt": "2022-09-06T11:20:13.270249", "lvl": "target", "msg": "<31>[ 98.719569] systemd[1]: systemd-journald.service: Got notification message from PID 170 (FDSTORE=1)"} - {"dt": "2022-09-06T11:20:13.270415", "lvl": "target", "msg": "<31>[ 98.739536] systemd[1]: systemd-journald.service: Added fd 48 (n/a) to fd store."} - {"dt": "2022-09-06T11:20:13.270471", "lvl": "target", "msg": "[ [0;31m*[0;1;31m*[0m[0;31m* [0m] (2 of 5) A start job is running for\u2026udev Devices (1min 33s / no limit)"} - {"dt": "2022-09-06T11:20:13.270525", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m[0;31m*[0m] (2 of 5) A start job is running for\u2026udev Devices (1min 34s / no limit)"} - {"dt": "2022-09-06T11:20:13.270577", "lvl": "target", "msg": "M[K[ [0;31m*[0;1;31m*[0m] (3 of 5) A start job is running for\u2026onfiguration (1min 34s / 2min 55s)"} - {"dt": "2022-09-06T11:20:13.270639", "lvl": "target", "msg": "<31>[ 100.049613] systemd[1]: systemd-journald.service: Received EPOLLHUP on stored fd 45 (stored), closing."} - {"dt": "2022-09-06T11:20:13.270686", "lvl": "target", "msg": "M[K[[0;32m OK [0m] Finished [0;1;39mColdplug All udev Devices[0m."} - {"dt": "2022-09-06T11:20:13.270732", "lvl": "target", "msg": "[K<5>[ 100.139293] random: crng init done"} - {"dt": "2022-09-06T11:20:13.270782", "lvl": "target", "msg": "<31>[ 100.178354] systemd[1]: systemd-networkd.socket: Changed listening -> running"} - {"dt": "2022-09-06T11:20:13.270832", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mLoad/Save Random Seed[0m."} - {"dt": "2022-09-06T11:20:13.270875", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mNetwork Configuration[0m."} - {"dt": "2022-09-06T11:20:13.270917", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mUpdate is Completed[0m."} - {"dt": "2022-09-06T11:20:13.270961", "lvl": "target", "msg": "<47>[ 100.428580] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:20:13.271003", "lvl": "target", "msg": " Starting [0;1;39mCommit a transient machine-id on disk[0m..."} - {"dt": "2022-09-06T11:20:13.271058", "lvl": "target", "msg": " Starting [0;1;39mNetwork Name Resolution[0m..."} - {"dt": "2022-09-06T11:20:13.271099", "lvl": "target", "msg": "<31>[ 100.469322] systemd[1]: systemd-resolved.service: User lookup succeeded: uid=994 gid=992"} - {"dt": "2022-09-06T11:20:13.271140", "lvl": "target", "msg": "<47>[ 100.475849] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:20:13.271182", "lvl": "target", "msg": "<31>[ 100.489656] systemd[1]: systemd-journald.service: Got notification message from PID 170 (FDSTORE=1)"} - {"dt": "2022-09-06T11:20:13.271223", "lvl": "target", "msg": "[[0;32m OK [0m] Finished [0;1;39mCommit a transient machine-id on disk[0m."} - {"dt": "2022-09-06T11:20:13.271263", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m."} - {"dt": "2022-09-06T11:20:13.271304", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m."} - {"dt": "2022-09-06T11:20:13.271344", "lvl": "target", "msg": "<31>[ 100.730548] systemd[1]: systemd-tmpfiles-clean.timer: Monotonic timer elapses in 13min 19.284442s."} - {"dt": "2022-09-06T11:20:13.271398", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m."} - {"dt": "2022-09-06T11:20:30.477316", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m."} - {"dt": "2022-09-06T11:20:30.477485", "lvl": "target", "msg": "<31>[ 108.337586] systemd[1]: logrotate.timer: Realtime timer elapses at Wed 2022-09-07 00:00:00 UTC."} - {"dt": "2022-09-06T11:20:30.477542", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m."} - {"dt": "2022-09-06T11:20:30.477589", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mTimer Units[0m."} - {"dt": "2022-09-06T11:20:30.477632", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mAvahi mDNS/DNS-SD Stack Activation Socket[0m."} - {"dt": "2022-09-06T11:20:30.477675", "lvl": "target", "msg": "[[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m."} - {"dt": "2022-09-06T11:20:30.477718", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mSocket Units[0m."} - {"dt": "2022-09-06T11:20:30.477760", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mBasic System[0m."} - {"dt": "2022-09-06T11:20:30.477801", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mKernel Logging Service[0m."} - {"dt": "2022-09-06T11:20:30.477841", "lvl": "target", "msg": "<47>[ 108.501945] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:20:30.477883", "lvl": "target", "msg": "<31>[ 108.519652] systemd[1]: busybox-syslog.service: Passing 1 fds to service"} - {"dt": "2022-09-06T11:20:30.477923", "lvl": "target", "msg": "<47>[ 108.521818] systemd-journald[170]: Sent WATCHDOG=1 notification."} - {"dt": "2022-09-06T11:20:30.477965", "lvl": "target", "msg": "<47>[ 108.569908] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:20:30.478006", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m."} - {"dt": "2022-09-06T11:20:30.478046", "lvl": "target", "msg": "<31>[ 108.647974] systemd[1]: crond.service: Forked /usr/sbin/crond as 221"} - {"dt": "2022-09-06T11:20:30.478086", "lvl": "target", "msg": "<47>[ 108.653744] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:20:30.478126", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mPeriodic Command Scheduler[0m."} - {"dt": "2022-09-06T11:20:30.478169", "lvl": "target", "msg": "<47>[ 108.781741] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:20:30.478213", "lvl": "target", "msg": " Starting [0;1;39mD-Bus System Message Bus[0m..."} - {"dt": "2022-09-06T11:20:30.478256", "lvl": "target", "msg": "<31>[ 108.819546] systemd[1]: getty@tty1.service: ConditionPathExists=/dev/tty0 succeeded."} - {"dt": "2022-09-06T11:20:30.478299", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mGetty on tty1[0m."} - {"dt": "2022-09-06T11:20:30.478339", "lvl": "target", "msg": "<47>[ 108.968902] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:20:30.478380", "lvl": "target", "msg": " Starting [0;1;39mTelephony service[0m..."} - {"dt": "2022-09-06T11:20:30.478432", "lvl": "target", "msg": "<31>[ 109.109763] systemd[1]: serial-getty@ttyAMA0.service: Job 101 serial-getty@ttyAMA0.service/start finished, result=done"} - {"dt": "2022-09-06T11:20:50.681220", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mSerial Getty on ttyAMA0[0m."} - {"dt": "2022-09-06T11:20:50.681389", "lvl": "target", "msg": "<31>[ 118.820404] systemd[1]: getty.target: starting held back, waiting for: serial-getty@ttyS2.service"} - {"dt": "2022-09-06T11:20:50.681444", "lvl": "target", "msg": "<31>[ 118.849399] systemd[1]: serial-getty@ttyS2.service: ConditionPathExists=/dev/ttyS2 succeeded."} - {"dt": "2022-09-06T11:20:50.681491", "lvl": "target", "msg": "<6>[ 118.940418] Bluetooth: Core ver 2.22"} - {"dt": "2022-09-06T11:20:50.681535", "lvl": "target", "msg": "<6>[ 118.940661] NET: Registered PF_BLUETOOTH protocol family"} - {"dt": "2022-09-06T11:20:50.681578", "lvl": "target", "msg": "<6>[ 118.940736] Bluetooth: HCI device and connection manager initialized"} - {"dt": "2022-09-06T11:20:50.681621", "lvl": "target", "msg": "<6>[ 118.940919] Bluetooth: HCI socket layer initialized"} - {"dt": "2022-09-06T11:20:50.681662", "lvl": "target", "msg": "<6>[ 118.941029] Bluetooth: L2CAP socket layer initialized"} - {"dt": "2022-09-06T11:20:50.681702", "lvl": "target", "msg": "<6>[ 118.941218] Bluetooth: SCO socket layer initialized"} - {"dt": "2022-09-06T11:20:50.681742", "lvl": "target", "msg": "<31>[ 119.149696] systemd[1]: serial-getty@ttyS2.service: Changed dead -> running"} - {"dt": "2022-09-06T11:20:50.681783", "lvl": "target", "msg": "<31>[ 119.170889] systemd[1]: serial-getty@ttyS2.service: Job 99 serial-getty@ttyS2.service/start finished, result=done"} - {"dt": "2022-09-06T11:20:50.681823", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mSerial Getty on ttyS2[0m."} - {"dt": "2022-09-06T11:20:50.681863", "lvl": "target", "msg": "<31>[ 119.231139] systemd[1]: getty.target changed dead -> active"} - {"dt": "2022-09-06T11:20:50.681904", "lvl": "target", "msg": "<31>[ 119.250750] systemd[1]: getty.target: Job 94 getty.target/start finished, result=done"} - {"dt": "2022-09-06T11:20:50.681947", "lvl": "target", "msg": "[[0;32m OK [0m] Reached target [0;1;39mLogin Prompts[0m."} - {"dt": "2022-09-06T11:20:50.681989", "lvl": "target", "msg": "<31>[ 119.291110] systemd[1]: multi-user.target: starting held back, waiting for: systemd-logind.service"} - {"dt": "2022-09-06T11:20:50.682030", "lvl": "target", "msg": "<31>[ 119.390560] systemd[1]: systemd-logind.service: Passing 0 fds to service"} - {"dt": "2022-09-06T11:20:50.682071", "lvl": "target", "msg": "<31>[ 119.470403] systemd[1]: systemd-logind.service: Forked /lib/systemd/systemd-logind as 228"} - {"dt": "2022-09-06T11:20:50.682112", "lvl": "target", "msg": "<31>[ 119.580462] systemd[1]: systemd-logind.service: Changed dead -> start"} - {"dt": "2022-09-06T11:20:50.682152", "lvl": "target", "msg": " Starting [0;1;39mUser Login Management[0m..."} - {"dt": "2022-09-06T11:20:50.682193", "lvl": "target", "msg": "<47>[ 119.635700] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:20:50.682234", "lvl": "target", "msg": "<31>[ 119.639698] systemd[1]: systemd-journald.service: Added fd 52 (n/a) to fd store."} - {"dt": "2022-09-06T11:20:50.682275", "lvl": "target", "msg": "<31>[ 119.689697] systemd[1]: systemd-journald.service: Added fd 53 (n/a) to fd store."} - {"dt": "2022-09-06T11:20:50.682315", "lvl": "target", "msg": "<31>[ 119.719358] systemd[1]: systemd-journald.service: Added fd 54 (n/a) to fd store."} - {"dt": "2022-09-06T11:20:50.682354", "lvl": "target", "msg": "<31>[ 119.783795] systemd[1]: systemd-journald.service: Added fd 56 (n/a) to fd store."} - {"dt": "2022-09-06T11:21:08.358751", "lvl": "target", "msg": "<31>[ 129.590871] systemd[1]: dbus.service: Got notification message from PID 222 (READY=1)"} - {"dt": "2022-09-06T11:21:08.358916", "lvl": "target", "msg": "<31>[ 129.609362] systemd[1]: dbus.service: Changed start -> running"} - {"dt": "2022-09-06T11:21:08.358971", "lvl": "target", "msg": "<31>[ 129.629708] systemd[1]: dbus.service: Job 83 dbus.service/start finished, result=done"} - {"dt": "2022-09-06T11:21:08.359017", "lvl": "target", "msg": "[[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m."} - {"dt": "2022-09-06T11:21:08.359064", "lvl": "target", "msg": "<31>[ 129.669447] systemd[1]: dbus.socket: Changed listening -> running"} - {"dt": "2022-09-06T11:21:08.359108", "lvl": "target", "msg": "<31>[ 129.689489] systemd[1]: Bus bus-api-system: changing state UNSET \u2192 OPENING"} - {"dt": "2022-09-06T11:21:08.359153", "lvl": "target", "msg": "<31>[ 129.709465] systemd[1]: sd-bus: starting bus bus-api-system by connecting to /run/dbus/system_bus_socket..."} - {"dt": "2022-09-06T11:21:08.359195", "lvl": "target", "msg": "<31>[ 129.729456] systemd[1]: Bus bus-api-system: changing state OPENING \u2192 AUTHENTICATING"} - {"dt": "2022-09-06T11:21:08.359237", "lvl": "target", "msg": "<31>[ 129.749661] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1 iface=org.freedesktop.systemd1.Manager"} - {"dt": "2022-09-06T11:21:08.359279", "lvl": "target", "msg": "<31>[ 129.769956] systemd[228]: Applying namespace mount on /run/systemd/unit-root/"} - {"dt": "2022-09-06T11:21:08.359321", "lvl": "target", "msg": "<31>[ 129.771224] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/job iface=org.freedesktop.systemd1.Job"} - {"dt": "2022-09-06T11:21:08.359362", "lvl": "target", "msg": "<31>[ 129.779413] systemd[218]: Not remounting /run/systemd/unit-root/sys/kernel/security deny-listed by /run/systemd/unit-root/sys, called for /run/systemd/unit-root/"} - {"dt": "2022-09-06T11:21:08.359405", "lvl": "target", "msg": "<31>[ 129.779929] systemd-udevd[190]: Broadcom BCM7425: sd-device-monitor: Passed 168 byte to netlink monitor"} - {"dt": "2022-09-06T11:21:08.359446", "lvl": "target", "msg": "<31>[ 129.799358] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Unit"} - {"dt": "2022-09-06T11:21:08.359488", "lvl": "target", "msg": "<31>[ 141.262607] systemd[225]: KDSETMODE ioctl for switching to text mode failed on TTY, ignoring: Inappropriate ioctl for device"} - {"dt": "2022-09-06T11:21:08.359530", "lvl": "target", "msg": "<31>[ 141.269754] systemd-udevd[190]: Validate module index"} - {"dt": "2022-09-06T11:21:08.359570", "lvl": "target", "msg": "<31>[ 141.270038] systemd-udevd[200]: Broadcom BCM7425: Device processed (SEQNUM=2497, ACTION=add)"} - {"dt": "2022-09-06T11:21:08.359610", "lvl": "target", "msg": "<31>[ 141.272451] systemd[223]: Not writing wtmp: No such file or directory"} - {"dt": "2022-09-06T11:21:27.258199", "lvl": "target", "msg": "<31>[ 141.279682] systemd[218]: Not remounting /run/systemd/unit-root/sys/fs/cgroup deny-listed by /run/systemd/unit-root/sys, called for /run/systemd/unit-root/"} - {"dt": "2022-09-06T11:21:27.258362", "lvl": "target", "msg": "<31>[ 141.279861] systemd[228]: Applying namespace mount on /run/systemd/unit-root/dev"} - {"dt": "2022-09-06T11:21:27.258416", "lvl": "target", "msg": "<31>[ 141.309840] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Automount"} - {"dt": "2022-09-06T11:21:27.258463", "lvl": "target", "msg": "<31>[ 141.329593] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Device"} - {"dt": "2022-09-06T11:21:27.258507", "lvl": "target", "msg": "<47>[ 141.343051] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:27.258551", "lvl": "target", "msg": "<31>[ 141.349608] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Mount"} - {"dt": "2022-09-06T11:21:27.258593", "lvl": "target", "msg": "<47>[ 141.354121] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:27.258647", "lvl": "target", "msg": "<31>[ 141.369696] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Scope"} - {"dt": "2022-09-06T11:21:27.258690", "lvl": "target", "msg": "<31>[ 141.389640] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Service"} - {"dt": "2022-09-06T11:21:27.258731", "lvl": "target", "msg": "<31>[ 141.410011] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Slice"} - {"dt": "2022-09-06T11:21:27.258772", "lvl": "target", "msg": "<31>[ 141.429591] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Socket"} - {"dt": "2022-09-06T11:21:27.258812", "lvl": "target", "msg": "<31>[ 141.446269] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Target"} - {"dt": "2022-09-06T11:21:27.258853", "lvl": "target", "msg": "<31>[ 153.300791] systemd-udevd[190]: Broadcom BCM7429: sd-device-monitor: Passed 168 byte to netlink monitor"} - {"dt": "2022-09-06T11:21:27.258893", "lvl": "target", "msg": "<31>[ 153.304297] systemd[228]: Successfully mounted /run/systemd/inaccessible/chr to /run/systemd/unit-root/dev/kmsg"} - {"dt": "2022-09-06T11:21:27.258934", "lvl": "target", "msg": "<31>[ 153.309548] systemd[218]: Not remounting /run/systemd/unit-root/sys/fs/cgroup/memory deny-listed by /run/systemd/unit-root/sys, called for /run/systemd/unit-root/"} - {"dt": "2022-09-06T11:21:47.253387", "lvl": "target", "msg": "<31>[ 153.559352] systemd[1]: Registering bus object implementation for path=/org/freedesktop/systemd1/unit iface=org.freedesktop.systemd1.Timer"} - {"dt": "2022-09-06T11:21:47.253549", "lvl": "target", "msg": "<31>[ 153.589406] systemd[1]: systemd-resolved.service: Watching D-Bus name 'org.freedesktop.resolve1'."} - {"dt": "2022-09-06T11:21:47.253605", "lvl": "target", "msg": "<31>[ 153.629321] systemd[1]: systemd-logind.service: Watching D-Bus name 'org.freedesktop.login1'."} - {"dt": "2022-09-06T11:21:47.253652", "lvl": "target", "msg": "<31>[ 153.649344] systemd[1]: systemd-timesyncd.service: Watching D-Bus name 'org.freedesktop.timesync1'."} - {"dt": "2022-09-06T11:21:47.253696", "lvl": "target", "msg": "<31>[ 153.669327] systemd[1]: systemd-networkd.service: Watching D-Bus name 'org.freedesktop.network1'."} - {"dt": "2022-09-06T11:21:47.253739", "lvl": "target", "msg": "<31>[ 153.719568] systemd[1]: Successfully connected to system bus."} - {"dt": "2022-09-06T11:21:47.253783", "lvl": "target", "msg": "<31>[ 153.759366] systemd[1]: systemd-journald.service: Added fd 58 (n/a) to fd store."} - {"dt": "2022-09-06T11:21:47.253826", "lvl": "target", "msg": "<31>[ 153.849695] systemd[1]: systemd-udevd.service: Got notification message from PID 190 (WATCHDOG=1)"} - {"dt": "2022-09-06T11:21:47.253868", "lvl": "target", "msg": "<31>[ 153.899326] systemd[1]: systemd-journald.service: Added fd 60 (n/a) to fd store."} - {"dt": "2022-09-06T11:21:47.253909", "lvl": "target", "msg": "<31>[ 154.009499] systemd[1]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=AddMatch cookie=6 reply_cookie=0 signature=s error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.253956", "lvl": "target", "msg": "<31>[ 154.159348] systemd[1]: Bus bus-api-system: changing state HELLO \u2192 RUNNING"} - {"dt": "2022-09-06T11:21:47.253999", "lvl": "target", "msg": "<31>[ 154.229348] systemd[1]: systemd-resolved.service: D-Bus name org.freedesktop.resolve1 now not owned by anyone."} - {"dt": "2022-09-06T11:21:47.254043", "lvl": "target", "msg": "<31>[ 154.259403] systemd[1]: Match type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='org.freedesktop.Avahi' successfully installed."} - {"dt": "2022-09-06T11:21:47.254086", "lvl": "target", "msg": ""} - {"dt": "2022-09-06T11:21:47.254128", "lvl": "target", "msg": "Linux-Kernel-Functional-Testing nodistro.0 am57xx-evm ttyAMA0"} - {"dt": "2022-09-06T11:21:47.254170", "lvl": "target", "msg": ""} - {"dt": "2022-09-06T11:21:47.254211", "lvl": "target", "msg": "am57xx-evm login: <31>[ 166.216468] systemd[228]: Not remounting /run/systemd/unit-root/dev/pts deny-listed by /run/systemd/unit-root/dev, called for /run/systemd/unit-root/"} - {"dt": "2022-09-06T11:21:47.254254", "lvl": "target", "msg": "<31>[ 166.219401] systemd-udevd[200]: Marvell 88E1111 (Finisar): Device processed (SEQNUM=2516, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.256351", "lvl": "debug", "msg": "Matched prompt #8: (.*)login:"} - {"dt": "2022-09-06T11:21:47.256423", "lvl": "results", "msg": {"case": "kernel-messages", "definition": "lava", "duration": "319.16", "extra": {"extra": [{"success": "(.*)login:"}]}, "level": "2.2.1", "namespace": "common", "result": "pass"}} - {"dt": "2022-09-06T11:21:47.256530", "lvl": "debug", "msg": "Sending username root"} - {"dt": "2022-09-06T11:21:47.256585", "lvl": "input", "msg": "root\n"} - {"dt": "2022-09-06T11:21:47.356772", "lvl": "target", "msg": "<31>[ 166.220126] systemd-udevd[190]: Marvell 88E1112: Device is queued (SEQNUM=2517, ACTION=addroot"} - {"dt": "2022-09-06T11:21:47.356914", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"} - {"dt": "2022-09-06T11:21:47.357042", "lvl": "debug", "msg": "login-action: Wait for prompt ['root@(.*):[/~]#', 'Login incorrect', 'Login timed out'] (timeout 00:34:40)"} - {"dt": "2022-09-06T11:21:47.357261", "lvl": "target", "msg": ")"} - {"dt": "2022-09-06T11:21:47.357332", "lvl": "target", "msg": "<31>[ 166.279286] systemd[1]: avahi-daemon.service: D-Bus name org.freedesktop.Avahi now not owned by anyone."} - {"dt": "2022-09-06T11:21:47.357392", "lvl": "target", "msg": "<31>[ 166.499312] systemd[1]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.3 path=n/a interface=n/a member=n/a cookie=11 reply_cookie=10 signature=n/a error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.357466", "lvl": "target", "msg": "<31>[ 166.526115] systemd[1]: serial-getty@ttyS2.service: Service RestartSec=100ms expired, scheduling restart."} - {"dt": "2022-09-06T11:21:47.357523", "lvl": "target", "msg": "<31>[ 166.570721] systemd[1]: serial-getty@ttyS2.service: Installed new job serial-getty@ttyS2.service/restart as 102"} - {"dt": "2022-09-06T11:21:47.357578", "lvl": "target", "msg": "<31>[ 166.629311] systemd[1]: Match type='signal',sender='org.freedesktop.DBus',path='/org/freedesktop/DBus',interface='org.freedesktop.DBus',member='NameOwnerChanged',arg0='org.ofono' successfully installed."} - {"dt": "2022-09-06T11:21:47.357646", "lvl": "target", "msg": "<31>[ 166.809280] systemd[1]: serial-getty@ttyS2.service: ConditionPathExists=/dev/ttyS2 succeeded."} - {"dt": "2022-09-06T11:21:47.357701", "lvl": "target", "msg": "<31>[ 167.439421] systemd[1]: network.target changed dead -> active"} - {"dt": "2022-09-06T11:21:47.357767", "lvl": "target", "msg": "<31>[ 167.516201] systemd[1]: avahi-daemon.service: Forked /usr/sbin/avahi-daemon as 230"} - {"dt": "2022-09-06T11:21:47.357827", "lvl": "target", "msg": "<47>[ 167.555881] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.357881", "lvl": "target", "msg": "<31>[ 167.560006] systemd[1]: avahi-daemon.service: Changed dead -> start"} - {"dt": "2022-09-06T11:21:47.357936", "lvl": "target", "msg": "<31>[ 167.599333] systemd[1]: systemd-journald.service: Added fd 59 (n/a) to fd store."} - {"dt": "2022-09-06T11:21:47.357990", "lvl": "target", "msg": "<31>[ 167.619394] systemd[1]: systemd-resolved.service: D-Bus name org.freedesktop.resolve1 now owned by :1.4"} - {"dt": "2022-09-06T11:21:47.358044", "lvl": "target", "msg": "<31>[ 167.759300] systemd[1]: avahi-daemon.service: Job 85 avahi-daemon.service/start finished, result=done"} - {"dt": "2022-09-06T11:21:47.358089", "lvl": "target", "msg": "<31>[ 167.779393] systemd[1]: avahi-daemon.socket: Changed listening -> running"} - {"dt": "2022-09-06T11:21:47.358128", "lvl": "target", "msg": "<31>[ 179.772950] systemd-udevd[200]: Qualcomm QCA8081: sd-device-monitor: Passed 168 byte to netlink monitor"} - {"dt": "2022-09-06T11:21:47.358167", "lvl": "target", "msg": "<31>[ 179.778794] systemd-udevd[190]: RTL8201CP Ethernet: Device ready for processing (SEQNUM=2563, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.358384", "lvl": "target", "msg": "<31>[ 179.780586] systemd[228]: Not remounting /run/systemd/unit-root/sys/fs/cgroup/blkio deny-listed by /run/systemd/unit-root/sys/fs/cgroup, called for /run/systemd/unit-root/sys"} - {"dt": "2022-09-06T11:21:47.358433", "lvl": "target", "msg": "<31>[ 179.849294] systemd[1]: multi-user.target: starting held back, waiting for: systemd-logind.service"} - {"dt": "2022-09-06T11:21:47.358475", "lvl": "target", "msg": "<47>[ 179.852533] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.358516", "lvl": "target", "msg": "<31>[ 179.879310] systemd[1]: systemd-journald.service: Added fd 22 (n/a) to fd store."} - {"dt": "2022-09-06T11:21:47.358556", "lvl": "target", "msg": "<46>[ 180.243250] systemd-journald[170]: Data hash table of /run/log/journal/65e6c7da10444f3f831c7273e1a36d30/system.journal has a fill level at 75.0 (10923 of 14563 items, 8388608 file size, 767 bytes per hash table item), suggesting rotation."} - {"dt": "2022-09-06T11:21:47.358601", "lvl": "target", "msg": "<46>[ 180.243682] systemd-journald[170]: /run/log/journal/65e6c7da10444f3f831c7273e1a36d30/system.journal: Journal header limits reached or header out-of-date, rotating."} - {"dt": "2022-09-06T11:21:47.358644", "lvl": "target", "msg": "<47>[ 180.244004] systemd-journald[170]: Rotating..."} - {"dt": "2022-09-06T11:21:47.358683", "lvl": "target", "msg": "<47>[ 180.246664] systemd-journald[170]: Reserving 333 entries in field hash table."} - {"dt": "2022-09-06T11:21:47.358723", "lvl": "target", "msg": "<47>[ 180.254879] systemd-journald[170]: Reserving 14563 entries in data hash table."} - {"dt": "2022-09-06T11:21:47.358762", "lvl": "target", "msg": "<47>[ 180.261568] systemd-journald[170]: Vacuuming..."} - {"dt": "2022-09-06T11:21:47.358800", "lvl": "target", "msg": "<47>[ 180.264110] systemd-journald[170]: Vacuuming done, freed 0B of archived journals from /run/log/journal/65e6c7da10444f3f831c7273e1a36d30."} - {"dt": "2022-09-06T11:21:47.358840", "lvl": "target", "msg": "<31>[ 180.559328] systemd[1]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=GetNameOwner cookie=17 reply_cookie=0 signature=s error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.358881", "lvl": "target", "msg": "<31>[ 180.579249] systemd[1]: Got message type=signal sender=org.freedesktop.DBus destination=n/a path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameOwnerChanged cookie=7 reply_cookie=0 signature=sss error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.358921", "lvl": "target", "msg": "<31>[ 193.790028] systemd-udevd[190]: ahci: Device is queued (SEQNUM=2601, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.358959", "lvl": "target", "msg": "<31>[ 193.798415] systemd-udevd[205]: 8250_pericom: Device processed (SEQNUM=2600, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.358998", "lvl": "target", "msg": "<47>[ 194.379032] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.359214", "lvl": "target", "msg": "<31>[ 194.730396] systemd[1]: Registering bus object implementation for path=/org/freedesktop/LogControl1 iface=org.freedesktop.LogControl1"} - {"dt": "2022-09-06T11:21:47.359262", "lvl": "target", "msg": "<31>[ 194.762637] systemd[1]: Sent message type=method_return sender=org.freedesktop.systemd1 destination=n/a path=n/a interface=n/a member=n/a cookie=2 reply_cookie=2 signature=v error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.359305", "lvl": "target", "msg": "<47>[ 194.877515] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.359345", "lvl": "target", "msg": "<47>[ 198.507760] systemd-journald[170]: Sent WATCHDOG=1 notification."} - {"dt": "2022-09-06T11:21:47.359384", "lvl": "target", "msg": "<46>[ 199.724142] systemd-journald[170]: Data hash table of /run/log/journal/65e6c7da10444f3f831c7273e1a36d30/system.journal has a fill level at 75.0 (10923 of 14563 items, 8388608 file size, 767 bytes per hash table item), suggesting rotation."} - {"dt": "2022-09-06T11:21:47.359424", "lvl": "target", "msg": "<46>[ 199.724462] systemd-journald[170]: /run/log/journal/65e6c7da10444f3f831c7273e1a36d30/system.journal: Journal header limits reached or header out-of-date, rotating."} - {"dt": "2022-09-06T11:21:47.359464", "lvl": "target", "msg": "<47>[ 199.724735] systemd-journald[170]: Rotating..."} - {"dt": "2022-09-06T11:21:47.359502", "lvl": "target", "msg": "<47>[ 199.735049] systemd-journald[170]: Reserving 333 entries in field hash table."} - {"dt": "2022-09-06T11:21:47.359541", "lvl": "target", "msg": "<47>[ 199.743340] systemd-journald[170]: Reserving 14563 entries in data hash table."} - {"dt": "2022-09-06T11:21:47.359580", "lvl": "target", "msg": "<47>[ 199.747626] systemd-journald[170]: Vacuuming..."} - {"dt": "2022-09-06T11:21:47.359618", "lvl": "target", "msg": "<47>[ 199.750503] systemd-journald[170]: Vacuuming done, freed 0B of archived journals from /run/log/journal/65e6c7da10444f3f831c7273e1a36d30."} - {"dt": "2022-09-06T11:21:47.359657", "lvl": "target", "msg": "<31>[ 202.509425] systemd[1]: dev-ttyS4.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.359696", "lvl": "target", "msg": "<31>[ 202.525970] systemd[1]: sys-devices-platform-serial8250-tty-ttyS4.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.359734", "lvl": "target", "msg": "<31>[ 203.064103] systemd[1]: sys-devices-virtual-block-ram1.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.359772", "lvl": "target", "msg": "<31>[ 208.979408] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=85 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.359993", "lvl": "target", "msg": "<31>[ 209.005841] systemd[1]: serial-getty@ttyS2.service: Child 234 belongs to serial-getty@ttyS2.service."} - {"dt": "2022-09-06T11:21:47.360039", "lvl": "target", "msg": "<31>[ 209.311054] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/serial_2dgetty_40ttyS2_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=112 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.360083", "lvl": "target", "msg": "<47>[ 209.316353] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.360123", "lvl": "target", "msg": "<31>[ 209.329432] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/serial_2dgetty_40ttyS2_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=113 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.360164", "lvl": "target", "msg": "<31>[ 209.430482] systemd[1]: dev-ram8.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.360203", "lvl": "target", "msg": "<31>[ 209.689647] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=136 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.360243", "lvl": "target", "msg": "<31>[ 209.739379] systemd[1]: dev-mtdblock0.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.360281", "lvl": "target", "msg": "<46>[ 212.149234] systemd-journald[170]: Data hash table of /run/log/journal/65e6c7da10444f3f831c7273e1a36d30/system.journal has a fill level at 75.0 (10923 of 14563 items, 8388608 file size, 767 bytes per hash table item), suggesting rotation."} - {"dt": "2022-09-06T11:21:47.360321", "lvl": "target", "msg": "<46>[ 212.149745] systemd-journald[170]: /run/log/journal/65e6c7da10444f3f831c7273e1a36d30/system.journal: Journal header limits reached or header out-of-date, rotating."} - {"dt": "2022-09-06T11:21:47.360359", "lvl": "target", "msg": "<47>[ 212.150005] systemd-journald[170]: Rotating..."} - {"dt": "2022-09-06T11:21:47.360398", "lvl": "target", "msg": "<47>[ 212.159575] systemd-journald[170]: Reserving 333 entries in field hash table."} - {"dt": "2022-09-06T11:21:47.360437", "lvl": "target", "msg": "<47>[ 212.167937] systemd-journald[170]: Reserving 14563 entries in data hash table."} - {"dt": "2022-09-06T11:21:47.360476", "lvl": "target", "msg": "<31>[ 225.302753] systemd-udevd[205]: ptyv6: Device processed (SEQNUM=3958, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.360516", "lvl": "target", "msg": "<31>[ 225.308928] systemd-udevd[200]: ptyv7: sd-device-monitor: Passed 189 byte to netlink monitor"} - {"dt": "2022-09-06T11:21:47.360736", "lvl": "target", "msg": "<31>[ 225.310617] systemd-udevd[206]: ptyv8: Device processed (SEQNUM=3960, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.360783", "lvl": "target", "msg": "<47>[ 225.312639] systemd-journald[170]: Vacuuming..."} - {"dt": "2022-09-06T11:21:47.360825", "lvl": "target", "msg": "<31>[ 225.317366] systemd-udevd[190]: ptyv9: Device ready for processing (SEQNUM=3961, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.360865", "lvl": "target", "msg": "<47>[ 225.331323] systemd-journald[170]: Vacuuming done, freed 0B of archived journals from /run/log/journal/65e6c7da10444f3f831c7273e1a36d30."} - {"dt": "2022-09-06T11:21:47.360905", "lvl": "target", "msg": "<31>[ 225.379497] systemd[1]: Child 236 (agetty) died (code=exited, status=1/FAILURE)"} - {"dt": "2022-09-06T11:21:47.360944", "lvl": "target", "msg": "<31>[ 225.412142] systemd[1]: serial-getty@ttyS2.service: Changed running -> dead"} - {"dt": "2022-09-06T11:21:47.360983", "lvl": "target", "msg": "<31>[ 225.437366] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1/unit/serial_2dgetty_40ttyS2_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=155 reply_cookie=0 signature=sa{sv}as error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.361023", "lvl": "target", "msg": "<47>[ 226.024647] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.361063", "lvl": "target", "msg": "<31>[ 226.039326] systemd[1]: systemd-journald.service: Got notification message from PID 170 (FDSTORE=1)"} - {"dt": "2022-09-06T11:21:47.361102", "lvl": "target", "msg": "<31>[ 226.059276] systemd[1]: systemd-journald.service: Added fd 12 (n/a) to fd store."} - {"dt": "2022-09-06T11:21:47.361141", "lvl": "target", "msg": "<31>[ 228.035629] systemd[1]: dev-ttya8.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.361179", "lvl": "target", "msg": "<31>[ 228.848498] systemd[1]: dev-ttyd2.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.361218", "lvl": "target", "msg": "<31>[ 229.731034] systemd[1]: dev-ttyq2.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.361257", "lvl": "target", "msg": "<31>[ 230.437412] systemd[1]: dev-ttys5.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.361296", "lvl": "target", "msg": "<31>[ 230.544001] systemd[1]: sys-devices-virtual-tty-ttys7.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.361335", "lvl": "target", "msg": "<31>[ 230.879534] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=481 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.361375", "lvl": "target", "msg": "<31>[ 242.506327] systemd-udevd[200]: ttytd: Processing device (SEQNUM=4254, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.361414", "lvl": "target", "msg": "<31>[ 242.507084] systemd-udevd[203]: ttytb: Failed to get watch handle, ignoring: No such file or directory"} - {"dt": "2022-09-06T11:21:47.361635", "lvl": "target", "msg": "<31>[ 242.507694] systemd-udevd[190]: ttyte: sd-device-monitor: Passed 188 byte to netlink monitor"} - {"dt": "2022-09-06T11:21:47.361682", "lvl": "target", "msg": "<31>[ 242.529695] systemd[1]: dev-ttyte.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.361724", "lvl": "target", "msg": "<31>[ 242.588543] systemd[1]: Child 240 (agetty) died (code=exited, status=1/FAILURE)"} - {"dt": "2022-09-06T11:21:47.361763", "lvl": "target", "msg": "<31>[ 242.819666] systemd[1]: serial-getty@ttyS2.service: Enqueued job serial-getty@ttyS2.service/restart as 361"} - {"dt": "2022-09-06T11:21:47.361803", "lvl": "target", "msg": "<31>[ 243.128946] systemd[1]: sys-devices-virtual-tty-ttyuf.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.361842", "lvl": "target", "msg": "<31>[ 243.399825] systemd[1]: dev-ttyvc.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.361881", "lvl": "target", "msg": "<46>[ 243.929152] systemd-journald[170]: Data hash table of /run/log/journal/65e6c7da10444f3f831c7273e1a36d30/system.journal has a fill level at 75.0 (10923 of 14563 items, 8388608 file size, 767 bytes per hash table item), suggesting rotation."} - {"dt": "2022-09-06T11:21:47.361922", "lvl": "target", "msg": "<46>[ 243.937796] systemd-journald[170]: /run/log/journal/65e6c7da10444f3f831c7273e1a36d30/system.journal: Journal header limits reached or header out-of-date, rotating."} - {"dt": "2022-09-06T11:21:47.361961", "lvl": "target", "msg": "<47>[ 243.938040] systemd-journald[170]: Rotating..."} - {"dt": "2022-09-06T11:21:47.362000", "lvl": "target", "msg": "<47>[ 243.948217] systemd-journald[170]: Reserving 333 entries in field hash table."} - {"dt": "2022-09-06T11:21:47.362040", "lvl": "target", "msg": "<47>[ 243.956665] systemd-journald[170]: Reserving 14563 entries in data hash table."} - {"dt": "2022-09-06T11:21:47.362079", "lvl": "target", "msg": "<31>[ 243.959821] systemd[1]: dev-ttyxa.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.362118", "lvl": "target", "msg": "<47>[ 243.961003] systemd-journald[170]: Vacuuming..."} - {"dt": "2022-09-06T11:21:47.362156", "lvl": "target", "msg": "<47>[ 243.965169] systemd-journald[170]: Vacuuming done, freed 0B of archived journals from /run/log/journal/65e6c7da10444f3f831c7273e1a36d30."} - {"dt": "2022-09-06T11:21:47.362196", "lvl": "target", "msg": "<31>[ 243.979315] systemd[1]: sys-devices-virtual-tty-ttyxa.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.362235", "lvl": "target", "msg": "<31>[ 243.999638] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=615 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.362275", "lvl": "target", "msg": "<31>[ 262.706386] systemd-udevd[200]: ttyxd: Failed to get watch handle, ignoring: No such file or directory"} - {"dt": "2022-09-06T11:21:47.362495", "lvl": "target", "msg": "<31>[ 262.711369] systemd-udevd[206]: ttyxe: Failed to get watch handle, ignoring: No such file or directory"} - {"dt": "2022-09-06T11:21:47.362543", "lvl": "target", "msg": "<31>[ 262.717026] systemd-udevd[190]: ttyy0: Device is queued (SEQNUM=4321, ACTION=add)"} - {"dt": "2022-09-06T11:21:47.362585", "lvl": "target", "msg": "<31>[ 262.739329] systemd[1]: dev-ttyxc.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.362633", "lvl": "target", "msg": "<31>[ 262.819377] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=621 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.362676", "lvl": "target", "msg": "<31>[ 262.889424] systemd[1]: dev-ttyy0.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.362717", "lvl": "target", "msg": "<31>[ 262.909476] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=625 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.362757", "lvl": "target", "msg": "<31>[ 262.949389] systemd[1]: dev-ttyy1.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.362796", "lvl": "target", "msg": "<31>[ 263.005755] systemd[1]: dev-ttyy2.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.362835", "lvl": "target", "msg": "<31>[ 263.069534] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=631 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.362875", "lvl": "target", "msg": "<31>[ 263.111877] systemd[1]: dev-ttyy4.device: Changed dead -> plugged"} - {"dt": "2022-09-06T11:21:47.362914", "lvl": "target", "msg": "<31>[ 263.330454] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=647 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.362955", "lvl": "target", "msg": "<31>[ 263.580858] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=660 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.362995", "lvl": "target", "msg": "<31>[ 263.694335] systemd[1]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitNew cookie=665 reply_cookie=0 signature=so error-name=n/a error-message=n/a"} - {"dt": "2022-09-06T11:21:47.363216", "lvl": "target", "msg": "<31>[ 280.387492] systemd-udevd[206]: vcsu: Setting permissions /dev/vcsu, uid=0, gid=5, mode=0660"} - {"dt": "2022-09-06T11:21:47.363263", "lvl": "target", "msg": "<47>[ 280.762603] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.363305", "lvl": "target", "msg": "<47>[ 290.818065] systemd-journald[170]: Sent WATCHDOG=1 notification."} - {"dt": "2022-09-06T11:21:47.363345", "lvl": "target", "msg": "<47>[ 291.131734] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.363385", "lvl": "target", "msg": "<47>[ 301.586319] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.363425", "lvl": "target", "msg": "<47>[ 312.042993] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:47.363464", "lvl": "target", "msg": "root"} - {"dt": "2022-09-06T11:21:50.296445", "lvl": "target", "msg": "7[r[999;999H[6n<47>[ 322.319491] systemd-journald[170]: Successfully sent stream file descriptor to service manager."} - {"dt": "2022-09-06T11:21:50.709943", "lvl": "debug", "msg": "Setting prompt string to ['root@(.*):[/~]#']"} - {"dt": "2022-09-06T11:21:50.710230", "lvl": "debug", "msg": "end: 2.2.1 login-action (duration 00:05:23) [common]"} - {"dt": "2022-09-06T11:21:50.710366", "lvl": "results", "msg": {"case": "login-action", "definition": "lava", "duration": "322.61", "extra": {"success": "(.*)login:"}, "level": "2.2.1", "namespace": "common", "result": "pass"}} - {"dt": "2022-09-06T11:21:50.710593", "lvl": "debug", "msg": "end: 2.2 auto-login-action (duration 00:05:23) [common]"} - {"dt": "2022-09-06T11:21:50.710767", "lvl": "info", "msg": "end: 2 boot-image-retry (duration 00:05:23) [common]"} - {"dt": "2022-09-06T11:21:50.710923", "lvl": "info", "msg": "start: 3 finalize (timeout 00:05:00) [common]"} - {"dt": "2022-09-06T11:21:50.711080", "lvl": "debug", "msg": "start: 3.1 power-off (timeout 00:00:30) [common]"} - {"dt": "2022-09-06T11:21:50.711236", "lvl": "debug", "msg": "end: 3.1 power-off (duration 00:00:00) [common]"} - {"dt": "2022-09-06T11:21:50.711369", "lvl": "debug", "msg": "start: 3.2 read-feedback (timeout 00:05:00) [common]"} - {"dt": "2022-09-06T11:21:51.712652", "lvl": "info", "msg": "Finalising connection for namespace 'common'"} - {"dt": "2022-09-06T11:21:51.712916", "lvl": "input", "msg": "poweroff\n"} - {"dt": "2022-09-06T11:21:51.813889", "lvl": "target", "msg": "root@am57xx-evm:~# poweroff"} - {"dt": "2022-09-06T11:21:51.814452", "lvl": "debug", "msg": "Already disconnected"} - {"dt": "2022-09-06T11:21:51.814595", "lvl": "target", "msg": "poweroff"} - {"dt": "2022-09-06T11:21:51.915378", "lvl": "debug", "msg": "end: 3.2 read-feedback (duration 00:00:01) [common]"} - {"dt": "2022-09-06T11:21:51.915653", "lvl": "debug", "msg": "Already disconnected"} - {"dt": "2022-09-06T11:21:51.915811", "lvl": "info", "msg": "end: 3 finalize (duration 00:00:01) [common]"} - {"dt": "2022-09-06T11:21:51.915984", "lvl": "info", "msg": "Cleaning after the job"} - {"dt": "2022-09-06T11:21:51.916143", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/kernel"} - {"dt": "2022-09-06T11:21:51.919792", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/rootfs"} - {"dt": "2022-09-06T11:21:52.100062", "lvl": "debug", "msg": "Cleaning up download directory: /var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1/deployimages-dzycg0rs/rootfs.modules"} - {"dt": "2022-09-06T11:21:52.101474", "lvl": "info", "msg": "Root tmp directory removed at /var/lib/lava/dispatcher/tmp/tuxrun-m9_vko2h-1"} - {"dt": "2022-09-06T11:21:52.103463", "lvl": "info", "msg": "Job finished correctly"} - {"dt": "2022-09-06T11:21:52.103590", "lvl": "results", "msg": {"case": "job", "definition": "lava", "result": "pass"}}