search

google-coral / tflite

Examples using TensorFlow Lite API to run inference on Coral devices
https://coral.withgoogle.com
Apache License 2.0
181 stars 67 forks source link

Kernel Oops - Bug: 0 #39

Closed Aeroten closed 3 years ago

Aeroten commented 3 years ago

Hi ! I tried to run the TFLite demo on Siemens IOT 2050 with the PCI E Coral TPU. Running the classification example in python :

python3 classify_image.py \ --model models/mobilenet_v2_1.0_224_inat_bird_quant_edgetpu.tflite \ --labels models/inat_bird_labels.txt \ --input images/parrot.jpg

Gives me

Message from syslogd@iot2050-debian at Nov 11 21:16:45 ... kernel:[ 1822.583092] Internal error: Oops - BUG: 0 [#4] PREEMPT SMP

Message from syslogd@iot2050-debian at Nov 11 21:16:45 ... kernel:[ 1822.611947] Process python3 (pid: 1119, stack limit = 0x0000000052b2a22a)

Message from syslogd@iot2050-debian at Nov 11 21:16:45 ... kernel:[ 1822.766935] Code: eb1a037f 54000080 f9400320 b4fffe40 (d4210000)

Any idea ? Thank you in advance for your support !

Namburger commented 3 years ago

hey! Could you share some outputs?

$ uname -a
$ lspci -vvv
$ modinfo gasket
$ modinfo apex
Aeroten commented 3 years ago

Hi Namburger,

Thanks for your kind reply ! Sure, here is the information.

Concerning the kernel I installed the Coral driver generating the headers and adding the name of the headers (linux-headers-iot2050) in the gasket-dkms package from the tutorial (https://coral.ai/docs/m2/get-started/#2-install-the-pcie-driver-and-edge-tpu-runtime). It seems to me it's installed properly.

Maybe interresting for you as well, I get the same error running the demo script without the Edge TPU, that's why I'm posting here.

root@iot2050-debian:~# uname -a Linux iot2050-debian 4.19.59+ #1 SMP PREEMPT Tue Nov 10 15:13:23 UTC 2020 aarch64 GNU/Linux

root@iot2050-debian:~# lspci -vvv 00:00.0 PCI bridge: Texas Instruments Device b00c (rev 01) (prog-if 00 [Normal decode]) Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- SERR- <PERR- INTx- Latency: 0 Interrupt: pin A routed to IRQ 303 Bus: primary=00, secondary=01, subordinate=ff, sec-latency=0 I/O behind bridge: 0000f000-00000fff Memory behind bridge: fff00000-000fffff Prefetchable memory behind bridge: 18100000-182fffff Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR- [virtual] Expansion ROM at 18030000 [disabled] [size=64K] BridgeCtl: Parity- SERR- NoISA- VGA- MAbort- >Reset- FastB2B- PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn- Capabilities: [40] Power Management version 3 Flags: PMEClk- DSI- D1+ D2- AuxCurrent=375mA PME(D0+,D1+,D2-,D3hot+,D3cold-) Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME- Capabilities: [50] MSI: Enable- Count=1/1 Maskable+ 64bit+ Address: 0000000000000000 Data: 0000 Masking: 00000000 Pending: 00000000 Capabilities: [70] Express (v2) Root Port (Slot-), MSI 00 DevCap: MaxPayload 256 bytes, PhantFunc 0 ExtTag- RBE+ DevCtl: Report errors: Correctable- Non-Fatal- Fatal- Unsupported- RlxdOrd+ ExtTag- PhantFunc- AuxPwr- NoSnoop- MaxPayload 128 bytes, MaxReadReq 128 bytes DevSta: CorrErr- UncorrErr- FatalErr- UnsuppReq- AuxPwr- TransPend- LnkCap: Port #0, Speed 8GT/s, Width x2, ASPM L0s L1, Exit Latency L0s <512ns, L1 <64us ClockPM- Surprise- LLActRep+ BwNot+ ASPMOptComp+ LnkCtl: ASPM Disabled; RCB 64 bytes Disabled- CommClk- ExtSynch- ClockPM- AutWidDis- BWInt- AutBWInt- LnkSta: Speed 5GT/s, Width x1, TrErr- Train- SlotClk+ DLActive+ BWMgmt- ABWMgmt- RootCtl: ErrCorrectable- ErrNon-Fatal- ErrFatal- PMEIntEna- CRSVisible+ RootCap: CRSVisible+ RootSta: PME ReqID 0000, PMEStatus- PMEPending- DevCap2: Completion Timeout: Range ABCD, TimeoutDis+, LTR-, OBFF Not Supported ARIFwd- DevCtl2: Completion Timeout: 50us to 50ms, TimeoutDis-, LTR-, OBFF Disabled ARIFwd- LnkCtl2: Target Link Speed: 8GT/s, EnterCompliance- SpeedDis- Transmit Margin: Normal Operating Range, EnterModifiedCompliance- ComplianceSOS- Compliance De-emphasis: -6dB LnkSta2: Current De-emphasis Level: -3.5dB, EqualizationComplete-, EqualizationPhase1- EqualizationPhase2-, EqualizationPhase3-, LinkEqualizationRequest- Capabilities: [b0] MSI-X: Enable- Count=257 Masked- Vector table: BAR=0 offset=00002000 PBA: BAR=0 offset=00004000 Capabilities: [100 v2] Advanced Error Reporting UESta: DLP- SDES- TLP- FCP- CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP- ECRC- UnsupReq- ACSViol- UEMsk: DLP- SDES- TLP- FCP- CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP- ECRC- UnsupReq- ACSViol- UESvrt: DLP+ SDES+ TLP- FCP+ CmpltTO- CmpltAbrt- UnxCmplt- RxOF+ MalfTLP+ ECRC- UnsupReq- ACSViol- CESta: RxErr- BadTLP- BadDLLP- Rollover- Timeout- NonFatalErr- CEMsk: RxErr- BadTLP- BadDLLP- Rollover- Timeout- NonFatalErr+ AERCap: First Error Pointer: 00, GenCap+ CGenEn- ChkCap+ ChkEn- Capabilities: [148 v1] Virtual Channel Caps: LPEVC=0 RefClk=100ns PATEntryBits=1 Arb: Fixed+ WRR32- WRR64- WRR128- Ctrl: ArbSelect=Fixed Status: InProgress- VC0: Caps: PATOffset=00 MaxTimeSlots=1 RejSnoopTrans- Arb: Fixed- WRR32- WRR64- WRR128- TWRR128- WRR256- Ctrl: Enable+ ID=0 ArbSelect=Fixed TC/VC=ff Status: NegoPending- InProgress- VC1: Caps: PATOffset=00 MaxTimeSlots=1 RejSnoopTrans- Arb: Fixed- WRR32- WRR64- WRR128- TWRR128- WRR256- Ctrl: Enable- ID=0 ArbSelect=Fixed TC/VC=00 Status: NegoPending- InProgress- VC2: Caps: PATOffset=00 MaxTimeSlots=1 RejSnoopTrans- Arb: Fixed- WRR32- WRR64- WRR128- TWRR128- WRR256- Ctrl: Enable- ID=0 ArbSelect=Fixed TC/VC=00 Status: NegoPending- InProgress- VC3: Caps: PATOffset=00 MaxTimeSlots=1 RejSnoopTrans- Arb: Fixed- WRR32- WRR64- WRR128- TWRR128- WRR256- Ctrl: Enable- ID=0 ArbSelect=Fixed TC/VC=00 Status: NegoPending- InProgress- Capabilities: [198 v1] #19 Capabilities: [1a8 v1] L1 PM Substates L1SubCap: PCI-PM_L1.2+ PCI-PM_L1.1+ ASPM_L1.2- ASPM_L1.1+ L1_PM_Substates+ PortCommonModeRestoreTime=10us PortTPowerOnTime=10us L1SubCtl1: PCI-PM_L1.2- PCI-PM_L1.1- ASPM_L1.2- ASPM_L1.1- T_CommonMode=10us L1SubCtl2: T_PwrOn=10us Capabilities: [1b8 v1] Precision Time Measurement PTMCap: Requester:+ Responder:+ Root:+ PTMClockGranularity: 4ns PTMControl: Enabled:- RootSelected:- PTMEffectiveGranularity: Unknown Capabilities: [1c4 v1] Vendor Specific Information: ID=0004 Rev=1 Len=054 <?> Kernel modules: pci_endpoint_test

01:00.0 System peripheral: Device 1ac1:089a (prog-if ff) Subsystem: Device 1ac1:089a Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+ Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- SERR- <PERR- INTx- Latency: 0 Interrupt: pin A routed to IRQ 303 Region 0: Memory at 18200000 (64-bit, prefetchable) [size=16K] Region 2: Memory at 18100000 (64-bit, prefetchable) [size=1M] Capabilities: [80] Express (v2) Endpoint, MSI 00 DevCap: MaxPayload 256 bytes, PhantFunc 0, Latency L0s <64ns, L1 <1us ExtTag+ AttnBtn- AttnInd- PwrInd- RBE+ FLReset- SlotPowerLimit 0.000W DevCtl: Report errors: Correctable- Non-Fatal- Fatal- Unsupported- RlxdOrd+ ExtTag+ PhantFunc- AuxPwr- NoSnoop+ MaxPayload 128 bytes, MaxReadReq 128 bytes DevSta: CorrErr+ UncorrErr- FatalErr- UnsuppReq- AuxPwr- TransPend- LnkCap: Port #1, Speed 5GT/s, Width x1, ASPM L0s L1, Exit Latency L0s <64ns, L1 <1us ClockPM+ Surprise- LLActRep- BwNot- ASPMOptComp+ LnkCtl: ASPM Disabled; RCB 64 bytes Disabled- CommClk- ExtSynch- ClockPM- AutWidDis- BWInt- AutBWInt- LnkSta: Speed 5GT/s, Width x1, TrErr- Train- SlotClk- DLActive- BWMgmt- ABWMgmt- DevCap2: Completion Timeout: Range ABCD, TimeoutDis+, LTR+, OBFF Not Supported DevCtl2: Completion Timeout: 50us to 50ms, TimeoutDis-, LTR-, OBFF Disabled LnkCtl2: Target Link Speed: 5GT/s, EnterCompliance- SpeedDis- Transmit Margin: Normal Operating Range, EnterModifiedCompliance- ComplianceSOS- Compliance De-emphasis: -6dB LnkSta2: Current De-emphasis Level: -6dB, EqualizationComplete-, EqualizationPhase1- EqualizationPhase2-, EqualizationPhase3-, LinkEqualizationRequest- Capabilities: [d0] MSI-X: Enable+ Count=128 Masked- Vector table: BAR=2 offset=00046800 PBA: BAR=2 offset=00046068 Capabilities: [e0] MSI: Enable- Count=1/32 Maskable- 64bit+ Address: 0000000000000000 Data: 0000 Capabilities: [f8] Power Management version 3 Flags: PMEClk- DSI- D1- D2- AuxCurrent=0mA PME(D0-,D1-,D2-,D3hot-,D3cold-) Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME- Capabilities: [100 v1] Vendor Specific Information: ID=1556 Rev=1 Len=008 <?> Capabilities: [108 v1] Latency Tolerance Reporting Max snoop latency: 0ns Max no snoop latency: 0ns Capabilities: [110 v1] L1 PM Substates L1SubCap: PCI-PM_L1.2+ PCI-PM_L1.1+ ASPM_L1.2+ ASPM_L1.1+ L1_PM_Substates+ PortCommonModeRestoreTime=10us PortTPowerOnTime=10us L1SubCtl1: PCI-PM_L1.2- PCI-PM_L1.1- ASPM_L1.2- ASPM_L1.1- T_CommonMode=0us LTR1.2_Threshold=0ns L1SubCtl2: T_PwrOn=10us Capabilities: [200 v2] Advanced Error Reporting UESta: DLP- SDES- TLP- FCP- CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP- ECRC- UnsupReq- ACSViol- UEMsk: DLP- SDES- TLP- FCP- CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP- ECRC- UnsupReq- ACSViol- UESvrt: DLP+ SDES- TLP- FCP+ CmpltTO- CmpltAbrt- UnxCmplt- RxOF- MalfTLP+ ECRC- UnsupReq- ACSViol- CESta: RxErr+ BadTLP- BadDLLP- Rollover- Timeout- NonFatalErr- CEMsk: RxErr- BadTLP- BadDLLP- Rollover- Timeout- NonFatalErr+ AERCap: First Error Pointer: 00, GenCap+ CGenEn- ChkCap+ ChkEn- Kernel driver in use: apex Kernel modules: apex

root@iot2050-debian:~# modinfo gasket filename: /lib/modules/4.19.59+/updates/dkms/gasket.ko author: Rob Springer rspringer@google.com license: GPL v2 version: 1.1.3 description: Google Gasket driver framework srcversion: EE63B2D1E7E5BEBAF51CEE1 depends: name: gasket vermagic: 4.19.59+ SMP preempt mod_unload modversions aarch64 parm: dma_bit_mask:int

root@iot2050-debian:~# modinfo apex filename: /lib/modules/4.19.59+/updates/dkms/apex.ko author: John Joseph jnjoseph@google.com license: GPL v2 version: 1.1 description: Google Apex driver srcversion: 58E1862C107AD9271AA7ADA alias: pci:v00001AC1d0000089Asvsdbcsci* depends: gasket name: apex vermagic: 4.19.59+ SMP preempt mod_unload modversions aarch64 parm: allow_power_save:int parm: allow_sw_clock_gating:int parm: allow_hw_clock_gating:int parm: bypass_top_level:int parm: trip_point0_temp:int parm: trip_point1_temp:int parm: trip_point2_temp:int parm: hw_temp_warn1:int parm: hw_temp_warn2:int parm: hw_temp_warn1_en:bool parm: hw_temp_warn2_en:bool parm: temp_poll_interval:int

Good luck ! :-)

Namburger commented 3 years ago

@Aeroten hello, installation looks good, I've actually never seen this message before o_0 Can you also attach the full dmesg?

Aeroten commented 3 years ago

Hi Namburger,

Yeah, same to me, this is really strange. I'm testing right now one of your C example, to see if it has something to do with python. I'll let you know the result there.

Here is the start dmesg

root@iot2050-debian:~# dmesg [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.19.59+ (builder@a22b1bcd338d) (gcc version 8.3.0 (Debian 8.3.0-2)) #1 SMP PREEMPT Tue Nov 10 15:13:23 UTC 2020 [ 0.000000] Machine model: SIMATIC IOT2050-ADVANCED [ 0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002810000 (options '') [ 0.000000] bootconsole [ns16550a0] enabled [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a0000000, size 1 MiB [ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@a0000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a0100000, size 15 MiB [ 0.000000] OF: reserved mem: initialized node r5f-memory@a0100000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB [ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@a1000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 15 MiB [ 0.000000] OF: reserved mem: initialized node r5f-memory@a1100000, compatible id shared-dma-pool [ 0.000000] cma: Reserved 512 MiB at 0x00000000c0000000 [ 0.000000] On node 0 totalpages: 31840 [ 0.000000] DMA32 zone: 32 pages used for memmap [ 0.000000] DMA32 zone: 0 pages reserved [ 0.000000] DMA32 zone: 31840 pages, LIFO batch:3 [ 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: Trusted OS migration not required [ 0.000000] psci: SMC Calling Convention v1.1 [ 0.000000] random: get_random_bytes called from start_kernel+0x94/0x3e4 with crng_init=0 [ 0.000000] percpu: Embedded 2 pages/cpu s52632 r8192 d70248 u131072 [ 0.000000] pcpu-alloc: s52632 r8192 d70248 u131072 alloc=2*65536 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: enabling workaround for ARM erratum 845719 [ 0.000000] Speculative Store Bypass Disable mitigation not required [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] Built 1 zonelists, mobility grouping off. Total pages: 31808 [ 0.000000] Kernel command line: root=PARTUUID=cc564b7b-eb36-484c-89cf-c851f3d606a4 console=ttyS3,115200n8 earlycon=ns16550a,mmio32,0x02810000 mtdparts=47040000.spi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),64k(pru0-fw),64k(pru1-fw),64k(rtu0-fw),64k(rtu1-fw),-@8m(ospi.rootfs) rw rootwait [ 0.000000] Dentry cache hash table entries: 262144 (order: 5, 2097152 bytes) [ 0.000000] Inode-cache hash table entries: 131072 (order: 4, 1048576 bytes) [ 0.000000] Memory: 1492288K/2037760K available (8830K kernel code, 832K rwdata, 3200K rodata, 640K init, 683K bss, 21184K reserved, 524288K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. [ 0.000000] Tasks RCU enabled. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv3: GIC: Using split EOI/Deactivate mode [ 0.000000] GICv3: Distributor has no Range Selector support [ 0.000000] GICv3: no VLPI support, no direct LPI support [ 0.000000] ITS [mem 0x01820000-0x0182ffff] [ 0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS [ 0.000000] ITS@0x0000000001820000: allocated 1048576 Devices @a2800000 (flat, esz 8, psz 64K, shr 0) [ 0.000000] ITS: using cache flushing for cmd queue [ 0.000000] GIC: using LPI property table @0x00000000a22d0000 [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001880000 [ 0.000000] CPU0: using LPI pending table @0x00000000a22e0000 [ 0.000000] GIC: using cache flushing for LPI property table [ 0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns [ 0.000004] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns [ 0.008607] Console: colour dummy device 80x25 [ 0.013194] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000) [ 0.023876] pid_max: default: 32768 minimum: 301 [ 0.028680] Security Framework initialized [ 0.032927] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes) [ 0.039804] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes) [ 0.071228] ASID allocator initialised with 32768 entries [ 0.084785] rcu: Hierarchical SRCU implementation. [ 0.097844] Platform MSI: gic-its@1820000 domain created [ 0.103548] PCI/MSI: /interconnect@100000/interrupt-controller@1800000/gic-its@1820000 domain created [ 0.121015] smp: Bringing up secondary CPUs ... [ 0.158105] Detected VIPT I-cache on CPU1 [ 0.158138] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000 [ 0.158197] CPU1: using LPI pending table @0x00000000a25a0000 [ 0.158242] CPU1: Booted secondary processor 0x0000000001 [0x410fd034] [ 0.219092] Detected VIPT I-cache on CPU2 [ 0.219129] GICv3: CPU2: found redistributor 100 region 0:0x00000000018c0000 [ 0.219191] CPU2: using LPI pending table @0x00000000a2600000 [ 0.219236] CPU2: Booted secondary processor 0x0000000100 [0x410fd034] [ 0.251473] Detected VIPT I-cache on CPU3 [ 0.251496] GICv3: CPU3: found redistributor 101 region 0:0x00000000018e0000 [ 0.251551] CPU3: using LPI pending table @0x00000000a2690000 [ 0.251585] CPU3: Booted secondary processor 0x0000000101 [0x410fd034] [ 0.251715] smp: Brought up 1 node, 4 CPUs [ 0.327290] SMP: Total of 4 processors activated. [ 0.332106] CPU features: detected: GIC system register CPU interface [ 0.338704] CPU features: detected: 32-bit EL0 Support [ 0.370318] CPU: All CPU(s) started at EL2 [ 0.374530] alternatives: patching kernel code [ 0.380520] devtmpfs: initialized [ 0.394535] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.404525] futex hash table entries: 1024 (order: 0, 65536 bytes) [ 0.412182] pinctrl core: initialized pinctrl subsystem [ 0.418431] NET: Registered protocol family 16 [ 0.424050] vdso: 2 pages (1 code @ (ptrval), 1 data @ (ptrval)) [ 0.431630] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.444350] DMA: preallocated 256 KiB pool for atomic allocations [ 0.468120] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.474999] HugeTLB registered 512 MiB page size, pre-allocated 0 pages [ 0.482794] cryptd: max_cpu_qlen set to 1000 [ 0.488868] SCSI subsystem initialized [ 0.492893] usbcore: registered new interface driver usbfs [ 0.498554] usbcore: registered new interface driver hub [ 0.504059] usbcore: registered new device driver usb [ 0.509641] media: Linux media interface: v0.10 [ 0.514302] videodev: Linux video capture interface: v2.00 [ 0.519948] pps_core: LinuxPPS API ver. 1 registered [ 0.525026] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it [ 0.534377] PTP clock support registered [ 0.538408] EDAC MC: Ver: 3.0.0 [ 0.542803] Advanced Linux Sound Architecture Driver Initialized. [ 0.549915] clocksource: Switched to clocksource arch_sys_counter [ 0.556365] VFS: Disk quotas dquot_6.6.0 [ 0.560490] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes) [ 0.573763] NET: Registered protocol family 2 [ 0.578894] tcp_listen_portaddr_hash hash table entries: 4096 (order: 0, 65536 bytes) [ 0.587026] TCP established hash table entries: 16384 (order: 1, 131072 bytes) [ 0.594542] TCP bind hash table entries: 16384 (order: 2, 262144 bytes) [ 0.601579] TCP: Hash tables configured (established 16384 bind 16384) [ 0.608378] UDP hash table entries: 2048 (order: 0, 65536 bytes) [ 0.614623] UDP-Lite hash table entries: 2048 (order: 0, 65536 bytes) [ 0.621457] NET: Registered protocol family 1 [ 0.626448] RPC: Registered named UNIX socket transport module. [ 0.632519] RPC: Registered udp transport module. [ 0.637332] RPC: Registered tcp transport module. [ 0.642144] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.648737] PCI: CLS 0 bytes, default 64 [ 0.649780] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available [ 0.662310] Initialise system trusted keyrings [ 0.667048] workingset: timestamp_bits=46 max_order=15 bucket_order=0 [ 0.678319] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.684989] NFS: Registering the id_resolver key type [ 0.690196] Key type id_resolver registered [ 0.694477] Key type id_legacy registered [ 0.698589] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 0.705669] 9p: Installing v9fs 9p2000 file system support [ 0.715265] Key type asymmetric registered [ 0.719469] Asymmetric key parser 'x509' registered [ 0.724513] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 0.732085] io scheduler noop registered [ 0.736104] io scheduler deadline registered [ 0.740599] io scheduler cfq registered (default) [ 0.745422] io scheduler mq-deadline registered [ 0.750059] io scheduler kyber registered [ 0.756284] pinctrl-single 4301c000.pinmux: 70 pins, size 280 [ 0.762739] pinctrl-single 11c000.pinmux: 185 pins, size 740 [ 0.768660] pinctrl-single 11c2e8.pinmux: 9 pins, size 36 [ 0.775230] pinctrl-single a40000.timesync_router: 512 pins, size 2048 [ 0.789810] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled [ 0.810178] brd: module loaded [ 0.819995] loop: module loaded [ 0.825962] libphy: Fixed MDIO Bus: probed [ 0.830625] tun: Universal TUN/TAP device driver, 1.6 [ 0.836379] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k [ 0.844395] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 0.850500] sky2: driver version 1.30 [ 0.855326] VFIO - User Level meta-driver version: 0.3 [ 0.861923] usbcore: registered new interface driver usb-storage [ 0.868633] i2c /dev entries driver [ 0.873268] sdhci: Secure Digital Host Controller Interface driver [ 0.879601] sdhci: Copyright(c) Pierre Ossman [ 0.884500] sdhci-pltfm: SDHCI platform and OF driver helper [ 0.891392] ledtrig-cpu: registered to indicate activity on CPUs [ 0.897621] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: invalid resource [ 0.908908] optee: probing for conduit method from DT. [ 0.914216] optee: revision 3.6 (e260ea8d) [ 0.914487] optee: initialized driver [ 0.923553] NET: Registered protocol family 17 [ 0.928256] 9pnet: Installing 9P2000 support [ 0.932673] Key type dns_resolver registered [ 0.937652] registered taskstats version 1 [ 0.941848] Loading compiled-in X.509 certificates [ 0.958658] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: invalid resource [ 0.968637] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: ABI: 2.6 (firmware rev 0x0013 '19.7.1-v2019.07a (Terrific Llam') [ 1.002151] random: fast init done [ 1.414656] omap_i2c 42120000.i2c: bus 0 rev0.12 at 400 kHz [ 1.442589] pca953x 1-0020: 1-0020 supply vcc not found, using dummy regulator [ 1.450032] pca953x 1-0020: Linked as a consumer to regulator.0 [ 1.457405] pca953x 1-0021: 1-0021 supply vcc not found, using dummy regulator [ 1.464833] pca953x 1-0021: Linked as a consumer to regulator.0 [ 1.472165] pca953x 1-0025: 1-0025 supply vcc not found, using dummy regulator [ 1.479592] pca953x 1-0025: Linked as a consumer to regulator.0 [ 1.486830] omap_i2c 40b00000.i2c: bus 1 rev0.12 at 400 kHz [ 1.514491] rtc-pcf8563 2-0051: low voltage detected, date/time is not reliable. [ 1.522165] rtc-pcf8563 2-0051: rtc core: registered rtc-pcf8563 as rtc0 [ 1.530108] omap_i2c 2000000.i2c: bus 2 rev0.12 at 400 kHz [ 1.536446] omap_i2c 2010000.i2c: bus 3 rev0.12 at 400 kHz [ 1.542661] omap_i2c 2020000.i2c: bus 4 rev0.12 at 400 kHz [ 1.549147] omap_i2c 2030000.i2c: bus 5 rev0.12 at 400 kHz [ 1.561196] keystone-pcie 5600000.pcie: Linked as a consumer to phy-910000.serdes.2 [ 1.569065] keystone-pcie 5600000.pcie: Dropping the link to phy-910000.serdes.2 [ 1.577610] k3-ringacc 2b800000.ringacc: Ring Accelerator probed rings:286, gp-rings[96,160] sci-dev-id:195 [ 1.587613] k3-ringacc 2b800000.ringacc: dma-ring-reset-quirk: enabled [ 1.594301] k3-ringacc 2b800000.ringacc: RA Proxy rev. 66344100, num_proxies:64 [ 1.602127] k3-ringacc 3c000000.ringacc: Ring Accelerator probed rings:818, gp-rings[304,464] sci-dev-id:187 [ 1.612196] k3-ringacc 3c000000.ringacc: dma-ring-reset-quirk: enabled [ 1.618879] k3-ringacc 3c000000.ringacc: RA Proxy rev. 66344100, num_proxies:64 [ 1.626674] omap8250 40a00000.serial: PM domain pd:149 will not be powered off [ 1.634379] 40a00000.serial: ttyS1 at MMIO 0x40a00000 (irq = 8, base_baud = 6000000) is a 8250 [ 1.643894] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 17, base_baud = 3000000) is a 8250 [ 1.652661] console [ttyS3] enabled [ 1.659704] bootconsole [ns16550a0] disabled [ 1.671628] cadence-qspi 47040000.spi: w25q128 (16384 Kbytes) [ 1.677406] 11 cmdlinepart partitions found on MTD device 47040000.spi.0 [ 1.684101] Creating 11 MTD partitions on "47040000.spi.0": [ 1.689677] 0x000000000000-0x000000080000 : "ospi.tiboot3" [ 1.695910] 0x000000080000-0x000000280000 : "ospi.tispl" [ 1.701798] 0x000000280000-0x000000680000 : "ospi.u-boot" [ 1.707727] 0x000000680000-0x0000006a0000 : "ospi.env" [ 1.713487] 0x0000006a0000-0x0000006c0000 : "ospi.env.backup" [ 1.719815] 0x0000006c0000-0x0000007c0000 : "ospi.sysfw" [ 1.725689] 0x0000007c0000-0x0000007d0000 : "pru0-fw" [ 1.731274] 0x0000007d0000-0x0000007e0000 : "pru1-fw" [ 1.736831] 0x0000007e0000-0x0000007f0000 : "rtu0-fw" [ 1.742461] 0x0000007f0000-0x000000800000 : "rtu1-fw" [ 1.748031] 0x000000800000-0x000001000000 : "ospi.rootfs" [ 1.930118] cadence-qspi 47040000.spi: Cadence QSPI NOR probe failed -517 [ 1.945745] am65-cpts 310d0000.cpts: CPTS ver 0x4e8a2109, freq:200000000, add_val:4 pps:0 [ 1.954882] dwc3 4010000.usb: Failed to get clk 'ref': -2 [ 1.960968] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 1.966482] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 [ 1.974488] xhci-hcd xhci-hcd.0.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010 [ 1.983934] xhci-hcd xhci-hcd.0.auto: irq 35, io mem 0x04010000 [ 1.990139] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 [ 1.998402] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.005619] usb usb1: Product: xHCI Host Controller [ 2.010494] usb usb1: Manufacturer: Linux 4.19.59+ xhci-hcd [ 2.016061] usb usb1: SerialNumber: xhci-hcd.0.auto [ 2.021368] hub 1-0:1.0: USB hub found [ 2.025144] hub 1-0:1.0: 1 port detected [ 2.029299] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 2.034789] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 [ 2.042452] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed [ 2.049031] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 2.057190] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19 [ 2.065447] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.072667] usb usb2: Product: xHCI Host Controller [ 2.077542] usb usb2: Manufacturer: Linux 4.19.59+ xhci-hcd [ 2.083109] usb usb2: SerialNumber: xhci-hcd.0.auto [ 2.088294] hub 2-0:1.0: USB hub found [ 2.092065] hub 2-0:1.0: 1 port detected [ 2.097190] dwc3 4030000.usb: Failed to get clk 'ref': -2 [ 2.102675] dwc3 4030000.usb: Configuration mismatch. dr_mode forced to host [ 2.110132] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 2.115636] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3 [ 2.123636] xhci-hcd xhci-hcd.1.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010 [ 2.133091] xhci-hcd xhci-hcd.1.auto: irq 37, io mem 0x04030000 [ 2.139267] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 [ 2.147528] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.154745] usb usb3: Product: xHCI Host Controller [ 2.159619] usb usb3: Manufacturer: Linux 4.19.59+ xhci-hcd [ 2.165186] usb usb3: SerialNumber: xhci-hcd.1.auto [ 2.170455] hub 3-0:1.0: USB hub found [ 2.174229] hub 3-0:1.0: 1 port detected [ 2.178373] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 2.183863] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4 [ 2.191524] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed [ 2.198099] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. [ 2.206257] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19 [ 2.214514] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.221729] usb usb4: Product: xHCI Host Controller [ 2.226603] usb usb4: Manufacturer: Linux 4.19.59+ xhci-hcd [ 2.232170] usb usb4: SerialNumber: xhci-hcd.1.auto [ 2.237429] hub 4-0:1.0: USB hub found [ 2.241212] hub 4-0:1.0: 1 port detected [ 2.246874] keystone-wdt 40610000.rti: heartbeat 60 sec [ 2.253442] mmc1: Unknown controller version (4). You may experience problems. [ 2.291853] mmc1: SDHCI controller on 4f80000.sdhci [4f80000.sdhci] using ADMA 64-bit [ 2.300502] mmc0: Unknown controller version (4). You may experience problems. [ 2.337513] mmc0: SDHCI controller on 4fa0000.sdhci [4fa0000.sdhci] using ADMA 64-bit [ 2.346574] mmc1: new HS200 MMC card at address 0001 [ 2.352632] mmcblk1: mmc1:0001 S0J56X 14.8 GiB [ 2.358009] mmcblk1boot0: mmc1:0001 S0J56X partition 1 31.5 MiB [ 2.362001] usb 1-1: new high-speed USB device number 2 using xhci-hcd [ 2.365432] mmcblk1boot1: mmc1:0001 S0J56X partition 2 31.5 MiB [ 2.378146] mmcblk1rpmb: mmc1:0001 S0J56X partition 3 4.00 MiB, chardev (243:0) [ 2.390324] mmcblk1: p1 [ 2.440599] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc5900 [ 2.447556] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc5900 [ 2.454459] keystone-pcie 5600000.pcie: Linked as a consumer to phy-910000.serdes.2 [ 2.464629] keystone-pcie 5600000.pcie: host bridge /interconnect@100000/pcie@5600000 ranges: [ 2.473167] keystone-pcie 5600000.pcie: IO 0x18020000..0x1802ffff -> 0x00000000 [ 2.480742] keystone-pcie 5600000.pcie: MEM 0x18030000..0x1fffffff -> 0x18030000 [ 2.522096] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 [ 2.530282] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 2.588469] keystone-pcie 5600000.pcie: Link up [ 2.593171] keystone-pcie 5600000.pcie: PCI host bridge to bus 0000:00 [ 2.593210] hub 1-1:1.0: USB hub found [ 2.599704] pci_bus 0000:00: root bus resource [bus 00-ff] [ 2.608943] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 2.608950] hub 1-1:1.0: 4 ports detected [ 2.615124] pci_bus 0000:00: root bus resource [mem 0x18030000-0x1fffffff] [ 2.626035] pci 0000:00:00.0: [104c:b00c] type 01 class 0x060400 [ 2.626092] pci 0000:00:00.0: reg 0x38: [mem 0x00000000-0x0000ffff pref] [ 2.626143] pci 0000:00:00.0: supports D1 [ 2.626148] pci 0000:00:00.0: PME# supported from D0 D1 D3hot [ 2.629883] pci 0000:01:00.0: [1ac1:089a] type 00 class 0x0000ff [ 2.630013] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00003fff 64bit pref] [ 2.630055] pci 0000:01:00.0: reg 0x18: [mem 0x00000000-0x000fffff 64bit pref] [ 2.633702] pci 0000:00:00.0: BAR 15: assigned [mem 0x18100000-0x182fffff pref] [ 2.641013] pci 0000:00:00.0: BAR 6: assigned [mem 0x18030000-0x1803ffff pref] [ 2.648239] pci 0000:00:00.0: PCI bridge to [bus 01-ff] [ 2.653468] pci 0000:00:00.0: bridge window [mem 0x18100000-0x182fffff pref] [ 2.661932] ti-udma 285c0000.udmap: Channels: 96 (tchan: 48, echan: 0, rchan: 48, rflow: 96) [ 2.675842] ti-udma 31150000.udmap: Channels: 267 (tchan: 120, echan: 32, rchan: 150, rflow: 300) [ 2.698364] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 2.704991] [drm] No driver support for vblank timestamp query. [ 2.712217] [drm] Initialized tidss 1.0.0 20180215 for 4a00000.dss on minor 0 [ 2.719637] [drm] Cannot find any crtc or sizes [ 2.725620] cadence-qspi 47040000.spi: w25q128 (16384 Kbytes) [ 2.731377] 11 cmdlinepart partitions found on MTD device 47040000.spi.0 [ 2.738077] Creating 11 MTD partitions on "47040000.spi.0": [ 2.743649] 0x000000000000-0x000000080000 : "ospi.tiboot3" [ 2.749813] 0x000000080000-0x000000280000 : "ospi.tispl" [ 2.755657] 0x000000280000-0x000000680000 : "ospi.u-boot" [ 2.761589] 0x000000680000-0x0000006a0000 : "ospi.env" [ 2.767390] 0x0000006a0000-0x0000006c0000 : "ospi.env.backup" [ 2.773706] 0x0000006c0000-0x0000007c0000 : "ospi.sysfw" [ 2.779561] 0x0000007c0000-0x0000007d0000 : "pru0-fw" [ 2.785183] 0x0000007d0000-0x0000007e0000 : "pru1-fw" [ 2.790763] 0x0000007e0000-0x0000007f0000 : "rtu0-fw" [ 2.796337] 0x0000007f0000-0x000000800000 : "rtu1-fw" [ 2.801963] 0x000000800000-0x000001000000 : "ospi.rootfs" [ 2.817720] rtc-pcf8563 2-0051: low voltage detected, date/time is not reliable. [ 2.825149] rtc-pcf8563 2-0051: hctosys: unable to read the hardware clock [ 2.927476] tps62363-vout: disabling [ 2.931068] ALSA device list: [ 2.934034] No soundcards found. [ 2.949953] usb 1-1.4: new full-speed USB device number 3 using xhci-hcd [ 3.055646] usb 1-1.4: New USB device found, idVendor=10c4, idProduct=ea60, bcdDevice= 1.00 [ 3.064035] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 3.071506] usb 1-1.4: Product: CP2102N USB to UART Bridge Controller [ 3.077962] usb 1-1.4: Manufacturer: Silicon Labs [ 3.082704] usb 1-1.4: SerialNumber: 704211ccd12aea1187b0bdfad0cd4f36 [ 3.106600] EXT4-fs (mmcblk1p1): 5 orphan inodes deleted [ 3.111945] EXT4-fs (mmcblk1p1): recovery complete [ 3.121420] EXT4-fs (mmcblk1p1): mounted filesystem with ordered data mode. Opts: (null) [ 3.129564] VFS: Mounted root (ext4 filesystem) on device 179:1. [ 3.136089] devtmpfs: mounted [ 3.139359] Freeing unused kernel memory: 640K [ 3.143849] Run /sbin/init as init process [ 3.286542] systemd[1]: System time before build time, advancing clock. [ 3.341021] NET: Registered protocol family 10 [ 3.346460] Segment Routing with IPv6 [ 3.377580] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) [ 3.400107] systemd[1]: Detected architecture arm64. [ 3.438376] systemd[1]: Set hostname to . [ 3.625516] systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. [ 3.642571] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) [ 3.812307] random: systemd: uninitialized urandom read (16 bytes read) [ 3.826118] random: systemd: uninitialized urandom read (16 bytes read) [ 3.833276] systemd[1]: Listening on udev Kernel Socket. [ 3.862401] systemd[1]: Condition check resulted in Journal Audit Socket being skipped. [ 3.870569] random: systemd: uninitialized urandom read (16 bytes read) [ 3.877472] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 3.902197] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 3.927741] systemd[1]: Created slice User and Session Slice. [ 4.044955] EXT4-fs (mmcblk1p1): re-mounted. Opts: (null) [ 4.675215] systemd-journald[165]: Received request to flush runtime journal from PID 1 [ 4.732303] Adding 2097088k swap on /swapfile. Priority:-2 extents:6 across:2334656k SS [ 5.318447] at24 2-0054: 1024 byte 24c08 EEPROM, writable, 16 bytes/write [ 5.364665] gasket: loading out-of-tree module taints kernel. [ 5.403287] pci 0000:00:00.0: limiting MRRS to 128 [ 5.409264] apex 0000:01:00.0: can't enable device: BAR 0 [mem 0x00000000-0x00003fff 64bit pref] not claimed [ 5.420935] apex 0000:01:00.0: BAR 2: assigned [mem 0x18100000-0x181fffff 64bit pref] [ 5.430659] apex 0000:01:00.0: BAR 0: assigned [mem 0x18200000-0x18203fff 64bit pref] [ 5.441190] apex 0000:01:00.0: enabling device (0000 -> 0002) [ 5.455596] apex 0000:01:00.0: limiting MRRS to 128 [ 5.752165] [drm] Cannot find any crtc or sizes [ 5.764660] usbcore: registered new interface driver cp210x [ 5.770364] usbserial: USB Serial support registered for cp210x [ 5.777672] cp210x 1-1.4:1.0: cp210x converter detected [ 5.812303] usb 1-1.4: cp210x converter now attached to ttyUSB0 [ 5.860383] k3_r5_rproc interconnect@100000:interconnect@28380000:r5fss@41000000: creating child devices for R5F cores [ 5.890136] platform 41000000.r5f: configured R5F for remoteproc mode [ 5.922385] platform 41000000.r5f: assigned reserved memory node r5f-dma-memory@a0000000 [ 5.982811] remoteproc remoteproc0: 41000000.r5f is available [ 5.998445] remoteproc remoteproc0: powering up 41000000.r5f [ 6.004262] remoteproc remoteproc0: Booting fw image am65x-mcu-r5f0_0-fw, size 283120 [ 6.018881] platform 41000000.r5f: booting R5F core using boot addr = 0x0 [ 6.030230] remoteproc remoteproc0: remote processor 41000000.r5f is now up [ 6.416703] random: crng init done [ 6.420179] random: 7 urandom warning(s) missed due to ratelimiting [ 6.902040] davinci_mdio b032400.mdio: davinci mdio revision 1.7, bus freq 1000000 [ 6.911754] libphy: b032400.mdio: probed [ 6.966180] davinci_mdio b032400.mdio: phy[0]: device b032400.mdio:00, driver TI DP83867 [ 6.974486] davinci_mdio b032400.mdio: phy[1]: device b032400.mdio:01, driver TI DP83867 [ 7.016243] remoteproc remoteproc1: b034000.pru is available [ 7.022187] pru-rproc b034000.pru: PRU rproc node pru@b034000 probed successfully [ 7.030523] remoteproc remoteproc2: b004000.rtu is available [ 7.036395] pru-rproc b004000.rtu: PRU rproc node rtu@b004000 probed successfully [ 7.044407] remoteproc remoteproc3: b038000.pru is available [ 7.050230] pru-rproc b038000.pru: PRU rproc node pru@b038000 probed successfully [ 7.058365] remoteproc remoteproc4: b006000.rtu is available [ 7.064271] pru-rproc b006000.rtu: PRU rproc node rtu@b006000 probed successfully [ 7.072331] remoteproc remoteproc5: b134000.pru is available [ 7.078158] pru-rproc b134000.pru: PRU rproc node pru@b134000 probed successfully [ 7.086320] remoteproc remoteproc6: b104000.rtu is available [ 7.094734] pru-rproc b104000.rtu: PRU rproc node rtu@b104000 probed successfully [ 7.106201] remoteproc remoteproc7: b138000.pru is available [ 7.116489] pru-rproc b138000.pru: PRU rproc node pru@b138000 probed successfully [ 7.129675] remoteproc remoteproc8: b106000.rtu is available [ 7.139350] pru-rproc b106000.rtu: PRU rproc node rtu@b106000 probed successfully [ 7.147418] remoteproc remoteproc9: b234000.pru is available [ 7.156327] pru-rproc b234000.pru: PRU rproc node pru@b234000 probed successfully [ 7.167051] remoteproc remoteproc10: b204000.rtu is available [ 7.177571] pru-rproc b204000.rtu: PRU rproc node rtu@b204000 probed successfully [ 7.187267] remoteproc remoteproc11: b238000.pru is available [ 7.193180] pru-rproc b238000.pru: PRU rproc node pru@b238000 probed successfully [ 7.201184] remoteproc remoteproc12: b206000.rtu is available [ 7.207237] pru-rproc b206000.rtu: PRU rproc node rtu@b206000 probed successfully [ 7.314687] icssg-prueth pruss0_eth: TI PRU ethernet initialized: dual EMAC mode, dual_icssg 0 [ 7.440380] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 7.455512] remoteproc remoteproc1: powering up b034000.pru [ 7.474102] remoteproc remoteproc1: Booting fw image mtd://pru0-fw, size 65536 [ 7.481385] remoteproc remoteproc1: remote processor b034000.pru is now up [ 7.488313] remoteproc remoteproc2: powering up b004000.rtu [ 7.506650] remoteproc remoteproc2: Booting fw image mtd://rtu0-fw, size 65536 [ 7.513967] remoteproc remoteproc2: remote processor b004000.rtu is now up [ 7.521733] net eth0: started [ 7.529726] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 7.546419] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready [ 7.581006] remoteproc remoteproc3: powering up b038000.pru [ 7.601766] remoteproc remoteproc3: Booting fw image mtd://pru1-fw, size 65536 [ 7.609083] remoteproc remoteproc3: remote processor b038000.pru is now up [ 7.616029] remoteproc remoteproc4: powering up b006000.rtu [ 7.634409] remoteproc remoteproc4: Booting fw image mtd://rtu1-fw, size 65536 [ 7.641713] remoteproc remoteproc4: remote processor b006000.rtu is now up [ 7.649782] net eth1: started [ 7.652974] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready [ 9.686384] icssg-prueth pruss0_eth eth1: Link is Up - 100Mbps/Full - flow control off [ 9.694407] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 10.617993] apex 0000:01:00.0: Apex performance not throttled due to temperature

And when I start the demonstration script

[23168.683341] ------------[ cut here ]------------ [23168.687965] kernel BUG at mm/memory.c:1995! [23168.692138] Internal error: Oops - BUG: 0 [#1] PREEMPT SMP [23168.697610] Modules linked in: ti_am335x_adc icssg_prueth kfifo_buf pru_rproc crc32_ce crct10dif_ce pruss irq_pruss_intc ti_k3_r5_remoteproc remoteproc cp210x usbserial ti_am335x_tscadc apex(O) pruss_soc_bus gasket(O) pci_endpoint_test at24 ip_tables x_tables ipv6 [23168.720993] Process python3 (pid: 16217, stack limit = 0x000000008e94322f) [23168.727855] CPU: 2 PID: 16217 Comm: python3 Tainted: G O 4.19.59+ #1 [23168.735491] Hardware name: SIMATIC IOT2050-ADVANCED (DT) [23168.740791] pstate: 20000005 (nzCv daif -PAN -UAO) [23168.745581] pc : remap_pfn_range+0x1b0/0x260 [23168.749840] lr : remap_pfn_range+0x164/0x260 [23168.754096] sp : ffff0000126afb20 [23168.757399] x29: ffff0000126afb20 x28: 0068000000000f43 [23168.762700] x27: 0000ffff7b314000 x26: 0000ffff7b304000 [23168.768000] x25: ffff80002890d980 x24: ffff8000261cabf8 [23168.773302] x23: 0000ffff7b314000 x22: ffff80003d66ffd8 [23168.778603] x21: 0040000000000041 x20: 0040000000000001 [23168.783903] x19: 0000ffff7b314000 x18: 0000000000000000 [23168.789204] x17: 0000000000000000 x16: 0000000000000000 [23168.794505] x15: 0000000000000000 x14: 0000000000000000 [23168.799805] x13: 0000000000000000 x12: ffff8000105f2e00 [23168.805104] x11: ffff8000105f2e00 x10: ffff7fe0000a2428 [23168.810406] x9 : 000000000000003f x8 : 0000000000010000 [23168.815707] x7 : ffff000000e20338 x6 : 0000000000100000 [23168.821008] x5 : 000000003d66ffd8 x4 : 0068000000000f43 [23168.826307] x3 : ffff7fe0000a2428 x2 : ffff00009ce3c000 [23168.831607] x1 : 0000000000000000 x0 : 0168000018140f43 [23168.836909] Call trace: [23168.839349] remap_pfn_range+0x1b0/0x260 [23168.843277] gasket_mmap+0x290/0x648 [gasket] [23168.847625] mmap_region+0x3fc/0x540 [23168.851191] do_mmap+0x2e4/0x488 [23168.854410] vm_mmap_pgoff+0xe0/0x108 [23168.858063] ksys_mmap_pgoff+0x17c/0x200 [23168.861979] __arm64_sys_mmap+0x28/0x38 [23168.865808] el0_svc_common+0x90/0x158 [23168.869546] el0_svc_handler+0x2c/0x80 [23168.873285] el0_svc+0x8/0xc [23168.876159] Code: eb1a037f 54000080 f9400320 b4fffe40 (d4210000) [23168.882238] ---[ end trace 50bf9025bcf38be8 ]--- [23168.886953] note: python3[16217] exited with preempt_count 1 [23207.910850] rcu: INFO: rcu_sched detected stalls on CPUs/tasks: [23207.916789] rcu: 0-....: (5252 ticks this GP) idle=43e/1/0x4000000000000000 softirq=470155/470197 fqs=2625 [23207.926593] rcu: (detected by 1, t=5255 jiffies, g=70165, q=21) [23207.932589] Task dump for CPU 0: [23207.935807] kswapd0 R running task 0 76 2 0x0000002a [23207.942844] Call trace: [23207.945295] switch_to+0x94/0xd0 [23270.929676] rcu: INFO: rcu_sched detected stalls on CPUs/tasks: [23270.935614] rcu: 0-....: (21007 ticks this GP) idle=43e/1/0x4000000000000000 softirq=470155/470215 fqs=10501 [23270.945591] rcu: (detected by 1, t=21010 jiffies, g=70165, q=51) [23270.951675] Task dump for CPU 0: [23270.954893] kswapd0 R running task 0 76 2 0x0000002a [23270.961930] Call trace: [23270.964380] switch_to+0x94/0xd0

Let's see if you see something...

Aeroten commented 3 years ago

Hi Namburger,

Just finished a quick test, same story in C using the "edgetpu-minimal-example"

root@iot2050-debian:~/edgetpu-minimal-example/build# ../out/aarch64/minimal ../test_data/mobilenet_v1_1.0_224_quant_edgetpu.tflite ../test_data/resized_cat.bmp ../test_data/imagenet_labels.txt

Message from syslogd@iot2050-debian at Nov 12 14:48:38 ... kernel:[ 1973.075573] Internal error: Oops - BUG: 0 [#1] PREEMPT SMP

Message from syslogd@iot2050-debian at Nov 12 14:48:38 ... kernel:[ 1973.104430] Process minimal (pid: 1887, stack limit = 0x0000000056c92afe)

The log from dmesg is very similar :

[ 1973.066780] ------------[ cut here ]------------ [ 1973.071400] kernel BUG at mm/memory.c:1995! [ 1973.075573] Internal error: Oops - BUG: 0 [#1] PREEMPT SMP [ 1973.081045] Modules linked in: icssg_prueth ti_am335x_adc kfifo_buf pru_rproc crc32_ce crct10dif_ce pruss irq_pruss_intc ti_k3_r5_remoteproc remoteproc cp210x usbserial ti_am335x_tscadc apex(O) pruss_soc_bus gasket(O) pci_endpoint_test at24 ip_tables x_tables ipv6 [ 1973.104430] Process minimal (pid: 1887, stack limit = 0x0000000056c92afe) [ 1973.111205] CPU: 1 PID: 1887 Comm: minimal Tainted: G O 4.19.59+ #1 [ 1973.118756] Hardware name: SIMATIC IOT2050-ADVANCED (DT) [ 1973.124055] pstate: 20000005 (nzCv daif -PAN -UAO) [ 1973.128844] pc : remap_pfn_range+0x1b0/0x260 [ 1973.133102] lr : remap_pfn_range+0x164/0x260 [ 1973.137357] sp : ffff00001186fb20 [ 1973.140660] x29: ffff00001186fb20 x28: 0068000000000f43 [ 1973.145961] x27: 0000ffffb3fc4000 x26: 0000ffffb3fb4000 [ 1973.151263] x25: ffff800070d09fd8 x24: ffff800027d3ebf8 [ 1973.156563] x23: 0000ffffb3fc4000 x22: ffff80002c73ffe8 [ 1973.161862] x21: 0040000000000041 x20: 0040000000000001 [ 1973.167162] x19: 0000ffffb3fc4000 x18: 0000000000000000 [ 1973.172461] x17: 0000000000000000 x16: 0000000000000000 [ 1973.177761] x15: 0000000000000000 x14: 0000000000000000 [ 1973.183062] x13: 0000000000000000 x12: ffff80007beb28f8 [ 1973.188361] x11: ffff80007beb28f8 x10: ffff7fe0001c3428 [ 1973.193663] x9 : 000000000000003f x8 : 0000000000010000 [ 1973.198962] x7 : ffff000000d20338 x6 : 0000000000100000 [ 1973.204262] x5 : 000000002c73ffe8 x4 : 0068000000000f43 [ 1973.209561] x3 : ffff7fe0001c3428 x2 : ffff00006418c000 [ 1973.214861] x1 : 0000000000000000 x0 : 0168000018140f43 [ 1973.220161] Call trace: [ 1973.222600] remap_pfn_range+0x1b0/0x260 [ 1973.226528] gasket_mmap+0x290/0x648 [gasket] [ 1973.230876] mmap_region+0x3fc/0x540 [ 1973.234442] do_mmap+0x2e4/0x488 [ 1973.237661] vm_mmap_pgoff+0xe0/0x108 [ 1973.241314] ksys_mmap_pgoff+0x17c/0x200 [ 1973.245230] __arm64_sys_mmap+0x28/0x38 [ 1973.249059] el0_svc_common+0x90/0x158 [ 1973.252798] el0_svc_handler+0x2c/0x80 [ 1973.256536] el0_svc+0x8/0xc [ 1973.259410] Code: eb1a037f 54000080 f9400320 b4fffe40 (d4210000) [ 1973.265490] ---[ end trace a0cd978e015c76ff ]--- [ 1973.270232] note: minimal[1887] exited with preempt_count 1

Aeroten commented 3 years ago

Hi ! It seems my Coral was defect. Replaced with another one solved the problem.

Thanks for the support !