This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

Linux/AM3357: Critical message in kernel boot

Part Number: AM3357
Other Parts Discussed in Thread: GRACE, TPS65217

Tool/software: Linux

Hello,

The 4.14 kernel from the recent processor SDK emits a CRITICAL-level message at boot, with the text "no matching timers found". From inspection of the code, it is not clear to me if this is actually an issue of any kind.  Is this expected?  Is it ok to ignore or demote a info-level log?

The relevant code is in drivers/clocksource/timer-probe.c, and still exists in mainline, so I was concerned that its level was more than an oversight.

Best,

Jonah

  • Hi,

    Please post the log file.
  • The log line in question occurs at 0.000173 below.

     

    [    0.000000] Booting Linux on physical CPU 0x0
    [    0.000000] Linux version 4.14.40-rt29 (ubuntu@ip-10-0-0-220) (gcc version 7.3.0 (Buildroot 2018.02.3-00146-ge4914f8)) #1 PREEMPT RT Thu Sep 6 05:57:32 UTC 2018
    [    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [    0.000000] OF: fdt: Machine model: XXX
    [    0.000000] Memory policy: Data cache writeback
    [    0.000000] cma: Reserved 64 MiB at 0x9c000000
    [    0.000000] CPU: All CPU(s) started in SVC mode.
    [    0.000000] AM335X ES2.1 (neon)
    [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 129920
    [    0.000000] Kernel command line: console=ttyO0,115200n8 fsck.repair=yes loglevel=7 root=PARTUUID=0000b5f0-07 rootwait elevator=noop
    [    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
    [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Memory: 442516K/524288K available (7168K kernel code, 317K rwdata, 1548K rodata, 1024K init, 325K bss, 16236K reserved, 65536K 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 : 0xe0800000 - 0xff800000   ( 496 MB)
    [    0.000000]     lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
    [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
    [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
    [    0.000000]       .text : 0xc0008000 - 0xc0800000   (8160 kB)
    [    0.000000]       .init : 0xc0a00000 - 0xc0b00000   (1024 kB)
    [    0.000000]       .data : 0xc0b00000 - 0xc0b4f7c0   ( 318 kB)
    [    0.000000]        .bss : 0xc0b4f7c0 - 0xc0ba0c04   ( 326 kB)
    [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
    [    0.000000] Preemptible hierarchical RCU implementation.
    [    0.000000]     No expedited grace period (rcu_normal_after_boot).
    [    0.000000]     Tasks RCU enabled.
    [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
    [    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
    [    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
    [    0.000014] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
    [    0.000026] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
    [    0.000032] OMAP clocksource: timer1 at 24000000 Hz
    [    0.000173] timer_probe: no matching timers found
    [    0.000386] Console: colour dummy device 80x30
    [    0.000517] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)
    [    0.089184] pid_max: default: 32768 minimum: 301
    [    0.089336] Security Framework initialized
    [    0.089393] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.089401] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.090230] CPU: Testing write buffer coherency: ok
    [    0.090980] Setting up static identity map for 0x80100000 - 0x80100060
    [    0.091211] Hierarchical SRCU implementation.
    [    0.093066] devtmpfs: initialized
    [    0.101570] random: get_random_u32 called from bucket_table_alloc+0x17c/0x238 with crng_init=0
    [    0.102120] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
    [    0.102466] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.102495] futex hash table entries: 256 (order: 1, 8192 bytes)
    [    0.107113] pinctrl core: initialized pinctrl subsystem
    [    0.108342] NET: Registered protocol family 16
    [    0.110205] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.124939] omap_hwmod: debugss: _wait_target_disable failed
    [    0.182779] OMAP GPIO hardware version 0.1
    [    0.192788] No ATAGs?
    [    0.192805] hw-breakpoint: debug architecture 0x4 unsupported.
    [    0.207489] edma 49000000.edma: TI EDMA DMA engine driver
    [    0.210685] usbcore: registered new interface driver usbfs
    [    0.210751] usbcore: registered new interface driver hub
    [    0.210906] usbcore: registered new device driver usb
    [    0.211184] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
    [    0.211335] pps_core: LinuxPPS API ver. 1 registered
    [    0.211344] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.211366] PTP clock support registered
    [    0.212032] Bluetooth: Core ver 2.22
    [    0.212095] NET: Registered protocol family 31
    [    0.212104] Bluetooth: HCI device and connection manager initialized
    [    0.212125] Bluetooth: HCI socket layer initialized
    [    0.212139] Bluetooth: L2CAP socket layer initialized
    [    0.212182] Bluetooth: SCO socket layer initialized
    [    0.213256] clocksource: Switched to clocksource timer1
    [    0.213449] VFS: Disk quotas dquot_6.6.0
    [    0.213516] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    [    0.222570] NET: Registered protocol family 2
    [    0.223565] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
    [    0.223633] TCP bind hash table entries: 4096 (order: 4, 98304 bytes)
    [    0.223789] TCP: Hash tables configured (established 4096 bind 4096)
    [    0.223910] UDP hash table entries: 256 (order: 2, 16384 bytes)
    [    0.223945] UDP-Lite hash table entries: 256 (order: 2, 16384 bytes)
    [    0.224154] NET: Registered protocol family 1
    [    0.224652] RPC: Registered named UNIX socket transport module.
    [    0.224666] RPC: Registered udp transport module.
    [    0.224673] RPC: Registered tcp transport module.
    [    0.224680] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.225934] hw perfevents: no interrupt-affinity property for /pmu, guessing.
    [    0.226055] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
    [    0.227596] workingset: timestamp_bits=14 max_order=17 bucket_order=3
    [    0.235949] NFS: Registering the id_resolver key type
    [    0.236004] Key type id_resolver registered
    [    0.236013] Key type id_legacy registered
    [    0.236062] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
    [    0.242801] NET: Registered protocol family 38
    [    0.243054] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
    [    0.243069] io scheduler noop registered (default)
    [    0.243077] io scheduler deadline registered
    [    0.243487] io scheduler cfq registered
    [    0.243500] io scheduler mq-deadline registered
    [    0.243508] io scheduler kyber registered
    [    0.244479] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
    [    0.247562] omap_uart 44e09000.serial: no wakeirq for uart0
    [    0.247795] 44e09000.serial: ttyO0 at MMIO 0x44e09000 (irq = 30, base_baud = 3000000) is a OMAP UART0
    [    0.884012] console [ttyO0] enabled
    [    0.888265] omap_uart 481a6000.serial: no wakeirq for uart3
    [    0.894345] 481a6000.serial: ttyO3 at MMIO 0x481a6000 (irq = 31, base_baud = 3000000) is a OMAP UART3
    [    0.905325] omap_rng 48310000.rng: Random Number Generator ver. 20
    [    0.921880] brd: module loaded
    [    0.933121] loop: module loaded
    [    0.937352] mtdoops: mtd device (mtddev=name/number) must be supplied
    [    0.945003] libphy: Fixed MDIO Bus: probed
    [    0.949959] usbcore: registered new interface driver cdc_wdm
    [    0.956231] udc-core: couldn't find an available UDC - added [g_ether] to list of pending drivers
    [    0.966100] omap_rtc 44e3e000.rtc: already running
    [    0.972025] omap_rtc 44e3e000.rtc: registered as rtc0
    [    0.977660] i2c /dev entries driver
    [    0.981389] IR NEC protocol handler initialized
    [    0.986118] IR RC5(x/sz) protocol handler initialized
    [    0.991375] IR RC6 protocol handler initialized
    [    0.996088] IR JVC protocol handler initialized
    [    1.000801] IR Sony protocol handler initialized
    [    1.005628] IR SANYO protocol handler initialized
    [    1.010522] IR Sharp protocol handler initialized
    [    1.015443] IR MCE Keyboard/mouse protocol handler initialized
    [    1.021511] IR XMP protocol handler initialized
    [    1.027246] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
    [    1.034737] Bluetooth: HCI UART driver ver 2.3
    [    1.039368] Bluetooth: HCI UART protocol H4 registered
    [    1.044847] Bluetooth: HCI UART protocol LL registered
    [    1.051014] omap_hsmmc 48060000.mmc: Got CD GPIO
    [    1.231600] mmc1: new high speed MMC card at address 0001
    [    1.237790] mmcblk1: mmc1:0001 MMC04G 3.66 GiB
    [    1.242678] mmcblk1boot0: mmc1:0001 MMC04G partition 1 1.00 MiB
    [    1.248997] mmcblk1boot1: mmc1:0001 MMC04G partition 2 1.00 MiB
    [    1.255338] mmcblk1rpmb: mmc1:0001 MMC04G partition 3 128 KiB
    [    1.269268]  mmcblk1: p1 p2 p3 p4 < p5 p6 p7 p8 >
    [    1.323903] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
    [    1.330243] omap-aes 53500000.aes: will run requests pump with realtime priority
    [    1.334840] omap_hsmmc 47810000.mmc: card claims to support voltages below defined range
    [    1.346848] mmc2: new high speed SDIO card at address 0001
    [    1.353594] wl18xx_driver wl18xx.0.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2
    [    1.353670] wl18xx_driver wl18xx.0.auto: Direct firmware load for ti-connectivity/wl18xx-conf-sm2.bin failed with error -2
    [    1.353679] wlcore: ERROR could not get configuration binary ti-connectivity/wl18xx-conf-sm2.bin: -2
    [    1.353681] wlcore: WARNING falling back to default config
    [    1.399858] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
    [    1.408088] usbcore: registered new interface driver usbhid
    [    1.414192] usbhid: USB HID core driver
    [    1.418570] Netfilter messages via NETLINK v0.30.
    [    1.423577] nfnl_acct: registering with nfnetlink.
    [    1.428862] nf_conntrack version 0.5.0 (8192 buckets, 32768 max)
    [    1.435879] ip_tables: (C) 2000-2006 Netfilter Core Team
    [    1.441647] Initializing XFRM netlink socket
    [    1.446788] NET: Registered protocol family 10
    [    1.452685] Segment Routing with IPv6
    [    1.456843] ip6_tables: (C) 2000-2006 Netfilter Core Team
    [    1.462467] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
    [    1.469440] NET: Registered protocol family 17
    [    1.474232] NET: Registered protocol family 15
    [    1.478931] Bridge firewalling registered
    [    1.483166] 8021q: 802.1Q VLAN Support v1.8
    [    1.487659] Key type dns_resolver registered
    [    1.492281] omap_voltage_late_init: Voltage driver support not added
    [    1.499113] ThumbEE CPU extension supported.
    [    1.541395] tps65217 0-0024: TPS65217 ID 0x6 version 1.2
    [    1.550753] at24 0-0050: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
    [    1.558119] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
    [    1.564575] omap_rtc 44e3e000.rtc: setting system clock to 2018-09-06 16:44:14 UTC (1536252254)
    [    1.576450] EXT4-fs (mmcblk1p7): couldn't mount as ext3 due to feature incompatibilities
    [    1.594218] EXT4-fs (mmcblk1p7): mounted filesystem without journal. Opts: (null)
    [    1.602096] VFS: Mounted root (ext4 filesystem) readonly on device 179:7.
    [    1.610360] devtmpfs: mounted
    [    1.615677] Freeing unused kernel memory: 1024K
    [    1.774400] wlcore: wl18xx HW: 183x or 180x, PG 2.2 (ROM 0x11)
    [    1.790381] wlcore: loaded
    [    2.036593] systemd[1]: systemd 237 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)
    [    2.059718] systemd[1]: Detected architecture arm.

  • the message should be informational and not a critical warning. I will submit ticket to change the warning level.