66AK2G12: kernel hangs after kernel command shell "reboot"

Hi Graziano,

I will review the log and get you back in a couple days.

Hi,

do you have any update about that issue?

Do you need more information that I can share you?

Please don't hesitate to ask me.

Regards

Graziano

Hi Graziano,

Sorry for the delay response.

Can you please attach the linux boot log for the k2g-evm? I don't have access to the k2g evm at this moment.

Do you use the rootfs provided in the Processor SDK release? I don't see some messages in your linux boot log which I normally see on evm.

If not, do you use the same rootfs on k2g-evm which can reboot successfully?

Hi Bin,

I am using the rootf provided from Processor SDK release  ver 06_01_00_08 Linux_RT. Before your question the zImage and rootfs used on k2g-evm had little differences from my board because the periphericals and modules are different BUT in order to be sure I have installed the same zImage and rootfs on k2g-evm as used on my board.

Below the boot log of k2g-evm board with the SAME zImage and rootfs:

U-Boot 2019.01 (Apr 08 2020 - 17:12:06 +0200)

CPU: 66AK2Gx-100 SR1.0
Model: Texas Instruments K2G General Purpose EVM
DRAM:
Clear entire DDR3 memory to enable ECC
2 GiB
MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
Loading Environment from FAT... Card did not respond to voltage select!
Net:
Warning: netcp@4000000 using MAC address from ROM
eth0: netcp@4000000
Hit any key to stop autoboot:  0
Card did not respond to voltage select!
Card did not respond to voltage select!
SF: Detected s25fl512s_256k with page size 256 Bytes, erase size 256 KiB, total 64 MiB
device 0 offset 0x240000, size 0x8040
SF: 32832 bytes @ 0x240000 Read: OK
K2_BM_15.07-53-ge1f885a SoC:k2g built:17:19:23, Oct 19 2019

## installed monitor @ 0xc0f7000, freq [24000000], status 202338304
device 0 offset 0x250000, size 0x8354
SF: 33620 bytes @ 0x250000 Read: OK
0 - Name:'pmmc@2900000' type:'internal memory mapped' supports: load start
Load Remote Processor 0 with data@addr=0x82000000 33620 bytes: Success!
device 0 offset 0x280000, size 0x800000
SF: 8388608 bytes @ 0x280000 Read: OK
device 0 offset 0x260000, size 0x8625
SF: 34341 bytes @ 0x260000 Read: OK
device 0 offset 0x200000, size 0x40000
SF: 262144 bytes @ 0x200000 Read: OK
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Ramdisk to 90000000, end 90000000 ... OK
   Loading Device Tree to 8fff5000, end 8ffff3c9 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.19.59-rt24-svn48 (root@ubuntu) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #17 SMP PREEMPT RT Fri May 14 11:47:18 CEST 2021
