On Fri, May 13, 2022 at 11:51 AM Jan Kiszka <[email protected]> wrote: > > On 12.05.22 13:37, Lad, Prabhakar wrote: > > Hi Jan, > > > > On Thu, May 12, 2022 at 12:16 PM Jan Kiszka <[email protected]> wrote: > >> > >> On 12.05.22 13:06, Lad, Prabhakar wrote: > >>> Hi Jan, > >>> > >>> On Thu, May 12, 2022 at 11:24 AM Jan Kiszka <[email protected]> > >>> wrote: > >>>> > >>>> On 12.05.22 09:01, Lad, Prabhakar wrote: > >>>>> Hi Jan, > >>>>> > >>>>> On Thu, May 12, 2022 at 6:45 AM Jan Kiszka <[email protected]> > >>>>> wrote: > >>>>>> > >>>>>> On 11.05.22 19:09, Lad, Prabhakar wrote: > >>>>>>> Hi Jan, > >>>>>>> > >>>>>>> On Wed, May 11, 2022 at 4:11 PM Jan Kiszka <[email protected]> > >>>>>>> wrote: > >>>>>>>> > >>>>>>>> On 11.05.22 13:20, Prabhakar Lad wrote: > >>>>>>>>> To add further more details: > >>>>>>>>> > >>>>>>>>> I am using jailhouse-enabling/5.10 Linux branch [0] with -next > >>>>>>>>> branch > >>>>>>>>> for jailhouse [1]. > >>>>>>>>> > >>>>>>>>> I added some debug prints and I see the panic is caused when entry() > >>>>>>>>> function is called (in enter_hypervisor). The entry function lands > >>>>>>>>> into > >>>>>>>>> assembly code (entry.S). I dont have a JTAG to see which exact > >>>>>>>>> instruction is causing this issue. > >>>>>>>> > >>>>>>>> So, already the first instruction in the loaded hypervisor binary is > >>>>>>>> not > >>>>>>>> executable? That would explain why we see no hypervisor output at > >>>>>>>> all. > >>>>>>>> > >>>>>>> To clarify when the hypervisor is loaded the output will be via > >>>>>>> debug_console specified in the root cell config? > >>>>>>> > >>>>>> > >>>>>> Correct - if you reach entry() in setup.c. > >>>>>> > >>>>>>>> Was that memory region properly reserved from Linux (via DTB > >>>>>>>> carve-out > >>>>>>>> e.g.)? > >>>>>>>> > >>>>>>> Yes I have the below memory reserved in my dts: > >>>>>>> > >>>>>>> memory@48000000 { > >>>>>>> device_type = "memory"; > >>>>>>> /* first 128MB is reserved for secure area. */ > >>>>>>> reg = <0x0 0x48000000 0x0 0x78000000>; > >>>>>>> }; > >>>>>>> > >>>>>>> reserved-memory { > >>>>>>> #address-cells = <2>; > >>>>>>> #size-cells = <2>; > >>>>>>> ranges; > >>>>>>> > >>>>>>> jh_inmate@a7f00000 { > >>>>>>> status = "okay"; > >>>>>>> no-map; > >>>>>>> reg = <0x00 0xa7f00000 0x00 0xf000000>; > >>>>>>> }; > >>>>>>> > >>>>>>> jailhouse: jailhouse@b6f00000 { > >>>>>>> status = "okay"; > >>>>>>> reg = <0x0 0xb6f00000 0x0 0x1000000>; > >>>>>>> no-map; > >>>>>>> }; > >>>>>>> }; > >>>>>>> > >>>>>>> Linux does report the hole in RAM: > >>>>>>> > >>>>>>> root@smarc-rzg2l:~# cat /proc/iomem | grep RAM > >>>>>>> 48000000-a7efffff : System RAM > >>>>>>> b7f00000-bfffffff : System RAM > >>>>>>> > >>>>>>> And below is my root cell config related to hypervisor memory: > >>>>>>> > >>>>>>> .hypervisor_memory = { > >>>>>>> .phys_start = 0xb6f00000, > >>>>>>> .size = 0x1000000, > >>>>>>> }, > >>>>>>> > >>>>>>> Is there anything obvious I have missed above? > >>>>>>> > >>>>>> > >>>>>> Nothing obvious to me so far. > >>>>>> > >>>>>> So, is this really the first instruction in > >>>>>> hypervisor/arch/arm64/entry.S, arch_entry, that triggers the > >>>>>> undefinstr? > >>>>>> Check the reported address by Linux against the disassembly of the > >>>>>> hypervisor. You could also play with adding 'ret' as first instruction, > >>>>>> to check if that returns without a crash. > >>>>>> > >>>>> I did play around with ret, below is my observation: > >>>>> > >>>>> Up until line 98 [0] just before calling arm_dcaches_flush adding ret > >>>>> returned without a crash. Adding a ret at line 99 [1] ie after > >>>>> arm_dcaches_flush it never returned. > >>>>> > >>>>> So I continued with adding ret in arm_dcaches_flush, I added ret as a > >>>>> first statement in arm_dcaches_flush and was able to see the panic! > >>>> > >>>> Which Jailhouse revision are you building? Already disassembled > >>>> hypervisor.o around arch_entry and arm_dcaches_flush? This is what I > >>>> have here for next: > >>>> > >>> I'm using the next branch too. > >>> > >>>> 0000ffffc0203efc <arm_dcaches_flush>: > >>>> ffffc0203efc: d53b0024 mrs x4, ctr_el0 > >>>> ffffc0203f00: d3504c84 ubfx x4, x4, #16, #4 > >>>> ... > >>>> > >>>> 0000ffffc0204800 <arch_entry>: > >>>> ffffc0204800: aa0003f0 mov x16, x0 > >>>> ffffc0204804: aa1e03f1 mov x17, x30 > >>>> ... > >>>> ffffc0204844: d2800042 mov x2, #0x2 > >>>> // #2 > >>>> ffffc0204848: 97fffdad bl ffffc0203efc > >>>> <arm_dcaches_flush> > >>>> > >>>> You could check if there is such a relative call in your case as well. > >>> yes it does exist, below is the snippet: > >>> > >>> 0000ffffc0204000 <arch_entry>: > >>> ffffc0204000: aa0003f0 mov x16, x0 > >>> ffffc0204004: aa1e03f1 mov x17, x30 > >>> ffffc0204008: 10fdffc0 adr x0, ffffc0200000 > >>> <hypervisor_header> > >>> ffffc020400c: f9402412 ldr x18, [x0, #72] > >>> ffffc0204010: 5800fd0f ldr x15, ffffc0205fb0 > >>> <sdei_handler+0x2c> > >>> ffffc0204014: 900000e1 adrp x1, ffffc0220000 <__page_pool> > >>> ffffc0204018: 79406002 ldrh w2, [x0, #48] > >>> ffffc020401c: d2880003 mov x3, #0x4000 > >>> // #16384 > >>> ffffc0204020: 9b030441 madd x1, x2, x3, x1 > >>> ffffc0204024: f842c02e ldur x14, [x1, #44] > >>> ffffc0204028: 5800fc8d ldr x13, ffffc0205fb8 > >>> <sdei_handler+0x34> > >>> ffffc020402c: f840c02c ldur x12, [x1, #12] > >>> ffffc0204030: cb0d018b sub x11, x12, x13 > >>> ffffc0204034: 924051c1 and x1, x14, #0x1fffff > >>> ffffc0204038: 8b0101ef add x15, x15, x1 > >>> ffffc020403c: f9001c0f str x15, [x0, #56] > >>> ffffc0204040: f9400401 ldr x1, [x0, #8] > >>> ffffc0204044: d2800042 mov x2, #0x2 > >>> // #2 > >>> ffffc0204048: 97ffff6c bl ffffc0203df8 <arm_dcaches_flush> > >>> ffffc020404c: 5800fba1 ldr x1, ffffc0205fc0 > >>> <sdei_handler+0x3c> > >>> ffffc0204050: 8b0b0021 add x1, x1, x11 > >>> ffffc0204054: d2800000 mov x0, #0x0 > >>> // #0 > >>> ffffc0204058: f100025f cmp x18, #0x0 > >>> ffffc020405c: 54000041 b.ne ffffc0204064 > >>> <arch_entry+0x64> // b.any > >>> ffffc0204060: d2800020 mov x0, #0x1 > >>> // #1 > >>> ffffc0204064: d4000002 hvc #0x0 > >>> ffffc0204068: d4000002 hvc #0x0 > >>> ffffc020406c: 14000000 b ffffc020406c <arch_entry+0x6c> > >>> > >>>> Then you could check, before jumping into arch_entry from the > >>>> hypervisor, that the opcode at the actual arm_dcaches_flush address is > >>>> as expected. But maybe already the building injects an issue here. > >>>> > >>> Any pointers on how I could do that? > >>> > >> > >> arm_dcaches_flush is loaded at arch_entry (header->entry + > >> hypervisor_mem) - 0x208. Read the u32 at that address and check if it is > >> what is in your hypervisor.o (likely also d53b0024). > >> > >> If that is the case, not the jump but that "mrs x4, ctr_el0" may > >> actually be the problem. Check out hypervisor/arch/arm64/caches.S and > >> see if that code, specifically dcache_line_size, causes trouble outside > >> of Jailhouse as well, maybe as inline assembly in the driver module. > >> > > > > With the below ret added, I get "JAILHOUSE_ENABLE: Success" > > > > diff --git a/hypervisor/arch/arm64/entry.S b/hypervisor/arch/arm64/entry.S > > index a9cabf7f..4e98b292 100644 > > --- a/hypervisor/arch/arm64/entry.S > > +++ b/hypervisor/arch/arm64/entry.S > > @@ -96,6 +96,7 @@ arch_entry: > > */ > > ldr x1, [x0, #HEADER_CORE_SIZE] > > mov x2, DCACHE_CLEAN_AND_INVALIDATE_ASM > > + ret > > bl arm_dcaches_flush > > > > /* install bootstrap_vectors */ > > > > Now when I undo the above and do the below, Im seeing a panic: > > > > diff --git a/hypervisor/arch/arm64/caches.S b/hypervisor/arch/arm64/caches.S > > index 39dad4af..ce29b8e8 100644 > > --- a/hypervisor/arch/arm64/caches.S > > +++ b/hypervisor/arch/arm64/caches.S > > @@ -40,6 +40,7 @@ > > */ > > .global arm_dcaches_flush > > arm_dcaches_flush: > > + ret > > dcache_line_size x3, x4 > > add x1, x0, x1 > > sub x4, x3, #1 > > > > Issue is seen even without dcache_line_size being called. Does that > > mean we are not landing in arm_dcaches_flush? > > > > Wait! This last "ret" will make you go back to arch_entry only, not to > the caller of arch_entry. Furthermore, x30/lr is after "bl > arm_dcaches_flush" no longer pointing to the caller of arch_entry. That > value is saved into x17. > > Could it be that arm_dcaches_flush was a red herring? Maybe we are > actually crashing on hvc in arch_entry, and that because of Linux not > starting in HVC mode, thus no hypervisor take-over stub being installed. > > Could you share the boot log of the kernel? > Attached is the complete log.
Cheers, Prabhakar -- You received this message because you are subscribed to the Google Groups "Jailhouse" group. To unsubscribe from this group and stop receiving emails from it, send an email to [email protected]. To view this discussion on the web visit https://groups.google.com/d/msgid/jailhouse-dev/CA%2BV-a8s9Ta0fPD6V%3DyGYDhvKngiMrCghi07Y_4XTbDPk9HeHfw%40mail.gmail.com.
NOTICE: BL2: v2.6(release):9cbfb52b4 NOTICE: BL2: Built : 11:44:55, May 5 2022 NOTICE: BL2: Booting BL31 NOTICE: BL31: v2.6(release):9cbfb52b4 NOTICE: BL31: Built : 11:44:55, May 5 2022 U-Boot 2021.10-g90edad6e00 (May 05 2022 - 11:45:00 +0100) CPU: Renesas Electronics K rev 16.15 Model: smarc-rzv2l DRAM: 1.9 GiB MMC: sd@11c00000: 0, sd@11c10000: 1 Loading Environment from MMC... OK In: serial@1004b800 Out: serial@1004b800 Err: serial@1004b800 Net: Error: ethernet@11c20000 address not set. No ethernet found. Hit any key to stop autoboot: 0 9326860 bytes read in 581 ms (15.3 MiB/s) 23907 bytes read in 3 ms (7.6 MiB/s) Uncompressed size: 25408000 = 0x183B200 Moving Image from 0x48080000 to 0x48200000, end=49ac0000 ## Flattened Device Tree blob at 48000000 Booting using the fdt blob at 0x48000000 Loading Device Tree to 0000000057ff7000, end 0000000057fffd62 ... OK Starting kernel ... [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x412fd050] [ 0.000000] Linux version 5.10.112-cip6+ (prasmi@prasmi) (aarch64-linux-gnu-gcc (Linaro GCC 7.5-2019.12) 7.5.0, GNU ld (Linaro_Binutils-2012 [ 0.000000] Machine model: Renesas SMARC EVK based on r9a07g054l2 [ 0.000000] efi: UEFI not found. [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbfc9eb00-0xbfca0fff] [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000048000000-0x00000000bfffffff] [ 0.000000] DMA32 empty [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000048000000-0x00000000a7efffff] [ 0.000000] node 0: [mem 0x00000000a7f00000-0x00000000b7efffff] [ 0.000000] node 0: [mem 0x00000000b7f00000-0x00000000bfffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x00000000bfffffff] [ 0.000000] cma: Reserved 16 MiB at 0x00000000bc000000 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: MIGRATE_INFO_TYPE not supported. [ 0.000000] psci: SMC Calling Convention v1.2 [ 0.000000] percpu: Embedded 22 pages/cpu s50456 r8192 d31464 u90112 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: detected: GIC system register CPU interface [ 0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 483840 [ 0.000000] Policy zone: DMA [ 0.000000] Kernel command line: root=/dev/sda1 rw rootwait rootfstype=ext4 ip=off [ 0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) [ 0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 1622696K/1966080K available (12288K kernel code, 2156K rwdata, 5708K rodata, 4544K init, 483K bss, 327000K reserved, 16) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv3: 480 SPIs implemented [ 0.000000] GICv3: 0 Extended SPIs implemented [ 0.000000] GICv3: Distributor has no Range Selector support [ 0.000000] GICv3: 16 PPIs implemented [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000011940000 [ 0.000000] random: get_random_bytes called from start_kernel+0x31c/0x4e8 with crng_init=0 [ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000005] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [ 0.000173] Console: colour dummy device 80x25 [ 0.000558] printk: console [tty0] enabled [ 0.000654] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) [ 0.000679] pid_max: default: 32768 minimum: 301 [ 0.000763] LSM: Security Framework initializing [ 0.000836] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.000860] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.002513] rcu: Hierarchical SRCU implementation. [ 0.003305] Detected Renesas RZ/V2L r9a07g054 [ 0.003777] EFI services will not be available. [ 0.004113] smp: Bringing up secondary CPUs ... [ 0.004617] Detected VIPT I-cache on CPU1 [ 0.004660] GICv3: CPU1: found redistributor 100 region 0:0x0000000011960000 [ 0.004720] CPU1: Booted secondary processor 0x0000000100 [0x412fd050] [ 0.004849] smp: Brought up 1 node, 2 CPUs [ 0.004897] SMP: Total of 2 processors activated. [ 0.004910] CPU features: detected: Privileged Access Never [ 0.004921] CPU features: detected: LSE atomic instructions [ 0.004931] CPU features: detected: User Access Override [ 0.004943] CPU features: detected: 32-bit EL0 Support [ 0.004954] CPU features: detected: Common not Private translations [ 0.004965] CPU features: detected: RAS Extension Support [ 0.004976] CPU features: detected: Data cache clean to the PoU not required for I/D coherence [ 0.004991] CPU features: detected: CRC32 instructions [ 0.005000] CPU features: detected: Speculative Store Bypassing Safe (SSBS) [ 0.020478] CPU: All CPU(s) started at EL1 [ 0.020563] alternatives: patching kernel code [ 0.022866] devtmpfs: initialized [ 0.027338] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.027394] futex hash table entries: 512 (order: 3, 32768 bytes, linear) [ 0.028058] pinctrl core: initialized pinctrl subsystem [ 0.029261] DMI not present or invalid. [ 0.029874] NET: Registered protocol family 16 [ 0.031482] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations [ 0.031648] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.031815] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.031925] audit: initializing netlink subsys (disabled) [ 0.032218] audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 [ 0.033184] thermal_sys: Registered thermal governor 'step_wise' [ 0.033324] cpuidle: using governor menu [ 0.033523] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.033610] ASID allocator initialised with 65536 entries [ 0.034502] Serial: AMBA PL011 UART driver [ 0.059912] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.059949] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages [ 0.059962] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.059975] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages [ 0.061210] cryptd: max_cpu_qlen set to 1000 [ 0.063535] ACPI: Interpreter disabled. [ 0.065844] iommu: Default domain type: Translated [ 0.066069] vgaarb: loaded [ 0.066402] SCSI subsystem initialized [ 0.066907] usbcore: registered new interface driver usbfs [ 0.066963] usbcore: registered new interface driver hub [ 0.067008] usbcore: registered new device driver usb [ 0.067684] pps_core: LinuxPPS API ver. 1 registered [ 0.067698] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]> [ 0.067729] PTP clock support registered [ 0.069220] clocksource: Switched to clocksource arch_sys_counter [ 0.069471] VFS: Disk quotas dquot_6.6.0 [ 0.069538] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.069733] pnp: PnP ACPI: disabled [ 0.075872] NET: Registered protocol family 2 [ 0.076106] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) [ 0.077353] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) [ 0.077469] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.077597] TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear) [ 0.077866] TCP: Hash tables configured (established 16384 bind 16384) [ 0.078091] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 0.078148] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) [ 0.078343] NET: Registered protocol family 1 [ 0.078973] RPC: Registered named UNIX socket transport module. [ 0.079005] RPC: Registered udp transport module. [ 0.079015] RPC: Registered tcp transport module. [ 0.079026] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.079045] PCI: CLS 0 bytes, default 64 [ 0.081460] Initialise system trusted keyrings [ 0.081749] workingset: timestamp_bits=42 max_order=19 bucket_order=0 [ 0.087764] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.088589] NFS: Registering the id_resolver key type [ 0.088645] Key type id_resolver registered [ 0.088656] Key type id_legacy registered [ 0.088750] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 0.088766] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 0.089005] 9p: Installing v9fs 9p2000 file system support [ 0.125255] Key type asymmetric registered [ 0.125292] Asymmetric key parser 'x509' registered [ 0.125360] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 0.125380] io scheduler mq-deadline registered [ 0.125391] io scheduler kyber registered [ 0.146394] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.148515] SuperH (H)SCI(F) driver initialized [ 0.149041] msm_serial: driver initialized [ 0.158498] loop: module loaded [ 0.163316] tun: Universal TUN/TAP device driver, 1.6 [ 0.164523] e1000e: Intel(R) PRO/1000 Network Driver [ 0.164551] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 0.164628] igb: Intel(R) Gigabit Ethernet Network Driver [ 0.164644] igb: Copyright (c) 2007-2014 Intel Corporation. [ 0.164691] igbvf: Intel(R) Gigabit Virtual Function Network Driver [ 0.164705] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 0.165051] sky2: driver version 1.30 [ 0.166836] VFIO - User Level meta-driver version: 0.3 [ 0.169127] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.169161] ehci-pci: EHCI PCI platform driver [ 0.169293] ehci-platform: EHCI generic platform driver [ 0.169974] ehci-orion: EHCI orion driver [ 0.170177] ehci-exynos: EHCI Exynos driver [ 0.170347] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.170398] ohci-pci: OHCI PCI platform driver [ 0.170465] ohci-platform: OHCI generic platform driver [ 0.170978] ohci-exynos: OHCI Exynos driver [ 0.171739] usbcore: registered new interface driver usb-storage [ 0.174745] i2c /dev entries driver [ 0.178596] sdhci: Secure Digital Host Controller Interface driver [ 0.178625] sdhci: Copyright(c) Pierre Ossman [ 0.179099] Synopsys Designware Multimedia Card Interface Driver [ 0.180252] sdhci-pltfm: SDHCI platform and OF driver helper [ 0.182017] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... [ 0.182702] usbcore: registered new interface driver usbhid [ 0.182722] usbhid: USB HID core driver [ 0.185337] NET: Registered protocol family 10 [ 0.186385] Segment Routing with IPv6 [ 0.186467] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 0.186980] NET: Registered protocol family 17 [ 0.187144] 9pnet: Installing 9P2000 support [ 0.187219] Key type dns_resolver registered [ 0.187661] registered taskstats version 1 [ 0.187682] Loading compiled-in X.509 certificates [ 0.195349] gpio-153 (gpio_sd0_pwr_en): hogged as output/high [ 0.195400] gpio-449 (sd0_dev_sel): hogged as output/high [ 0.195430] gpio-458 (can0_stb): hogged as output/low [ 0.195453] gpio-459 (can1_stb): hogged as output/low [ 0.195479] gpio-434 (sd1_pwr_en): hogged as output/high [ 0.196246] pinctrl-rzg2l 11030000.pinctrl: pinctrl-rzg2l support registered [ 0.201165] 1004b800.serial: ttySC0 at MMIO 0x1004b800 (irq = 21, base_baud = 0) is a scif [ 1.249358] printk: console [ttySC0] enabled [ 1.255395] 1004c000.serial: ttySC1 at MMIO 0x1004c000 (irq = 26, base_baud = 0) is a scif [ 1.265704] renesas_spi 1004b000.spi: dma_request_slave_channel_compat failed [ 1.272894] renesas_spi 1004b000.spi: DMA not available, using PIO [ 1.279458] renesas_spi 1004b000.spi: probed [ 1.285424] ravb 11c20000.ethernet: no valid MAC address supplied, using a random one [ 1.295833] ravb 11c20000.ethernet eth0: Base address at 0x11c20000, 36:aa:25:6f:68:2b, IRQ 84. [ 1.306198] ravb 11c30000.ethernet: no valid MAC address supplied, using a random one [ 1.316398] ravb 11c30000.ethernet eth1: Base address at 0x11c30000, be:fd:29:bd:ad:37, IRQ 87. [ 1.353257] ehci-platform 11c50100.usb: EHCI Host Controller [ 1.358972] ehci-platform 11c50100.usb: new USB bus registered, assigned bus number 1 [ 1.366924] ehci-platform 11c50100.usb: irq 92, io mem 0x11c50100 [ 1.385219] ehci-platform 11c50100.usb: USB 2.0 started, EHCI 1.10 [ 1.392062] hub 1-0:1.0: USB hub found [ 1.395848] hub 1-0:1.0: 1 port detected [ 1.401545] ehci-platform 11c70100.usb: EHCI Host Controller [ 1.407247] ehci-platform 11c70100.usb: new USB bus registered, assigned bus number 2 [ 1.415147] ehci-platform 11c70100.usb: irq 93, io mem 0x11c70100 [ 1.437232] ehci-platform 11c70100.usb: USB 2.0 started, EHCI 1.10 [ 1.444029] hub 2-0:1.0: USB hub found [ 1.447823] hub 2-0:1.0: 1 port detected [ 1.453458] ohci-platform 11c50000.usb: Generic Platform OHCI controller [ 1.460191] ohci-platform 11c50000.usb: new USB bus registered, assigned bus number 3 [ 1.468167] ohci-platform 11c50000.usb: irq 90, io mem 0x11c50000 [ 1.563866] hub 3-0:1.0: USB hub found [ 1.567659] hub 3-0:1.0: 1 port detected [ 1.573289] ohci-platform 11c70000.usb: Generic Platform OHCI controller [ 1.580018] ohci-platform 11c70000.usb: new USB bus registered, assigned bus number 4 [ 1.587977] ohci-platform 11c70000.usb: irq 91, io mem 0x11c70000 [ 1.683869] hub 4-0:1.0: USB hub found [ 1.687661] hub 4-0:1.0: 1 port detected [ 1.694064] i2c-riic 10058000.i2c: registered with 100000Hz bus speed [ 1.702762] i2c-riic 10058400.i2c: registered with 100000Hz bus speed [ 1.711481] i2c-riic 10058c00.i2c: registered with 400000Hz bus speed [ 1.770772] renesas_sdhi_internal_dmac 11c00000.mmc: mmc0 base at 0x0000000011c00000, max clock rate 133 MHz [ 1.773337] renesas_sdhi_internal_dmac 11c10000.mmc: mmc1 base at 0x0000000011c10000, max clock rate 133 MHz [ 1.790987] Waiting for root device /dev/sda1... [ 1.884082] mmc0: new HS200 MMC card at address 0001 [ 1.889757] mmcblk0: mmc0:0001 S0J58X 59.3 GiB [ 1.894503] mmcblk0boot0: mmc0:0001 S0J58X partition 1 31.5 MiB [ 1.900567] mmcblk0boot1: mmc0:0001 S0J58X partition 2 31.5 MiB [ 1.906687] mmcblk0rpmb: mmc0:0001 S0J58X partition 3 4.00 MiB, chardev (241:0) [ 1.915131] mmcblk0: p1 [ 2.158984] mmc1: new ultra high speed SDR104 SDHC card at address aaaa [ 2.166279] mmcblk1: mmc1:aaaa SC16G 14.8 GiB [ 2.176129] mmcblk1: p1 p2 [ 2.237238] usb 2-1: new high-speed USB device number 2 using ehci-platform [ 2.399132] usb-storage 2-1:1.0: USB Mass Storage device detected [ 2.405771] scsi host0: usb-storage 2-1:1.0 [ 3.422391] scsi 0:0:0:0: Direct-Access General USB Flash Disk 1.00 PQ: 0 ANSI: 2 [ 3.431650] sd 0:0:0:0: [sda] 7831552 512-byte logical blocks: (4.01 GB/3.73 GiB) [ 3.439842] sd 0:0:0:0: [sda] Write Protect is off [ 3.445223] sd 0:0:0:0: [sda] No Caching mode page found [ 3.450541] sd 0:0:0:0: [sda] Assuming drive cache: write through [ 3.460396] sda: sda1 sda2 [ 3.466472] sd 0:0:0:0: [sda] Attached SCSI removable disk [ 3.490928] random: fast init done [ 4.601040] EXT4-fs (sda1): recovery complete [ 4.607725] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) [ 4.615451] VFS: Mounted root (ext4 filesystem) on device 8:1. [ 4.625962] devtmpfs: mounted [ 4.633301] Freeing unused kernel memory: 4544K [ 4.638013] Run /sbin/init as init process [ 5.005808] systemd[1]: System time before build time, advancing clock. [ 5.054314] systemd[1]: systemd 244.5+ running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR -SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -) [ 5.076408] systemd[1]: Detected architecture arm64. Welcome to Poky (Yocto Project Reference Distro) 3.1.5 (dunfell)! [ 5.118479] systemd[1]: Set hostname to <smarc-rzg2l>. [ 5.581460] random: systemd: uninitialized urandom read (16 bytes read) [ 5.588384] systemd[1]: system-getty.slice: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. [ 5.600722] systemd[1]: (This warning is only shown for the first unit using IP firewalling.) [ 5.612528] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 5.633437] random: systemd: uninitialized urandom read (16 bytes read) [ 5.641593] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 5.665424] random: systemd: uninitialized urandom read (16 bytes read) [ 5.673402] systemd[1]: Created slice User and Session Slice. [ OK ] Created slice User and Session Slice. [ 5.693831] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password …ts to Console Directory Watch. [ 5.717729] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password R…uests to Wall Directory Watch. [ 5.741584] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 5.761496] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 5.781467] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 5.801471] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 5.835065] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ 5.857530] systemd[1]: Reached target RPC Port Mapper. [ OK ] Reached target RPC Port Mapper. [ 5.879976] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 5.901867] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ 5.926323] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 5.946017] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 5.970106] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 5.990264] systemd[1]: Listening on Network Service Netlink Socket. [ OK ] Listening on Network Service Netlink Socket. [ 6.014135] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 6.033885] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 6.058434] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 6.082062] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 6.110110] systemd[1]: Mounting Kernel Debug File System... Mounting Kernel Debug File System... [ 6.135494] systemd[1]: Mounting Temporary Directory (/tmp)... Mounting Temporary Directory (/tmp)... [ 6.158493] systemd[1]: Condition check resulted in Create list of static device nodes for the current kernel being skipped. [ 6.174424] systemd[1]: Starting Start psplash boot splash screen... Starting Start psplash boot splash screen... [ 6.214803] systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.244175] systemd[1]: Starting RPC Bind... Starting RPC Bind... [ 6.261664] systemd[1]: Condition check resulted in File System Check on Root Device being skipped. [ 6.277168] systemd[1]: Starting Journal Service... Starting Journal Service... [ 6.326467] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 6.358455] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 6.406490] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 6.447183] EXT4-fs (sda1): re-mounted. Opts: (null) [ 6.473041] systemd[1]: Started RPC Bind. [ OK ] Started RPC Bind. [ 6.516469] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Temporary Directory (/tmp). [FAILED] Failed to start Start psplash boot splash screen. See 'systemctl status psplash-start.service' for details. [DEPEND] Dependency failed for Star…progress communication helper. [FAILED] Failed to start Load Kernel Modules. See 'systemctl status systemd-modules-load.service' for details. [ OK ] Started Remount Root and Kernel File Systems. Mounting Kernel Configuration File System... Starting Flush Journal to Persistent Storage... Starting Apply Kernel Variables... [ 6.796829] systemd-journald[139]: Received client request to flush runtime journal. Starting Create Static Device Nodes in /dev... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /var/volatile... Starting udev Kernel Device Manager... [ OK ] Mounted /var/volatile. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started udev Kernel Device Manager. [ OK ] Started Create Volatile Files and Directories. Starting Network Service... Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started Network Service. Starting Network Name Resolution... [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target System Time Set. [ OK ] Reached target System Time Synchronized. [ OK ] Reached target Timers. [ OK ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket. [ OK ] Listening on D-Bus System Message Bus Socket. Starting sshd.socket. [ OK ] Listening on sshd.socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Kernel Logging Service. [ OK ] Started System Logging Service. [ OK ] Started D-Bus System Message Bus. Starting rng-tools.service... Starting Login Service... [ OK ] Started Network Name Resolution. [ OK ] Reached target Network. [ 8.029319] random: crng init done [ 8.032747] random: 7 urandom warning(s) missed due to ratelimiting [ OK ] Reached target Host and Network Name Lookups. Starting Avahi mDNS/DNS-SD Stack... Starting Permit User Sessions... [ OK ] Started Load/Save Random Seed. [ OK ] Started rng-tools.service. [ OK ] Started Permit User Sessions. [ OK ] Started Avahi mDNS/DNS-SD Stack. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttySC0. [ OK ] Reached target Login Prompts. Starting weston.service... [ OK ] Started Login Service. [ OK ] Started weston.service. [ OK ] Reached target Multi-User System. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. [ 10.850619] Microchip KSZ9131 Gigabit PHY 11c30000.ethernet-ffffffff:07: attached PHY driver [Microchip KSZ9131 Gigabit PHY] (mii_bus:phy_a) [ 10.936505] Microchip KSZ9131 Gigabit PHY 11c20000.ethernet-ffffffff:07: attached PHY driver [Microchip KSZ9131 Gigabit PHY] (mii_bus:phy_a) Poky (Yocto Project Reference Distro) 3.1.5 smarc-rzg2l ttySC0 BSP: RZG2L/RZG2L-SMARC-EVK/1.1 LSI: RZG2L Version: 1.1 smarc-rzg2l login: root [ 23.124746] audit: type=1006 audit(1600598656.116:2): pid=203 uid=0 old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=1 res=1 root@smarc-rzg2l:~# ;143R -sh: syntax error near unexpected token `;' root@smarc-rzg2l:~# root@smarc-rzg2l:~# ./cpy.sh fsck from util-linux 2.35.1 dosfsck 2.11, 12 Mar 2005, FAT32, LFN /dev/mmcblk1p2: 11 files, 20774/38357 clusters [ 28.814665] FAT-fs (mmcblk1p2): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. root@smarc-rzg2l:~# root@smarc-rzg2l:~# root@smarc-rzg2l:~# ./hyp.sh [ 32.024548] jailhouse: loading out-of-tree module taints kernel. Reading configuration set: Root cell: Renesas RZ/V2L SMARC (renesas-r9a07g054l2.cell) Overlapping memory regions inside cell: None Overlapping memory regions with hypervisor: None Missing resource interceptions for architecture arm64: None [ 32.844987] obcode @arm_dcaches_flush: d53b0024 [ 32.844993] obcode @arm_dcaches_flush: d53b0024 [ 32.845022] ------------[ cut here ]------------ [ 32.849537] ------------[ cut here ]------------ [ 32.854035] kernel BUG at arch/arm64/kernel/traps.c:407! [ 32.858627] kernel BUG at arch/arm64/kernel/traps.c:407! [ 32.863218] Internal error: Oops - BUG: 0 [#1] PREEMPT SMP [ 32.879240] Modules linked in: jailhouse(O) [ 32.883417] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G O 5.10.112-cip6+ #13 [ 32.891470] Hardware name: Renesas SMARC EVK based on r9a07g054l2 (DT) [ 32.897968] pstate: 00400085 (nzcv daIf +PAN -UAO -TCO BTYPE=--) [ 32.903958] pc : do_undefinstr+0x26c/0x320 [ 32.908036] lr : do_undefinstr+0x1cc/0x320 [ 32.912115] sp : ffff8000118cbcf0 [ 32.915413] x29: ffff8000118cbcf0 x28: ffff800011632f80 [ 32.920705] x27: ffff800011632f80 x26: ffff8000118cc000 [ 32.925995] x25: ffff8000118c8000 x24: ffff80001162a344 [ 32.931286] x23: 0000000020400085 x22: ffff800013004864 [ 32.936577] x21: ffff8000118cbed0 x20: ffff8000118cbd80 [ 32.941867] x19: ffff800011107000 x18: 0000000000000001 [ 32.947158] x17: ffff800008c11828 x16: 0000000000000000 [ 32.952449] x15: ffff800013004864 x14: 000000001004b800 [ 32.957739] x13: 0000ffffc0200000 x12: 00000000b6f00000 [ 32.963030] x11: ffff0000f6d00000 x10: ffff8000118cbed0 [ 32.968321] x9 : ffff8000118cbed0 x8 : 3062333564203a68 [ 32.973612] x7 : 0000000000000000 x6 : ffff8000118cbd48 [ 32.978902] x5 : 00000000d5300000 x4 : ffff800011635410 [ 32.984193] x3 : 00000000d4000000 x2 : 0000000000000000 [ 32.989483] x1 : ffff800011632f80 x0 : 0000000020400085 [ 32.994775] Call trace: [ 32.997212] do_undefinstr+0x26c/0x320 [ 33.000949] el1_undef+0x30/0x50 [ 33.004164] el1_sync_handler+0xc4/0xe0 [ 33.007983] el1_sync+0x84/0x140 [ 33.011197] 0xffff800013004864 [ 33.014328] flush_smp_call_function_queue+0xf8/0x268 [ 33.019359] generic_smp_call_function_single_interrupt+0x14/0x20 [ 33.025426] ipi_handler+0x8c/0x158 [ 33.028901] handle_percpu_devid_fasteoi_ipi+0x74/0x88 [ 33.034020] generic_handle_irq+0x30/0x48 [ 33.038013] __handle_domain_irq+0x60/0xb8 [ 33.042094] gic_handle_irq+0x58/0x128 [ 33.045826] el1_irq+0xc8/0x180 [ 33.048953] arch_cpu_idle+0x18/0x28 [ 33.052512] default_idle_call+0x24/0x5c [ 33.056419] do_idle+0x1ec/0x288 [ 33.059631] cpu_startup_entry+0x28/0x68 [ 33.063536] rest_init+0xd8/0xe8 [ 33.066753] arch_call_rest_init+0x10/0x1c [ 33.070831] start_kernel+0x4b0/0x4e8 [ 33.074481] Code: f94013b5 17fffff1 a9025bb5 f9001bb7 (d4210000) [ 33.080556] ---[ end trace 7392b981aa062d06 ]--- [ 33.085153] Kernel panic - not syncing: Oops - BUG: Fatal exception in interrupt [ 33.092519] SMP: stopping secondary CPUs [ 34.180205] SMP: failed to stop secondary CPUs 0-1 [ 34.184973] Kernel Offset: disabled [ 34.188445] CPU features: 0x0040026,2a00a238 [ 34.192695] Memory Limit: none [ 34.195737] ---[ end Kernel panic - not syncing: Oops - BUG: Fatal exception in interrupt ]---