[    0.000000] CPU: ARMv7 Processor [412fc0f4] revision 4 (ARMv7), cr=30c5387d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] OF: fdt: Machine model: Texas Instruments K2G General Purpose EVM
[    0.000000] bootconsole [earlycon0] enabled
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Switching physical address space to 0x800000000
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000081f800000, size 8 MiB
[    0.000000] OF: reserved mem: initialized node dsp-common-memory@81f800000, compatible id shared-dma-pool
[    0.000000] cma: Reserved 48 MiB at 0x000000087cc00000
[    0.000000] random: get_random_bytes called from start_kernel+0xb0/0x488 with crng_init=0
[    0.000000] percpu: Embedded 16 pages/cpu s36064 r8192 d21280 u65536
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 491840
[    0.000000] Kernel command line: console=ttyS0,115200n8 earlyprintk root=/dev/mtdblock7 rw rootfstype=jffs2 rootwait
[    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 1885228K/1974272K available (8192K kernel code, 261K rwdata, 1972K rodata, 2048K init, 268K bss, 31700K reserved, 57344K cma-reserved, 1261568K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (10208 kB)
[    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (2048 kB)
[    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 262 kB)
[    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 269 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1.
[    0.000000] rcu:     RCU priority boosting: priority 1 delay 500 ms.
[    0.000000]  No expedited grace period (rcu_normal_after_boot).
[    0.000000]  Tasks RCU enabled.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    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.000011] Switching to timer-based delay loop, resolution 41ns
[    0.000126] keystone_timer_init: failed to get clock
[    0.000138] Failed to initialize '/soc@0/timer@2210000': -517
[    0.000375] Console: colour dummy device 80x30
[    0.265645] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000)
[    0.265653] pid_max: default: 32768 minimum: 301
[    0.265790] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.265799] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.266493] CPU: Testing write buffer coherency: ok
[    0.266519] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.266780] /cpus/cpu@0 missing clock-frequency property
[    0.315222] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.380291] Setting up static identity map for 0x80200000 - 0x80200138
[    0.400257] rcu: Hierarchical SRCU implementation.
[    0.460766] EFI services will not be available.
[    0.480390] smp: Bringing up secondary CPUs ...
[    0.484940] smp: Brought up 1 node, 1 CPU
[    0.488964] SMP: Total of 1 processors activated (48.00 BogoMIPS).
[    0.495202] CPU: All CPU(s) started in HYP mode.
[    0.499834] CPU: Virtualization extensions available.
[    0.505663] devtmpfs: initialized
[    0.520583] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.528678] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.538556] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.545810] pinctrl core: initialized pinctrl subsystem
[    0.551985] DMI not present or invalid.
[    0.556342] NET: Registered protocol family 16
[    0.563749] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.572627] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.580695] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.594819] davinci_gpio 2603000.gpio: Error -517 getting gpio clock
[    0.602819] davinci_gpio 260a000.gpio: Error -517 getting gpio clock
[    0.666643] SCSI subsystem initialized
[    0.671140] pps_core: LinuxPPS API ver. 1 registered
[    0.676125] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.685324] PTP clock support registered
[    0.689277] EDAC MC: Ver: 3.0.0
[    0.712282] clocksource: Switched to clocksource arch_sys_counter
[    0.731545] NET: Registered protocol family 2
[    0.736831] tcp_listen_portaddr_hash hash table entries: 512 (order: 2, 16384 bytes)
[    0.744670] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.751826] TCP bind hash table entries: 8192 (order: 5, 229376 bytes)
[    0.758708] TCP: Hash tables configured (established 8192 bind 8192)
[    0.765304] UDP hash table entries: 512 (order: 3, 32768 bytes)
[    0.771296] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes)
[    0.778099] NET: Registered protocol family 1
[    0.842507] RPC: Registered named UNIX socket transport module.
[    0.848453] RPC: Registered udp transport module.
[    0.853227] RPC: Registered tcp transport module.
[    0.857948] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.865192] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.874545] platform alarmtimer: set dma_pfn_offset00780000
[    0.881133] Initialise system trusted keyrings
[    0.885938] workingset: timestamp_bits=14 max_order=19 bucket_order=5
[    0.901723] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.922762] NFS: Registering the id_resolver key type
[    0.927863] Key type id_resolver registered
[    0.932062] Key type id_legacy registered
[    0.936159] ntfs: driver 2.1.32 [Flags: R/O].
[    0.940831] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[    0.951425] Key type asymmetric registered
[    0.955599] Asymmetric key parser 'x509' registered
[    0.960668] bounce: pool size: 64 pages
[    0.964617] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.972046] io scheduler noop registered
[    0.976057] io scheduler deadline registered
[    0.980641] io scheduler cfq registered (default)
[    0.985403] io scheduler mq-deadline registered
[    0.989953] io scheduler kyber registered
[    0.994811] keystone_irq 26202a0.keystone_irq: irqchip registered, nr_irqs 28
[    1.003877] pinctrl-single 2621000.pinmux: please update dts to use #pinctrl-cells = <1>
[    1.012569] pinctrl-single 2621000.pinmux: 260 pins, size 1040
[    1.106563] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    1.113075] platform serial8250: set dma_pfn_offset00780000
[    1.139402] brd: module loaded
[    1.156662] loop: module loaded
[    1.164935] platform Fixed MDIO bus.0: set dma_pfn_offset00780000
[    1.171381] libphy: Fixed MDIO Bus: probed
[    1.178829] i2c /dev entries driver
[    1.188761] NET: Registered protocol family 10
[    1.212888] Segment Routing with IPv6
[    1.216674] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.223517] NET: Registered protocol family 17
[    1.228120] Key type dns_resolver registered
[    1.232592] Registering SWP/SWPB emulation handler
[    1.238276] Loading compiled-in X.509 certificates
[    1.245126] davinci_gpio 2603000.gpio: Error -517 getting gpio clock
[    1.252952] davinci_gpio 260a000.gpio: Error -517 getting gpio clock
[    1.268921] ti-sci 2921c00.pmmc: ABI: 2.4 (firmware rev 0x0011 '0.1.1-50-g129ef (Eagle)')
[    1.293143] random: fast init done
[    1.378321] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.386682] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.395325] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.414891] keystone timer clock @166666666 Hz
[    1.439669] edma 2700000.edma: TI EDMA DMA engine driver
[    1.446039] edma 2728000.edma: memcpy is disabled
[    1.457317] edma 2728000.edma: TI EDMA DMA engine driver
[    1.466760] ti,keystone-serdes 2320000.phy: Direct firmware load for ks2_pcie_serdes.bin failed with error -2
[    1.476829] ti,keystone-serdes 2320000.phy: can't get any serdes init fw
[    1.483567] ti,keystone-serdes 2320000.phy: serdes procider init failed -19
[    1.491586] keystone-navigator-qmss soc@0:qmss@4020000: qmgr start queue 0, number of queues 128
[    1.500631] keystone-navigator-qmss soc@0:qmss@4020000: added qmgr start queue 0, num of queues 128, reg_peek (ptrval), reg_status   (null), reg_config (ptrval), reg_region (ptrval), reg_push (ptrval), reg_pop (ptrval)
[    1.521623] keystone-navigator-dma soc@0:knav_dmas@0: DMA dma_gbe registered 53 logical channels, flows 32, tx chans: 21, rx chans: 32
[    1.535989] console [ttyS0] disabled
[    1.539611] 2530c00.serial: ttyS0 at MMIO 0x2530c00 (irq = 24, base_baud = 12000000) is a TI DA8xx/66AK2x
[    1.549274] console [ttyS0] enabled
[    1.549274] console [ttyS0] enabled
[    1.556247] bootconsole [earlycon0] disabled
[    1.556247] bootconsole [earlycon0] disabled
[    1.567450] 2531400.serial: ttyS2 at MMIO 0x2531400 (irq = 25, base_baud = 12000000) is a TI DA8xx/66AK2x
[    1.579199] cadence-qspi 2940000.qspi: non-uniform erase sector maps are not supported yet.
[    1.587557] cadence-qspi 2940000.qspi: s25fl512s (65536 Kbytes)
[    1.593548] 8 fixed-partitions partitions found on MTD device 2940000.qspi.0
[    1.600583] Creating 8 MTD partitions on "2940000.qspi.0":
[    1.606063] 0x000000000000-0x000000100000 : "MLO"
[    1.612242] 0x000000100000-0x000000200000 : "u-boot.img"
[    1.618920] 0x000000200000-0x000000240000 : "fdt"
[    1.624954] 0x000000240000-0x000000250000 : "skern-k2g.bin"
[    1.630518] mtd: partition "skern-k2g.bin" doesn't end on an erase/write block -- force read-only
[    1.640723] 0x000000250000-0x000000260000 : "ti-sci-firmware"
[    1.646462] mtd: partition "ti-sci-firmware" doesn't start on an erase/write block boundary -- force read-only
[    1.657791] 0x000000260000-0x000000280000 : "k2-fw-initrd.cpio.gz"
[    1.663963] mtd: partition "k2-fw-initrd.cpio.gz" doesn't start on an erase/write block boundary -- force read-only
[    1.675709] 0x000000280000-0x000000a80000 : "kernel"
[    1.682007] 0x000000a80000-0x000004000000 : "file-system"
[    1.689968] spi_davinci 21805400.spi: DMA is not supported (-19)
[    1.696472] spi_davinci 21805400.spi: Controller at 0x(ptrval)
[    1.703487] spi_davinci 21805800.spi: DMA is not supported (-19)
[    1.710572] spi_davinci 21805800.spi: Controller at 0x(ptrval)
[    1.717483] spi_davinci 21805c00.spi: DMA is not supported (-19)
[    1.723977] spi_davinci 21805c00.spi: Controller at 0x(ptrval)
[    1.730866] spi_davinci 21806000.spi: DMA is not supported (-19)
[    1.737345] spi_davinci 21806000.spi: Controller at 0x(ptrval)
[    1.802331] davinci_mdio 4200f00.mdio: davinci mdio revision 1.7, bus freq 2500000
[    1.809890] libphy: 4200f00.mdio: probed
[    1.825134] davinci_mdio 4200f00.mdio: phy[0]: device 4200f00.mdio:00, driver Micrel KSZ9031 Gigabit PHY
[    1.835200] netcp-1.0 2620110.netcp: ALE enabled
[    1.840081] netcp-1.0 2620110.netcp: initialized cpsw ale version 1.4
[    1.846515] netcp-1.0 2620110.netcp: ALE Table size 64
[    1.851784] netcp-1.0 2620110.netcp: cpts: overflow check period 500 (jiffies)
[    1.859000] netcp-1.0 2620110.netcp: CPTS: ref_clk_freq:250000000 calc_mult:2147483648 calc_shift:29 error:0 nsec/sec
[    1.870758] netcp-1.0 2620110.netcp: module(netcp-xgbe) not used for device
[    1.878434] hctosys: unable to open rtc device (rtc0)
[    1.904248] ldo1: disabling
[    9.287702] VFS: Mounted root (jffs2 filesystem) on device 31:7.
[    9.294030] devtmpfs: mounted
[    9.300140] Freeing unused kernel memory: 2048K
[    9.304779] Run /sbin/init as init process
[    9.674800] random: crng init done
INIT: version 2.88 booting
Framebuffer /dev/fb0 not detected
Boot splashscreen disabled
[   11.657652] Key type cifs.idmap registered
[   12.214763] NET: Registered protocol family 38
[   12.404473] usbcore: registered new interface driver usbfs
[   12.410046] usbcore: registered new interface driver hub
[   12.415474] usbcore: registered new device driver usb
[   12.525019] registered dspmem misc device
[   12.597739] at24 0-0050: 131072 byte 24c1024 EEPROM, writable, 1 bytes/write
[   12.718469] remoteproc remoteproc0: 20ab4000.pru is available
[   12.724817] pru-rproc 20ab4000.pru: PRU rproc node pru@20ab4000 probed successfully
[   12.732920] remoteproc remoteproc1: 20ab8000.pru is available
[   12.738808] pru-rproc 20ab8000.pru: PRU rproc node pru@20ab8000 probed successfully
[   12.746850] remoteproc remoteproc2: 20af4000.pru is available
[   12.752737] pru-rproc 20af4000.pru: PRU rproc node pru@20af4000 probed successfully
[   12.760743] remoteproc remoteproc3: 20af8000.pru is available
[   12.766623] pru-rproc 20af8000.pru: PRU rproc node pru@20af8000 probed successfully
[   12.934972] CAN device driver interface
[   13.021521] c_can_platform 260b200.can: c_can_platform device registered (regs=fda0746d, irq=26)
[   13.032693] c_can_platform 260b400.can: c_can_platform device registered (regs=7e2c6f2f, irq=27)
[   13.103421] usb_phy_generic usbphy:usb-phy@0: usbphy:usb-phy@0 supply vcc not found, using dummy regulator
[   13.113348] usb_phy_generic usbphy:usb-phy@0: Linked as a consumer to regulator.0
[   13.121140] usb_phy_generic usbphy:usb-phy@1: usbphy:usb-phy@1 supply vcc not found, using dummy regulator
[   13.131029] usb_phy_generic usbphy:usb-phy@1: Linked as a consumer to regulator.0
[   13.295264] dwc3 2690000.usb: Failed to get clk 'ref': -2
[   13.301180] platform xhci-hcd.0.auto: set dma_pfn_offset00780000
[   13.309165] dwc3 2590000.usb: Failed to get clk 'ref': -2
[   13.421476] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   13.453713] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   13.529234] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[   13.535123] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
[   13.543344] xhci-hcd xhci-hcd.0.auto: hcc params 0x0298f66d hci version 0x100 quirks 0x0000000002010010
[   13.552936] xhci-hcd xhci-hcd.0.auto: irq 59, io mem 0x02690000
[   13.559607] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[   13.567863] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   13.575108] usb usb1: Product: xHCI Host Controller
[   13.579980] usb usb1: Manufacturer: Linux 4.19.59-rt24-svn48 xhci-hcd
[   13.586411] usb usb1: SerialNumber: xhci-hcd.0.auto
[   13.592144] hub 1-0:1.0: USB hub found
[   13.596082] hub 1-0:1.0: 1 port detected
[   13.600618] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[   13.606119] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
[   13.613775] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed
[   13.620400] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[   13.628677] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[   13.636930] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   13.644141] usb usb2: Product: xHCI Host Controller
[   13.649010] usb usb2: Manufacturer: Linux 4.19.59-rt24-svn48 xhci-hcd
[   13.655502] usb usb2: SerialNumber: xhci-hcd.0.auto
[   13.661178] hub 2-0:1.0: USB hub found
[   13.664966] hub 2-0:1.0: 1 port detected
[   13.717112] usbcore: registered new interface driver cdc_acm
[   13.722890] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[   13.764704] usbcore: registered new interface driver usb-storage
[   13.777290] usbcore: registered new interface driver uas
[   13.844428] usbcore: registered new interface driver cp210x
[   13.850066] usbserial: USB Serial support registered for cp210x
[   13.887438] usbcore: registered new interface driver ftdi_sio
[   13.893350] usbserial: USB Serial support registered for FTDI USB Serial Device
[   13.938438] usbcore: registered new interface driver option
[   13.944211] usbserial: USB Serial support registered for GSM modem (1-port)
[   13.971111] usbcore: registered new interface driver pl2303
[   13.977306] usbserial: USB Serial support registered for pl2303
[   14.023214] usbcore: registered new interface driver usbtest
[   14.439659] zero gadget: Gadget Zero, version: Cinco de Mayo 2008
[   14.445876] zero gadget: zero ready
[   14.470652] udc-core: couldn't find an available UDC - added [g_ether] to list of pending drivers
[   14.502094] gadgetfs: USB Gadget filesystem, version 24 Aug 2004
[   14.527142] file system registered
[   14.549602] udc-core: couldn't find an available UDC - added [g_mass_storage] to list of pending drivers
[   14.577823] udc-core: couldn't find an available UDC - added [g_serial] to list of pending drivers
[   14.606068] udc-core: couldn't find an available UDC - added [printer] to list of pending drivers
[   14.634572] udc-core: couldn't find an available UDC - added [g_cdc] to list of pending drivers
[   14.682583] udc-core: couldn't find an available UDC - added [dbgp] to list of pending drivers
[   14.711202] udc-core: couldn't find an available UDC - added [g_multi] to list of pending drivers
[   14.738928] udc-core: couldn't find an available UDC - added [g_ncm] to list of pending drivers
[   14.766965] udc-core: couldn't find an available UDC - added [g_acm_ms] to list of pending drivers
[   15.160897] davinci-wdt 2250000.wdt: heartbeat 60 sec
[   15.186526] EDAC MC0: Giving out device to module ti-emif-edac controller ti,emif-keystone: DEV 21010000.emif (INTERRUPT)
[   15.365750] usbcore: registered new interface driver usbhid
[   15.371318] usbhid: USB HID core driver
[   15.620119] Driver for 1-wire Dallas network protocol.
[   15.717568] keystone-rproc 10800000.dsp: assigned reserved memory node dsp-common-memory@81f800000
[   15.726784] remoteproc remoteproc4: 10800000.dsp is available
[   15.849059] GACT probability on
[   15.872086] Mirror/redirect action on
[   15.961737] Simple TC action Loaded
[   16.291929] netem: version 1.3
[   16.438106] u32 classifier
[   16.440812]     input device check on
[   16.444575]     Actions configured
[   17.107748] IPVS: Registered protocols ()
[   17.111860] IPVS: Connection hash table configured (size=4096, memory=32Kbytes)
[   17.119739] IPVS: ipvs loaded.
[   17.290903] IPVS: [rr] scheduler registered.
[   17.678440] Initializing XFRM netlink socket
[   17.836219] mip6: Mobile IPv6
[   17.908550] 8021q: 802.1Q VLAN Support v1.8
[   17.956095] NET: Registered protocol family 15
[   18.041051] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[   18.512821] Bridge firewalling registered
[   18.591626] can: controller area network core (rev 20170425 abi 9)
[   18.598569] NET: Registered protocol family 29
[   18.628778] can: raw protocol (rev 20170425)
[   18.660354] can: broadcast manager protocol (rev 20170425 t)
[   18.690659] can: netlink gateway (rev 20170425) max_hops=1
[   18.784919] sctp: Hash tables configured (bind 128/146)
[   18.836410] NET: Registered protocol family 45
Sat Oct 19 19:15:05 UTC 2019
INIT: Entering runlevel: 5
Configuring network interfaces... [   20.307166] netcp-1.0 2620110.netcp eth0: Link is Down
[   20.313970] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   20.319802] 8021q: adding VLAN 0 to HW filter on device eth0
udhcpc: started, v1.29.3
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
udhcpc: no lease, forking to background
ifconfig: SIOCGIFFLAGS: No such device
ip: either "dev" is duplicate, or "type" is garbage
ip: either "dev" is duplicate, or "type" is garbage
done.
Starting Dropbear SSH server: Will output 1024 bit rsa secret key to '/etc/dropbear/dropbear_rsa_host_key'
Generating key, this may take a while...
Public key portion is:
ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAAAglK652/JNBhg6x6VzzZNYEQul3znUPK4xbX9VBy6FyH+dUDYBe5Zjy9da1fXsZ+kQ5Xi9Ck2edSWmKC08EvaFDnGjtFHlXziTPP7H1uZIgINPbFtNDUdnQlMed0x4JTPQ2GMlsvwVVHsA8g3ERUE8OfPFfV2290dXgcz2KI7sGmpIAM= root@k2g-hpc
Fingerprint: md5 5e:da:81:03:9b:6f:5d:f4:ad:85:1a:cb:38:28:2c:4c
dropbear.
Starting syslogd/klogd: done

 _____                    _____           _         _
|  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_
|     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
|__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|
              |___|                    |___|

Arago Project http://arago-project.org k2g-hpc /dev/ttyS0

Arago 2019.07 k2g-hpc /dev/ttyS0

k2g-hpc login: root
Password:
root@k2g-hpc:~# reboot
INIT: Sending processes the TERM signal
root@k2g-hpc:~# Framebuffer /dev/fb0 not detected
Boot splashscreen disabled
Stopping syslogd/klogd: stopped syslogd (pid 1558)
stopped klogd (pid 1561)
done
Deconfiguring network interfaces... ifdown: interface eth1 not configured
ifdown: interface can0 not configured
ifdown: interface can1 not configured
done.
Sending all processes the TERM signal...
Sending all processes the KILL signal...
Unmounting remote filesystems...
Deactivating swap...
Unmounting local filesystems...
Rebooting... [   45.879385] reboot: Restarting system


U-Boot 2019.01 (Apr 08 2020 - 17:12:06 +0200)

CPU: 66AK2Gx-100 SR1.0
Model: Texas Instruments K2G General Purpose EVM
DRAM:
Clear entire DDR3 memory to enable ECC
2 GiB
MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
Loading Environment from FAT... Card did not respond to voltage select!
Net:
Warning: netcp@4000000 using MAC address from ROM
eth0: netcp@4000000
Hit any key to stop autoboot:  0
=>

As you can see in boot log, the reboot command in linux shell works as expexted while the log hangs with my board at 

Rebooting... [  379.250088] reboot: Restarting system

At this point the device tree file could be discriminating between the 2 boards but it is only my supposition.

I hope it can help you in your investigation.

Regards

Graziano

Hi Graziano,

Comparing the logs between the evm and your board, I don't see anything standing out. Can you please apply the following kernel patch, run the new kernel on your board and provide the kernel log for reboot? It adds some debug messages to trace the reboot process.

diff --git a/drivers/firmware/ti_sci.c b/drivers/firmware/ti_sci.c
index 825891f6e6cd..c19d0e5443b8 100644
--- a/drivers/firmware/ti_sci.c
+++ b/drivers/firmware/ti_sci.c
@@ -3503,6 +3503,7 @@ static int tisci_reboot_handler(struct notifier_block *nb, unsigned long mode,
        struct ti_sci_info *info = reboot_to_ti_sci_info(nb);
        const struct ti_sci_handle *handle = &info->handle;
 
+       dev_warn(dev, "DT reboot called here\n");
        ti_sci_cmd_core_reboot(handle);
 
        /* call fail OR pass, we should not be here in the first place */
@@ -3593,6 +3594,7 @@ static int ti_sci_probe(struct platform_device *pdev)
        INIT_LIST_HEAD(&info->node);
        minfo = &info->minfo;
 
+       dev_warn(dev, "DT reboot property: %d\n", reboot);
        /*
         * Pre-allocate messages
         * NEVER allocate more than what we can indicate in hdr.seq
@@ -3667,6 +3669,7 @@ static int ti_sci_probe(struct platform_device *pdev)
                info->nb.priority = 128;
 
                ret = register_restart_handler(&info->nb);
+               dev_warn(dev, "DT reboot registration: %d\n", ret);
                if (ret) {
                        dev_err(dev, "reboot registration fail(%d)\n", ret);
                        return ret;

Hi,

I added dev_warn() in ti_sci_probe() function but I could not in  tisci_reboot_handler() because in that function there is no declaration of dev as struct device and the lernel compilation gives me error.

Below the kernel log with dev_warn() added in ti_sci_probe():

U-Boot 2019.01-svn8 (May 13 2021 - 12:04:48 +0200)

CPU: 66AK2Gx-100 SR1.0
Model: Texas Instruments K2G SoC - Hitachi Propulsion Controller (HPC) board
Avvio u-boot entro 1 secondi
256 MiB
MMC:
Loading Environment from FAT... In:    serial@02530c00
Out:   serial@02530c00
Err:   serial@02530c00
MAC 1: 04:91:62:B2:BE:3C
MAC 2: 04:91:62:B2:9B:9E
Net:   No ethernet found.
Hit any key to stop autoboot:  0
syntax error
No MMC device available
No MMC device available
SF: Detected n25q512ax3 with page size 256 Bytes, erase size 4 KiB, total 64 MiB
device 0 offset 0x240000, size 0x8040
SF: 32832 bytes @ 0x240000 Read: OK
K2_BM_15.07-53-ge1f885a SoC:k2g built:17:19:23, Oct 19 2019

## installed monitor @ 0xc0f7000, freq [24000000], status 202338304
device 0 offset 0x250000, size 0x8354
SF: 33620 bytes @ 0x250000 Read: OK
0 - Name:'pmmc@2900000' type:'internal memory mapped' supports: load start
Load Remote Processor 0 with data@addr=0x82000000 33620 bytes: Success!
device 0 offset 0x280000, size 0x800000
SF: 8388608 bytes @ 0x280000 Read: OK
device 0 offset 0x260000, size 0x8625
SF: 34341 bytes @ 0x260000 Read: OK
device 0 offset 0x200000, size 0x40000
SF: 262144 bytes @ 0x200000 Read: OK
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Ramdisk to 90000000, end 90000000 ... OK
   Loading Device Tree to 8df0e000, end 8df180ea ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.19.59-rt24-svn48 (root@ubuntu) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #18 SMP PREEMPT RT Tue May 25 11:01:47 CEST 2021
[    0.000000] CPU: ARMv7 Processor [412fc0f4] revision 4 (ARMv7), cr=30c5387d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] OF: fdt: Machine model: Texas Instruments K2G - Hitachi Propulsion Controller (HPC)
[    0.000000] bootconsole [earlycon0] enabled
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Switching physical address space to 0x800000000
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: incorrect alignment of CMA region
[    0.000000] cma: Reserved 48 MiB at 0x0000000802c00000
[    0.000000] random: get_random_bytes called from start_kernel+0xb0/0x488 with crng_init=0
[    0.000000] percpu: Embedded 16 pages/cpu s36064 r8192 d21280 u65536
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 34240
[    0.000000] Kernel command line: console=ttyS0,115200n8 earlyprintk root=/dev/mtdblock7 rw rootfstype=jffs2 rootwait
[    0.000000] Dentry cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Memory: 67196K/139264K available (8192K kernel code, 261K rwdata, 1972K rodata, 2048K init, 268K bss, 22916K reserved, 49152K cma-reserved, 0K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xd0800000 - 0xff800000   ( 752 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (10208 kB)
[    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (2048 kB)
[    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 262 kB)
[    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 269 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1.
[    0.000000] rcu:     RCU priority boosting: priority 1 delay 500 ms.
[    0.000000]  No expedited grace period (rcu_normal_after_boot).
[    0.000000]  Tasks RCU enabled.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000008] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.000019] Switching to timer-based delay loop, resolution 41ns
[    0.000212] keystone_timer_init: failed to get clock
[    0.000230] Failed to initialize '/soc@0/timer@2210000': -517
[    0.000601] Console: colour dummy device 80x30
[    0.253700] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000)
[    0.253714] pid_max: default: 32768 minimum: 301
[    0.253930] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.253941] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.255033] CPU: Testing write buffer coherency: ok
[    0.255073] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.255460] /cpus/cpu@0 missing clock-frequency property
[    0.304063] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.360467] Setting up static identity map for 0x80200000 - 0x80200138
[    0.380412] rcu: Hierarchical SRCU implementation.
[    0.441252] EFI services will not be available.
[    0.460632] smp: Bringing up secondary CPUs ...
[    0.465202] smp: Brought up 1 node, 1 CPU
[    0.469243] SMP: Total of 1 processors activated (48.00 BogoMIPS).
[    0.475468] CPU: All CPU(s) started in HYP mode.
[    0.480118] CPU: Virtualization extensions available.
[    0.486160] devtmpfs: initialized
[    0.510954] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.519300] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.529223] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.537046] pinctrl core: initialized pinctrl subsystem
[    0.543857] DMI not present or invalid.
[    0.548511] NET: Registered protocol family 16
[    0.557657] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.567785] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.575876] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.657784] SCSI subsystem initialized
[    0.662705] pps_core: LinuxPPS API ver. 1 registered
[    0.667709] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.676953] PTP clock support registered
[    0.680932] EDAC MC: Ver: 3.0.0
[    0.703562] clocksource: Switched to clocksource arch_sys_counter
[    0.731372] NET: Registered protocol family 2
[    0.737248] tcp_listen_portaddr_hash hash table entries: 128 (order: 0, 4096 bytes)
[    0.745122] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    0.752177] TCP bind hash table entries: 2048 (order: 3, 57344 bytes)
[    0.758837] TCP: Hash tables configured (established 2048 bind 2048)
[    0.765484] UDP hash table entries: 256 (order: 2, 16384 bytes)
[    0.771498] UDP-Lite hash table entries: 256 (order: 2, 16384 bytes)
[    0.778505] NET: Registered protocol family 1
[    0.833938] RPC: Registered named UNIX socket transport module.
[    0.839906] RPC: Registered udp transport module.
[    0.844723] RPC: Registered tcp transport module.
[    0.849462] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.857176] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.867010] platform alarmtimer: set dma_pfn_offset00780000
[    0.874395] Initialise system trusted keyrings
[    0.879280] workingset: timestamp_bits=14 max_order=15 bucket_order=1
[    0.901190] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.924340] NFS: Registering the id_resolver key type
[    0.929509] Key type id_resolver registered
[    0.933825] Key type id_legacy registered
[    0.937985] ntfs: driver 2.1.32 [Flags: R/O].
[    0.942863] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[    0.955810] Key type asymmetric registered
[    0.959946] Asymmetric key parser 'x509' registered
[    0.965219] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.972674] io scheduler noop registered
[    0.976742] io scheduler deadline registered
[    0.981549] io scheduler cfq registered (default)
[    0.986399] io scheduler mq-deadline registered
[    0.990967] io scheduler kyber registered
[    0.996331] keystone_irq 26202a0.keystone_irq: irqchip registered, nr_irqs 28
[    1.005925] pinctrl-single 2621000.pinmux: please update dts to use #pinctrl-cells = <1>
[    1.014952] pinctrl-single 2621000.pinmux: 260 pins, size 1040
[    1.164353] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    1.170955] platform serial8250: set dma_pfn_offset00780000
[    1.209791] brd: module loaded
[    1.235884] loop: module loaded
[    1.246353] platform Fixed MDIO bus.0: set dma_pfn_offset00780000
[    1.253023] libphy: Fixed MDIO Bus: probed
[    1.261803] i2c /dev entries driver
[    1.268846] ti-sci 2921c00.pmmc: DT reboot property: 1
[    1.279942] NET: Registered protocol family 10
[    1.304525] Segment Routing with IPv6
[    1.308394] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.315933] NET: Registered protocol family 17
[    1.320654] Key type dns_resolver registered
[    1.325327] Registering SWP/SWPB emulation handler
[    1.331583] Loading compiled-in X.509 certificates
[    1.339324] davinci_gpio 2603000.gpio: Error -517 getting gpio clock
[    1.348160] davinci_gpio 260a000.gpio: Error -517 getting gpio clock
[    1.367102] ti-sci 2921c00.pmmc: DT reboot property: 1
[    1.373161] ti-sci 2921c00.pmmc: ABI: 2.4 (firmware rev 0x0011 '0.1.1-50-g129ef (Eagle)')
[    1.408200] random: fast init done
[    1.547382] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.555864] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.564803] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.591023] keystone timer clock @100000000 Hz
[    1.629633] edma 2700000.edma: TI EDMA DMA engine driver
[    1.636612] edma 2728000.edma: memcpy is disabled
[    1.652224] edma 2728000.edma: TI EDMA DMA engine driver
[    1.662195] lm90 1-004c: 1-004c supply vcc not found, using dummy regulator
[    1.669468] lm90 1-004c: Linked as a consumer to regulator.0
[    1.687614] rtc-ds3232 2-0068: rtc core: registered ds3232 as rtc0
[    1.700496] console [ttyS0] disabled
[    1.704271] 2530c00.serial: ttyS0 at MMIO 0x2530c00 (irq = 24, base_baud = 12000000) is a TI DA8xx/66AK2x
[    1.714047] console [ttyS0] enabled
[    1.714047] console [ttyS0] enabled
[    1.721036] bootconsole [earlycon0] disabled
[    1.721036] bootconsole [earlycon0] disabled
[    1.733236] cadence-qspi 2940000.qspi: n25q512ax3 (65536 Kbytes)
[    1.739383] 8 fixed-partitions partitions found on MTD device 2940000.qspi.0
[    1.746429] Creating 8 MTD partitions on "2940000.qspi.0":
[    1.751918] 0x000000000000-0x000000100000 : "MLO"
[    1.759005] 0x000000100000-0x000000200000 : "u-boot.img"
[    1.766498] 0x000000200000-0x000000240000 : "fdt"
[    1.773368] 0x000000240000-0x000000250000 : "skern-k2g.bin"
[    1.781149] 0x000000250000-0x000000260000 : "ti-sci-firmware"
[    1.789087] 0x000000260000-0x000000280000 : "k2-fw-initrd.cpio.gz"
[    1.797410] 0x000000280000-0x000000a80000 : "kernel"
[    1.804620] 0x000000a80000-0x000004000000 : "file-system"
[    1.814315] spi_davinci 21805400.spi: DMA is not supported (-19)
[    1.822040] spi_davinci 21805400.spi: Controller at 0x(ptrval)
[    1.829754] spi_davinci 21805800.spi: DMA is not supported (-19)
[    1.837435] spi_davinci 21805800.spi: Controller at 0x(ptrval)
[    1.845142] spi_davinci 21805c00.spi: DMA is not supported (-19)
[    1.851881] spi_davinci 21805c00.spi: Controller at 0x(ptrval)
[    1.859447] spi_davinci 21806000.spi: DMA is not supported (-19)
[    1.866201] spi_davinci 21806000.spi: Controller at 0x(ptrval)
[    1.874304] rtc-ds3232 2-0068: setting system clock to 2021-05-25 11:04:56 UTC (1621940696)
[    2.944534] VFS: Mounted root (jffs2 filesystem) on device 31:7.
[    2.951110] devtmpfs: mounted
[    2.959830] Freeing unused kernel memory: 2048K
[    2.964553] Run /sbin/init as init process
[    3.894807] random: crng init done
INIT: version 2.88 booting
Framebuffer /dev/fb0 not detected
Boot splashscreen disabled
[    6.754869] Key type cifs.idmap registered
[    7.640470] NET: Registered protocol family 38
[    7.942573] usbcore: registered new interface driver usbfs
[    7.948212] usbcore: registered new interface driver hub
[    7.953786] usbcore: registered new device driver usb
[    8.135126] registered dspmem misc device
[    8.263637] davinci_mdio 20ab2400.mdio: davinci mdio revision 1.6, bus freq 2500000
[    8.271296] libphy: 20ab2400.mdio: probed
[    8.347121] davinci_mdio 20ab2400.mdio: phy[0]: device 20ab2400.mdio:00, driver TI DP83822
[    8.355606] davinci_mdio 20ab2400.mdio: phy[1]: device 20ab2400.mdio:01, driver TI DP83822
[    8.590131] remoteproc remoteproc0: 20ab4000.pru is available
[    8.597041] pru-rproc 20ab4000.pru: PRU rproc node pru@20ab4000 probed successfully
[    8.605456] remoteproc remoteproc1: 20ab8000.pru is available
[    8.611457] pru-rproc 20ab8000.pru: PRU rproc node pru@20ab8000 probed successfully
[    8.619791] remoteproc remoteproc2: 20af4000.pru is available
[    8.625792] pru-rproc 20af4000.pru: PRU rproc node pru@20af4000 probed successfully
[    8.634110] remoteproc remoteproc3: 20af8000.pru is available
[    8.640080] pru-rproc 20af8000.pru: PRU rproc node pru@20af8000 probed successfully
[    8.699112] prueth pruss0_eth: pruss_fw_drop_untagged_vlan 0
[    8.704789] prueth pruss0_eth: pruss MC Mask (Port 0) ff:ff:ff:ff:ff:ff
[    8.711405] prueth pruss0_eth: pruss MC Mask (Port 1) ff:ff:ff:ff:ff:ff
[    8.721219] prueth pruss0_eth: TI PRU ethernet (type 0) driver initialized
[    8.949842] CAN device driver interface
[    9.081651] c_can_platform 260b200.can: c_can_platform device registered (regs=cb6ede37, irq=25)
[    9.589798] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    9.637154] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    9.830305] usbcore: registered new interface driver cdc_acm
[    9.836255] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    9.899831] usbcore: registered new interface driver usb-storage
[    9.916238] usbcore: registered new interface driver uas
[   10.020256] usbcore: registered new interface driver cp210x
[   10.026201] usbserial: USB Serial support registered for cp210x
[   10.086854] usbcore: registered new interface driver ftdi_sio
[   10.092738] usbserial: USB Serial support registered for FTDI USB Serial Device
[   10.165092] usbcore: registered new interface driver option
[   10.170791] usbserial: USB Serial support registered for GSM modem (1-port)
[   10.209793] usbcore: registered new interface driver pl2303
[   10.215766] usbserial: USB Serial support registered for pl2303
[   10.285538] usbcore: registered new interface driver usbtest
[   10.951211] udc-core: couldn't find an available UDC - added [zero] to list of pending drivers
[   10.989524] udc-core: couldn't find an available UDC - added [g_ether] to list of pending drivers
[   11.034373] gadgetfs: USB Gadget filesystem, version 24 Aug 2004
[   11.069918] file system registered
[   11.106065] udc-core: couldn't find an available UDC - added [g_mass_storage] to list of pending drivers
[   11.145516] udc-core: couldn't find an available UDC - added [g_serial] to list of pending drivers
[   11.182935] udc-core: couldn't find an available UDC - added [printer] to list of pending drivers
[   11.221714] udc-core: couldn't find an available UDC - added [g_cdc] to list of pending drivers
[   11.290576] udc-core: couldn't find an available UDC - added [dbgp] to list of pending drivers
[   11.330513] udc-core: couldn't find an available UDC - added [g_multi] to list of pending drivers
[   11.368952] udc-core: couldn't find an available UDC - added [g_ncm] to list of pending drivers
[   11.408604] udc-core: couldn't find an available UDC - added [g_acm_ms] to list of pending drivers
[   12.019122] davinci-wdt 2250000.wdt: heartbeat 60 sec
[   12.056646] EDAC MC0: Giving out device to module ti-emif-edac controller ti,emif-keystone: DEV 21010000.emif (INTERRUPT)
[   12.332121] usbcore: registered new interface driver usbhid
[   12.337893] usbhid: USB HID core driver
[   12.732715] Driver for 1-wire Dallas network protocol.
[   12.882980] keystone-rproc 10800000.dsp: device does not have specific CMA pool
[   12.890813] remoteproc remoteproc4: 10800000.dsp is available
[   13.082928] GACT probability on
[   13.117192] Mirror/redirect action on
[   13.258315] Simple TC action Loaded
[   13.775616] netem: version 1.3
[   14.004785] u32 classifier
[   14.007503]     input device check on
[   14.011165]     Actions configured
[   15.150198] IPVS: Registered protocols ()
[   15.155537] IPVS: Connection hash table configured (size=4096, memory=32Kbytes)
[   15.163238] IPVS: ipvs loaded.
[   15.456774] IPVS: [rr] scheduler registered.
[   16.124952] Initializing XFRM netlink socket
[   16.395661] mip6: Mobile IPv6
[   16.518860] 8021q: 802.1Q VLAN Support v1.8
[   16.597846] NET: Registered protocol family 15
[   16.731644] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[   17.456155] Bridge firewalling registered
[   17.571673] can: controller area network core (rev 20170425 abi 9)
[   17.578382] NET: Registered protocol family 29
[   17.620839] can: raw protocol (rev 20170425)
[   17.667549] can: broadcast manager protocol (rev 20170425 t)
[   17.713356] can: netlink gateway (rev 20170425) max_hops=1
[   17.861620] sctp: Hash tables configured (bind 128/146)
[   17.944031] NET: Registered protocol family 45
INIT: Entering runlevel: 5
Configuring network interfaces... [   19.454364] pruss_intc_irq_set_affinity: event 20 not configured: ch = -1, host = -1
[   19.454569] pruss_intc_irq_set_affinity: event 22 not configured: ch = -1, host = -1
[   19.454737] pruss_intc_irq_set_affinity: event 26 not configured: ch = -1, host = -1
[   19.456106] iep ptp bc clkid -1
[   19.483236] remoteproc remoteproc0: powering up 20ab4000.pru
[   19.494496] remoteproc remoteproc0: Booting fw image ti-pruss/k2g-pru0-prueth-fw.elf, size 6328
[   19.503472] pruss 20a80000.pruss: configured system_events[63-0] = 00000600,04500000
[   19.511245] pruss 20a80000.pruss: configured intr_channels = 0x000000d5 host_intr = 0x00000155
[   19.519861] remoteproc remoteproc0: remote processor 20ab4000.pru is now up
[   19.527008] net eth0: started
[   19.529262] prueth pruss0_eth eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[   19.538698] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   19.544736] 8021q: adding VLAN 0 to HW filter on device eth0
[   19.550553] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
udhcpc: started, v1.29.3
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
udhcpc: no lease, forking to background
[   28.107785] pruss_intc_irq_set_affinity: event 21 not configured: ch = -1, host = -1
[   28.107991] pruss_intc_irq_set_affinity: event 23 not configured: ch = -1, host = -1
[   28.108157] pruss_intc_irq_set_affinity: event 27 not configured: ch = -1, host = -1
[   28.108369] remoteproc remoteproc1: powering up 20ab8000.pru
[   28.140526] remoteproc remoteproc1: Booting fw image ti-pruss/k2g-pru1-prueth-fw.elf, size 6356
[   28.149569] pruss 20a80000.pruss: configured system_events[63-0] = 00600000,08a00000
[   28.157357] pruss 20a80000.pruss: configured intr_channels = 0x0000032a host_intr = 0x000002aa
[   28.166362] remoteproc remoteproc1: remote processor 20ab8000.pru is now up
[   28.173443] net eth1: started
[   28.176611] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   28.182453] 8021q: adding VLAN 0 to HW filter on device eth1
udhcpc: started, v1.29.3
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
udhcpc: no lease, forking to background
ip: either "dev" is duplicate, or "type" is garbage
ip: either "dev" is duplicate, or "type" is garbage
done.
Starting Dropbear SSH server: dropbear.
Starting syslogd/klogd: done

 _____                    _____           _         _
|  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_
|     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
|__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|
              |___|                    |___|

Arago Project http://arago-project.org k2g-hpc /dev/ttyS0

Arago 2019.07 k2g-hpc /dev/ttyS0

k2g-hpc login: root
Password:
sh: 22: unknown operand
root@k2g-hpc:~# reboot
INIT: Sending processes the TERM signal
root@k2g-hpc:~# Framebuffer /dev/fb0 not detected
Boot splashscreen disabled
Stopping syslogd/klogd: stopped syslogd (pid 1510)
stopped klogd (pid 1513)
done
Deconfiguring network interfaces... [   63.615639] prueth pruss0_eth eth0: Link is Down
[   63.624473] pruss 20a80000.pruss: unconfigured system_events[63-0] = 00000600,04500000
[   63.632392] pruss 20a80000.pruss: unconfigured host_intr = 0x00000155
[   63.638946] remoteproc remoteproc0: stopped remote processor 20ab4000.pru
[   63.646390] net eth0: stopped
[   63.925445] pruss 20a80000.pruss: unconfigured system_events[63-0] = 00600000,08a00000
[   63.933371] pruss 20a80000.pruss: unconfigured host_intr = 0x000002aa
[   63.939832] remoteproc remoteproc1: stopped remote processor 20ab8000.pru
[   63.947919] net eth1: stopped
ifdown: interface can0 not configured
ifdown: interface can1 not configured
done.
Sending all processes the TERM signal...
Sending all processes the KILL signal...
Unmounting remote filesystems...
Deactivating swap...
Unmounting local filesystems...
Rebooting... [   71.460119] reboot: Restarting system

Effectively that warning appears twice in kernel log (see row 4 and 14):

[    1.246353] platform Fixed MDIO bus.0: set dma_pfn_offset00780000
[    1.253023] libphy: Fixed MDIO Bus: probed
[    1.261803] i2c /dev entries driver
[    1.268846] ti-sci 2921c00.pmmc: DT reboot property: 1
[    1.279942] NET: Registered protocol family 10
[    1.304525] Segment Routing with IPv6
[    1.308394] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.315933] NET: Registered protocol family 17
[    1.320654] Key type dns_resolver registered
[    1.325327] Registering SWP/SWPB emulation handler
[    1.331583] Loading compiled-in X.509 certificates
[    1.339324] davinci_gpio 2603000.gpio: Error -517 getting gpio clock
[    1.348160] davinci_gpio 260a000.gpio: Error -517 getting gpio clock
[    1.367102] ti-sci 2921c00.pmmc: DT reboot property: 1
[    1.373161] ti-sci 2921c00.pmmc: ABI: 2.4 (firmware rev 0x0011 '0.1.1-50-g129ef (Eagle)')
[    1.408200] random: fast init done

I wait for your suggestion to manage the dev_warn in  tisci_reboot_handler() but I hope that this log is sufficient to understand the problem.

Regards

Graziano

Hi Graziano,

[    1.367102] ti-sci 2921c00.pmmc: DT reboot property: 1                       
[    1.373161] ti-sci 2921c00.pmmc: ABI: 2.4 (firmware rev 0x0011 '0.1.1-50-g129ef (Eagle)')

It is interesting that the second dev_warn() is not printed. It is expected between the two lines above.

Can you please remove the first patch, and apply the patch attached below? Th patch adds more debug prints maybe it can tell why the second dev_warn() is not called. I also fixed the dev_warn() in tisci_reboot_handle(). Sorry about the error in the first patch.

This patch is quite big, so I attached it below. You can download it and apply.

diff --git a/drivers/firmware/ti_sci.c b/drivers/firmware/ti_sci.c
index 825891f6e6cd..44c56d7611ce 100644
--- a/drivers/firmware/ti_sci.c
+++ b/drivers/firmware/ti_sci.c
@@ -3503,6 +3503,7 @@ static int tisci_reboot_handler(struct notifier_block *nb, unsigned long mode,
 	struct ti_sci_info *info = reboot_to_ti_sci_info(nb);
 	const struct ti_sci_handle *handle = &info->handle;
 
+	printk(KERN_WARNING "DT reboot called here\n");
 	ti_sci_cmd_core_reboot(handle);
 
 	/* call fail OR pass, we should not be here in the first place */
@@ -3593,6 +3594,7 @@ static int ti_sci_probe(struct platform_device *pdev)
 	INIT_LIST_HEAD(&info->node);
 	minfo = &info->minfo;
 
+	dev_warn(dev, "DT reboot property: %d\n", reboot);
 	/*
 	 * Pre-allocate messages
 	 * NEVER allocate more than what we can indicate in hdr.seq
@@ -3606,23 +3608,33 @@ static int ti_sci_probe(struct platform_device *pdev)
 					 desc->max_msgs,
 					 sizeof(*minfo->xfer_block),
 					 GFP_KERNEL);
-	if (!minfo->xfer_block)
+	if (!minfo->xfer_block) {
+		dev_warn(dev, "DT reboot kcalloc failed (xfer_block %dx%d)\n",
+				desc->max_msgs, sizeof(*minfo->xfer_block));
 		return -ENOMEM;
+	}
 
 	minfo->xfer_alloc_table = devm_kcalloc(dev,
 					       BITS_TO_LONGS(desc->max_msgs),
 					       sizeof(unsigned long),
 					       GFP_KERNEL);
-	if (!minfo->xfer_alloc_table)
+	if (!minfo->xfer_alloc_table) {
+		dev_warn(dev, "DT reboot kcalloc failed (xfer_alloc_table %dx%d)\n",
+				BITS_TO_LONGS(desc->max_msgs),
+				sizeof(unsigned long));
 		return -ENOMEM;
+	}
 	bitmap_zero(minfo->xfer_alloc_table, desc->max_msgs);
 
 	/* Pre-initialize the buffer pointer to pre-allocated buffers */
 	for (i = 0, xfer = minfo->xfer_block; i < desc->max_msgs; i++, xfer++) {
 		xfer->xfer_buf = devm_kcalloc(dev, 1, desc->max_msg_size,
 					      GFP_KERNEL);
-		if (!xfer->xfer_buf)
+		if (!xfer->xfer_buf) {
+		dev_warn(dev, "DT reboot kcalloc failed (xfer_buf[%d] %d)\n",
+				i, desc->max_msg_size);
 			return -ENOMEM;
+		}
 
 		xfer->tx_message.buf = xfer->xfer_buf;
 		init_completion(&xfer->done);
@@ -3646,12 +3658,14 @@ static int ti_sci_probe(struct platform_device *pdev)
 	info->chan_rx = mbox_request_channel_byname(cl, "rx");
 	if (IS_ERR(info->chan_rx)) {
 		ret = PTR_ERR(info->chan_rx);
+		dev_warn(dev, "DT reboot mbox rx failed (%d)\n", ret);
 		goto out;
 	}
 
 	info->chan_tx = mbox_request_channel_byname(cl, "tx");
 	if (IS_ERR(info->chan_tx)) {
 		ret = PTR_ERR(info->chan_tx);
+		dev_warn(dev, "DT reboot mbox tx failed (%d)\n", ret);
 		goto out;
 	}
 	ret = ti_sci_cmd_get_revision(info);
@@ -3662,11 +3676,13 @@ static int ti_sci_probe(struct platform_device *pdev)
 
 	ti_sci_setup_ops(info);
 
+	dev_warn(dev, "DT reboot property again: %d\n", reboot);
 	if (reboot) {
 		info->nb.notifier_call = tisci_reboot_handler;
 		info->nb.priority = 128;
 
 		ret = register_restart_handler(&info->nb);
+		dev_warn(dev, "DT reboot registration: %d\n", ret);
 		if (ret) {
 			dev_err(dev, "reboot registration fail(%d)\n", ret);
 			return ret;

Hi,

I have applied the patch. 

Below the kernel log:

U-Boot 2019.01-svn8 (May 13 2021 - 12:04:48 +0200)

CPU: 66AK2Gx-100 SR1.0
Model: Texas Instruments K2G SoC - Hitachi Propulsion Controller (HPC) board
Avvio u-boot entro 1 secondi
256 MiB
MMC:
Loading Environment from FAT... In:    serial@02530c00
Out:   serial@02530c00
Err:   serial@02530c00
MAC 1: 04:91:62:B2:BE:3C
MAC 2: 04:91:62:B2:9B:9E
Net:   No ethernet found.
Hit any key to stop autoboot:  0
syntax error
No MMC device available
No MMC device available
SF: Detected n25q512ax3 with page size 256 Bytes, erase size 4 KiB, total 64 MiB
device 0 offset 0x240000, size 0x8040
SF: 32832 bytes @ 0x240000 Read: OK
K2_BM_15.07-53-ge1f885a SoC:k2g built:17:19:23, Oct 19 2019

## installed monitor @ 0xc0f7000, freq [24000000], status 202338304
device 0 offset 0x250000, size 0x8354
SF: 33620 bytes @ 0x250000 Read: OK
0 - Name:'pmmc@2900000' type:'internal memory mapped' supports: load start
Load Remote Processor 0 with data@addr=0x82000000 33620 bytes: Success!
device 0 offset 0x280000, size 0x800000
SF: 8388608 bytes @ 0x280000 Read: OK
device 0 offset 0x260000, size 0x8625
SF: 34341 bytes @ 0x260000 Read: OK
device 0 offset 0x200000, size 0x40000
SF: 262144 bytes @ 0x200000 Read: OK
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Ramdisk to 90000000, end 90000000 ... OK
   Loading Device Tree to 8df0e000, end 8df180ea ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.19.59-rt24-svn48 (root@ubuntu) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #19 SMP PREEMPT RT Tue May 25 15:35:23 CEST 2021
[    0.000000] CPU: ARMv7 Processor [412fc0f4] revision 4 (ARMv7), cr=30c5387d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] OF: fdt: Machine model: Texas Instruments K2G - Hitachi Propulsion Controller (HPC)
[    0.000000] bootconsole [earlycon0] enabled
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Switching physical address space to 0x800000000
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: incorrect alignment of CMA region
[    0.000000] cma: Reserved 48 MiB at 0x0000000802c00000
[    0.000000] random: get_random_bytes called from start_kernel+0xb0/0x488 with crng_init=0
[    0.000000] percpu: Embedded 16 pages/cpu s36064 r8192 d21280 u65536
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 34240
[    0.000000] Kernel command line: console=ttyS0,115200n8 earlyprintk root=/dev/mtdblock7 rw rootfstype=jffs2 rootwait
[    0.000000] Dentry cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Memory: 67196K/139264K available (8192K kernel code, 261K rwdata, 1976K rodata, 2048K init, 268K bss, 22916K reserved, 49152K cma-reserved, 0K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xd0800000 - 0xff800000   ( 752 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (10208 kB)
[    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (2048 kB)
[    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 262 kB)
[    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 269 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1.
[    0.000000] rcu:     RCU priority boosting: priority 1 delay 500 ms.
[    0.000000]  No expedited grace period (rcu_normal_after_boot).
[    0.000000]  Tasks RCU enabled.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000007] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.000018] Switching to timer-based delay loop, resolution 41ns
[    0.000209] keystone_timer_init: failed to get clock
[    0.000229] Failed to initialize '/soc@0/timer@2210000': -517
[    0.000599] Console: colour dummy device 80x30
[    0.253692] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000)
[    0.253705] pid_max: default: 32768 minimum: 301
[    0.253925] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.253936] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.255026] CPU: Testing write buffer coherency: ok
[    0.255068] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.255456] /cpus/cpu@0 missing clock-frequency property
[    0.304053] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.360460] Setting up static identity map for 0x80200000 - 0x80200138
[    0.380406] rcu: Hierarchical SRCU implementation.
[    0.441245] EFI services will not be available.
[    0.460621] smp: Bringing up secondary CPUs ...
[    0.465189] smp: Brought up 1 node, 1 CPU
[    0.469230] SMP: Total of 1 processors activated (48.00 BogoMIPS).
[    0.475455] CPU: All CPU(s) started in HYP mode.
[    0.480105] CPU: Virtualization extensions available.
[    0.486161] devtmpfs: initialized
[    0.510954] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.519298] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.529215] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.537033] pinctrl core: initialized pinctrl subsystem
[    0.543862] DMI not present or invalid.
[    0.548515] NET: Registered protocol family 16
[    0.557638] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.567817] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.575944] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.657779] SCSI subsystem initialized
[    0.662701] pps_core: LinuxPPS API ver. 1 registered
[    0.667704] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.676938] PTP clock support registered
[    0.680917] EDAC MC: Ver: 3.0.0
[    0.703571] clocksource: Switched to clocksource arch_sys_counter
[    0.731390] NET: Registered protocol family 2
[    0.737266] tcp_listen_portaddr_hash hash table entries: 128 (order: 0, 4096 bytes)
[    0.745136] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    0.752192] TCP bind hash table entries: 2048 (order: 3, 57344 bytes)
[    0.758843] TCP: Hash tables configured (established 2048 bind 2048)
[    0.765492] UDP hash table entries: 256 (order: 2, 16384 bytes)
[    0.771504] UDP-Lite hash table entries: 256 (order: 2, 16384 bytes)
[    0.778509] NET: Registered protocol family 1
[    0.833946] RPC: Registered named UNIX socket transport module.
[    0.839914] RPC: Registered udp transport module.
[    0.844734] RPC: Registered tcp transport module.
[    0.849472] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.857185] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.867022] platform alarmtimer: set dma_pfn_offset00780000
[    0.874422] Initialise system trusted keyrings
[    0.879304] workingset: timestamp_bits=14 max_order=15 bucket_order=1
[    0.901434] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.924350] NFS: Registering the id_resolver key type
[    0.929482] Key type id_resolver registered
[    0.933745] Key type id_legacy registered
[    0.937903] ntfs: driver 2.1.32 [Flags: R/O].
[    0.942779] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[    0.955733] Key type asymmetric registered
[    0.959871] Asymmetric key parser 'x509' registered
[    0.965137] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.972593] io scheduler noop registered
[    0.976661] io scheduler deadline registered
[    0.981473] io scheduler cfq registered (default)
[    0.986315] io scheduler mq-deadline registered
[    0.990885] io scheduler kyber registered
[    0.996241] keystone_irq 26202a0.keystone_irq: irqchip registered, nr_irqs 28
[    1.005850] pinctrl-single 2621000.pinmux: please update dts to use #pinctrl-cells = <1>
[    1.014889] pinctrl-single 2621000.pinmux: 260 pins, size 1040
[    1.165140] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    1.171745] platform serial8250: set dma_pfn_offset00780000
[    1.210764] brd: module loaded
[    1.236856] loop: module loaded
[    1.247368] platform Fixed MDIO bus.0: set dma_pfn_offset00780000
[    1.254130] libphy: Fixed MDIO Bus: probed
[    1.262879] i2c /dev entries driver
[    1.269922] ti-sci 2921c00.pmmc: DT reboot property: 1
[    1.275319] ti-sci 2921c00.pmmc: DT reboot mbox rx failed (-517)
[    1.287303] NET: Registered protocol family 10
[    1.304541] Segment Routing with IPv6
[    1.308413] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.315931] NET: Registered protocol family 17
[    1.320651] Key type dns_resolver registered
[    1.325339] Registering SWP/SWPB emulation handler
[    1.331600] Loading compiled-in X.509 certificates
[    1.339365] davinci_gpio 2603000.gpio: Error -517 getting gpio clock
[    1.348254] davinci_gpio 260a000.gpio: Error -517 getting gpio clock
[    1.367306] ti-sci 2921c00.pmmc: DT reboot property: 1
[    1.373358] ti-sci 2921c00.pmmc: DT reboot property again: 1
[    1.379213] ti-sci 2921c00.pmmc: DT reboot registration: 0
[    1.384813] ti-sci 2921c00.pmmc: ABI: 2.4 (firmware rev 0x0011 '0.1.1-50-g129ef (Eagle)')
[    1.419642] random: fast init done
[    1.560171] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.568665] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.577618] ti-sci-clk 2921c00.pmmc:clocks: get-parent failed for dev=76, clk=11, ret=-19
[    1.603998] keystone timer clock @100000000 Hz
[    1.642691] edma 2700000.edma: TI EDMA DMA engine driver
[    1.649696] edma 2728000.edma: memcpy is disabled
[    1.665528] edma 2728000.edma: TI EDMA DMA engine driver
[    1.675525] lm90 1-004c: 1-004c supply vcc not found, using dummy regulator
[    1.682776] lm90 1-004c: Linked as a consumer to regulator.0
[    1.700966] rtc-ds3232 2-0068: rtc core: registered ds3232 as rtc0
[    1.714078] console [ttyS0] disabled
[    1.717742] 2530c00.serial: ttyS0 at MMIO 0x2530c00 (irq = 24, base_baud = 12000000) is a TI DA8xx/66AK2x
[    1.727483] console [ttyS0] enabled
[    1.727483] console [ttyS0] enabled
[    1.734543] bootconsole [earlycon0] disabled
[    1.734543] bootconsole [earlycon0] disabled
[    1.746861] cadence-qspi 2940000.qspi: n25q512ax3 (65536 Kbytes)
[    1.753010] 8 fixed-partitions partitions found on MTD device 2940000.qspi.0
[    1.760057] Creating 8 MTD partitions on "2940000.qspi.0":
[    1.765551] 0x000000000000-0x000000100000 : "MLO"
[    1.772652] 0x000000100000-0x000000200000 : "u-boot.img"
[    1.780154] 0x000000200000-0x000000240000 : "fdt"
[    1.787072] 0x000000240000-0x000000250000 : "skern-k2g.bin"
[    1.794930] 0x000000250000-0x000000260000 : "ti-sci-firmware"
[    1.802875] 0x000000260000-0x000000280000 : "k2-fw-initrd.cpio.gz"
[    1.811236] 0x000000280000-0x000000a80000 : "kernel"
[    1.818388] 0x000000a80000-0x000004000000 : "file-system"
[    1.828023] spi_davinci 21805400.spi: DMA is not supported (-19)
[    1.835754] spi_davinci 21805400.spi: Controller at 0x(ptrval)
[    1.843472] spi_davinci 21805800.spi: DMA is not supported (-19)
[    1.851183] spi_davinci 21805800.spi: Controller at 0x(ptrval)
[    1.858809] spi_davinci 21805c00.spi: DMA is not supported (-19)
[    1.865564] spi_davinci 21805c00.spi: Controller at 0x(ptrval)
[    1.873133] spi_davinci 21806000.spi: DMA is not supported (-19)
[    1.879900] spi_davinci 21806000.spi: Controller at 0x(ptrval)
[    1.887994] rtc-ds3232 2-0068: setting system clock to 2021-05-25 15:39:50 UTC (1621957190)
[    2.938203] VFS: Mounted root (jffs2 filesystem) on device 31:7.
[    2.944744] devtmpfs: mounted
[    2.953460] Freeing unused kernel memory: 2048K
[    2.958187] Run /sbin/init as init process
[    3.940529] random: crng init done
INIT: version 2.88 booting
Framebuffer /dev/fb0 not detected
Boot splashscreen disabled
[    6.797753] Key type cifs.idmap registered
[    7.680343] NET: Registered protocol family 38
[    7.981830] usbcore: registered new interface driver usbfs
[    7.987458] usbcore: registered new interface driver hub
[    7.992973] usbcore: registered new device driver usb
[    8.172036] registered dspmem misc device
[    8.304187] davinci_mdio 20ab2400.mdio: davinci mdio revision 1.6, bus freq 2500000
[    8.311846] libphy: 20ab2400.mdio: probed
[    8.390137] davinci_mdio 20ab2400.mdio: phy[0]: device 20ab2400.mdio:00, driver TI DP83822
[    8.398605] davinci_mdio 20ab2400.mdio: phy[1]: device 20ab2400.mdio:01, driver TI DP83822
[    8.632454] remoteproc remoteproc0: 20ab4000.pru is available
[    8.638602] pru-rproc 20ab4000.pru: PRU rproc node pru@20ab4000 probed successfully
[    8.648061] remoteproc remoteproc1: 20ab8000.pru is available
[    8.654064] pru-rproc 20ab8000.pru: PRU rproc node pru@20ab8000 probed successfully
[    8.662366] remoteproc remoteproc2: 20af4000.pru is available
[    8.668368] pru-rproc 20af4000.pru: PRU rproc node pru@20af4000 probed successfully
[    8.676643] remoteproc remoteproc3: 20af8000.pru is available
[    8.682614] pru-rproc 20af8000.pru: PRU rproc node pru@20af8000 probed successfully
[    8.741663] prueth pruss0_eth: pruss_fw_drop_untagged_vlan 0
[    8.747337] prueth pruss0_eth: pruss MC Mask (Port 0) ff:ff:ff:ff:ff:ff
[    8.754009] prueth pruss0_eth: pruss MC Mask (Port 1) ff:ff:ff:ff:ff:ff
[    8.763988] prueth pruss0_eth: TI PRU ethernet (type 0) driver initialized
[    8.992042] CAN device driver interface
[    9.124875] c_can_platform 260b200.can: c_can_platform device registered (regs=793dd15f, irq=25)
[    9.631723] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    9.681307] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    9.874884] usbcore: registered new interface driver cdc_acm
[    9.880550] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    9.945153] usbcore: registered new interface driver usb-storage
[    9.961075] usbcore: registered new interface driver uas
[   10.065014] usbcore: registered new interface driver cp210x
[   10.070721] usbserial: USB Serial support registered for cp210x
[   10.128044] usbcore: registered new interface driver ftdi_sio
[   10.133999] usbserial: USB Serial support registered for FTDI USB Serial Device
[   10.207224] usbcore: registered new interface driver option
[   10.212926] usbserial: USB Serial support registered for GSM modem (1-port)
[   10.253061] usbcore: registered new interface driver pl2303
[   10.258935] usbserial: USB Serial support registered for pl2303
[   10.328448] usbcore: registered new interface driver usbtest
[   10.992451] udc-core: couldn't find an available UDC - added [zero] to list of pending drivers
[   11.030312] udc-core: couldn't find an available UDC - added [g_ether] to list of pending drivers
[   11.075143] gadgetfs: USB Gadget filesystem, version 24 Aug 2004
[   11.110563] file system registered
[   11.144177] udc-core: couldn't find an available UDC - added [g_mass_storage] to list of pending drivers
[   11.183065] udc-core: couldn't find an available UDC - added [g_serial] to list of pending drivers
[   11.223295] udc-core: couldn't find an available UDC - added [printer] to list of pending drivers
[   11.261310] udc-core: couldn't find an available UDC - added [g_cdc] to list of pending drivers
[   11.330738] udc-core: couldn't find an available UDC - added [dbgp] to list of pending drivers
[   11.370096] udc-core: couldn't find an available UDC - added [g_multi] to list of pending drivers
[   11.408832] udc-core: couldn't find an available UDC - added [g_ncm] to list of pending drivers
[   11.447880] udc-core: couldn't find an available UDC - added [g_acm_ms] to list of pending drivers
[   12.055465] davinci-wdt 2250000.wdt: heartbeat 60 sec
[   12.093401] EDAC MC0: Giving out device to module ti-emif-edac controller ti,emif-keystone: DEV 21010000.emif (INTERRUPT)
[   12.367403] usbcore: registered new interface driver usbhid
[   12.372981] usbhid: USB HID core driver
[   12.767090] Driver for 1-wire Dallas network protocol.
[   12.917271] keystone-rproc 10800000.dsp: device does not have specific CMA pool
[   12.925043] remoteproc remoteproc4: 10800000.dsp is available
[   13.115480] GACT probability on
[   13.150018] Mirror/redirect action on
[   13.290625] Simple TC action Loaded
[   13.804488] netem: version 1.3
[   14.031163] u32 classifier
[   14.034149]     input device check on
[   14.037813]     Actions configured
[   15.161234] IPVS: Registered protocols ()
[   15.166670] IPVS: Connection hash table configured (size=4096, memory=32Kbytes)
[   15.175194] IPVS: ipvs loaded.
[   15.464975] IPVS: [rr] scheduler registered.
[   16.132355] Initializing XFRM netlink socket
[   16.404707] mip6: Mobile IPv6
[   16.526766] 8021q: 802.1Q VLAN Support v1.8
[   16.606306] NET: Registered protocol family 15
[   16.740991] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[   17.461663] Bridge firewalling registered
[   17.576920] can: controller area network core (rev 20170425 abi 9)
[   17.583362] NET: Registered protocol family 29
[   17.625422] can: raw protocol (rev 20170425)
[   17.671270] can: broadcast manager protocol (rev 20170425 t)
[   17.715236] can: netlink gateway (rev 20170425) max_hops=1
[   17.862663] sctp: Hash tables configured (bind 128/146)
[   17.943498] NET: Registered protocol family 45
INIT: Entering runlevel: 5
Configuring network interfaces... [   19.479802] pruss_intc_irq_set_affinity: event 20 not configured: ch = -1, host = -1
[   19.480008] pruss_intc_irq_set_affinity: event 22 not configured: ch = -1, host = -1
[   19.480175] pruss_intc_irq_set_affinity: event 26 not configured: ch = -1, host = -1
[   19.481541] iep ptp bc clkid -1
[   19.508792] remoteproc remoteproc0: powering up 20ab4000.pru
[   19.519911] remoteproc remoteproc0: Booting fw image ti-pruss/k2g-pru0-prueth-fw.elf, size 6328
[   19.528987] pruss 20a80000.pruss: configured system_events[63-0] = 00000600,04500000
[   19.536814] pruss 20a80000.pruss: configured intr_channels = 0x000000d5 host_intr = 0x00000155
[   19.545428] remoteproc remoteproc0: remote processor 20ab4000.pru is now up
[   19.552505] net eth0: started
[   19.554866] prueth pruss0_eth eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[   19.563792] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   19.569635] 8021q: adding VLAN 0 to HW filter on device eth0
[   19.575450] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
udhcpc: started, v1.29.3
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending select for 192.168.1.184
udhcpc: lease of 192.168.1.184 obtained, lease time 86400
/etc/udhcpc.d/50default: Adding DNS 192.168.1.1
[   26.072572] pruss_intc_irq_set_affinity: event 21 not configured: ch = -1, host = -1
[   26.072776] pruss_intc_irq_set_affinity: event 23 not configured: ch = -1, host = -1
[   26.072945] pruss_intc_irq_set_affinity: event 27 not configured: ch = -1, host = -1
[   26.073156] remoteproc remoteproc1: powering up 20ab8000.pru
[   26.106004] remoteproc remoteproc1: Booting fw image ti-pruss/k2g-pru1-prueth-fw.elf, size 6356
[   26.115003] pruss 20a80000.pruss: configured system_events[63-0] = 00600000,08a00000
[   26.122750] pruss 20a80000.pruss: configured intr_channels = 0x0000032a host_intr = 0x000002aa
[   26.131381] remoteproc remoteproc1: remote processor 20ab8000.pru is now up
[   26.138546] net eth1: started
[   26.141718] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   26.147563] 8021q: adding VLAN 0 to HW filter on device eth1
udhcpc: started, v1.29.3
udhcpc: sending discover
udhcpc: sending discover
udhcpc: sending discover
udhcpc: no lease, forking to background
ip: either "dev" is duplicate, or "type" is garbage
ip: either "dev" is duplicate, or "type" is garbage
done.
Starting Dropbear SSH server: dropbear.
Starting syslogd/klogd: done

 _____                    _____           _         _
|  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_
|     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
|__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|
              |___|                    |___|

Arago Project http://arago-project.org k2g-hpc /dev/ttyS0

Arago 2019.07 k2g-hpc /dev/ttyS0

k2g-hpc login: root
Password:
sh: 22: unknown operand
root@k2g-hpc:~# reboot

The system is going down for reboot NOW!S0) (T
root@k2g-hpc:~# Framebuffer /dev/fb0 not detected
Boot splashscreen disabled
Stopping syslogd/klogd: stopped syslogd (pid 1515)
stopped klogd (pid 1518)
done
Deconfiguring network interfaces... [   45.845349] prueth pruss0_eth eth0: Link is Down
[   45.854205] pruss 20a80000.pruss: unconfigured system_events[63-0] = 00000600,04500000
[   45.862125] pruss 20a80000.pruss: unconfigured host_intr = 0x00000155
[   45.868575] remoteproc remoteproc0: stopped remote processor 20ab4000.pru
[   45.876325] net eth0: stopped
[   46.155463] pruss 20a80000.pruss: unconfigured system_events[63-0] = 00600000,08a00000
[   46.163388] pruss 20a80000.pruss: unconfigured host_intr = 0x000002aa
[   46.169838] remoteproc remoteproc1: stopped remote processor 20ab8000.pru
[   46.178402] net eth1: stopped
ifdown: interface can0 not configured
ifdown: interface can1 not configured
done.
Sending all processes the TERM signal...
Sending all processes the KILL signal...
Unmounting remote filesystems...
Deactivating swap...
Unmounting local filesystems...
Rebooting... [   53.690438] reboot: Restarting system

Let me know any news.

Regards

Graziano

Hi Graziano,

Thanks for the log.

In Linux for K2G, when received reboot command, kernel does all the necessary teardown, then call the reboot handler - tisci_reboot_handler() to reboot the processor.

The log shows the reboot handler is registered (line 174 in the log above), but it is not called during reboot.

I will review the kernel code more early next week and get you back. Sorry for the delay, the coming weekend is a holiday in US, and I will be off for a few days. Meanwhile if you can a chance, you can try to add some debug printk in the kernel reboot routine to understand why the reboot handler is not called. I believe the kernel reboot code is in <kernel src>/kernel/reboot.c.